-- Logs begin at Fri 2024-05-03 21:45:27 CEST, end at Fri 2024-05-03 22:58:46 CEST. -- May 03 22:57:00 volumio sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 03 22:57:00 volumio sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:57:00 volumio autossh[1294]: received signal to exit (15) May 03 22:57:00 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... May 03 22:57:00 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 03 22:57:00 volumio systemd[1]: sshtunnel.service: Succeeded. May 03 22:57:00 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. May 03 22:57:00 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 03 22:57:00 volumio sudo[3567]: pam_unix(sudo:session): session closed for user root May 03 22:57:00 volumio autossh[3570]: port set to 0, monitoring disabled May 03 22:57:00 volumio autossh[3570]: starting ssh (count 1) May 03 22:57:00 volumio autossh[3570]: ssh child pid is 3573 May 03 22:57:00 volumio volumio[3139]: info: Remote SSH Started May 03 22:57:00 volumio volumiossh-tunnel[3569]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. May 03 22:57:00 volumio volumio[3139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 May 03 22:57:00 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:00 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:01 volumio sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 03 22:57:01 volumio sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:57:01 volumio systemd[1]: Started Volumio Bluetooth Module. May 03 22:57:01 volumio sudo[3575]: pam_unix(sudo:session): session closed for user root May 03 22:57:01 volumio sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 03 22:57:01 volumio sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:57:01 volumio volumio[3139]: info: Volumio BT Module successfully started May 03 22:57:01 volumio volumiobt[3577]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 03 22:57:01 volumio sudo[3578]: pam_unix(sudo:session): session closed for user root May 03 22:57:01 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:57:01 volumio volumio[3139]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 03 22:57:01 volumio volumio[3139]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 03 22:57:01 volumio sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 03 22:57:01 volumio sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:57:01 volumio systemd[1]: Started Volumio Tidal Connect Service. May 03 22:57:01 volumio sudo[3590]: pam_unix(sudo:session): session closed for user root May 03 22:57:01 volumio volumio[3139]: info: Executing endpoint tc_getconfig May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 03 22:57:01 volumio vtcs[3592]: STARTING TidalConnect services, version: 1.4.0.34 May 03 22:57:01 volumio vtcs[3592]: STARTED TidalConnect services. May 03 22:57:01 volumio volumio[3139]: info: Executing endpoint tc_connect May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 03 22:57:01 volumio volumio[3139]: info: Connecting to TidalConnect May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:57:01 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:57:01 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:57:01 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:57:01 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:57:01 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:01 volumio volumio[3139]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:57:01 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:57:01 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:01 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:57:01 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:57:01 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:57:01 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:01 volumio volumio[3139]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect May 03 22:57:02 volumio sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 03 22:57:02 volumio sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:57:02 volumio volumiobt[3577]: pulseaudio: no process found May 03 22:57:02 volumio sudo[3611]: pam_unix(sudo:session): session closed for user root May 03 22:57:02 volumio pulseaudio[3615]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 03 22:57:02 volumio pulseaudio[3615]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 03 22:57:02 volumio volumiobt[3577]: Applying permissions May 03 22:57:02 volumio volumiobt[3577]: Setting BT discoverable and pairable May 03 22:57:02 volumio bluetoothd[637]: Endpoint registered: sender=:1.83 path=/MediaEndpoint/A2DPSource May 03 22:57:02 volumio bluetoothd[637]: Endpoint registered: sender=:1.83 path=/MediaEndpoint/A2DPSink May 03 22:57:02 volumio pulseaudio[3615]: 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 May 03 22:57:02 volumio volumiobt[3577]: Agent registered May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# -e power on May 03 22:57:02 volumio volumiobt[3577]: Invalid command in menu main: -e May 03 22:57:02 volumio volumiobt[3577]: May 03 22:57:02 volumio volumiobt[3577]: Use "help" for a list of available commands in a menu. May 03 22:57:02 volumio volumiobt[3577]: Use "menu " if you want to enter any submenu. May 03 22:57:02 volumio volumiobt[3577]: Use "back" if you want to return to menu main. May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# agent on May 03 22:57:02 volumio volumiobt[3577]: Agent is already registered May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# discoverable on May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# pairable on May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# agent NoInputNoOutput May 03 22:57:02 volumio volumiobt[3577]: Agent is already registered May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# default-agent May 03 22:57:02 volumio volumiobt[3577]: [bluetooth]# quit May 03 22:57:02 volumio volumiobt[3577]: [59B blob data] May 03 22:57:03 volumio volumio[3139]: info: MRS: Getting audio outputs on start May 03 22:57:03 volumio volumio[3139]: info: MRS: Requesting all other devices output May 03 22:57:04 volumio volumio[3139]: info: TidalConnect service started! May 03 22:57:04 volumio volumio[3139]: info: BOOT COMPLETED May 03 22:57:04 volumio volumio[3139]: [Metrics] CommandRouter: 34s 375.68ms May 03 22:57:04 volumio volumio[3139]: info: CoreCommandRouter::volumiosetStartupVolume May 03 22:57:04 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:57:04 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:57:04 volumio volumio[3139]: info: CoreCommandRouter::Close All Modals sent May 03 22:57:04 volumio volumio[3139]: info: CoreCommandRouter::Close All Modals sent May 03 22:57:04 volumio volumio[3139]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 03 22:57:04 volumio volumio[3139]: info: FusionDsp - ---- read samplerate from file: 44100 May 03 22:57:04 volumio volumio[3139]: info: camilladsp stopping service pid 3385... May 03 22:57:04 volumio volumio[3139]: info: camilladsp service terminated, instance 1 May 03 22:57:04 volumio volumio[3139]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 03 22:57:04 volumio volumio[3139]: info: FusionDsp - crossfeed chumoy May 03 22:57:04 volumio volumio[3139]: info: FusionDsp - Effects disabled May 03 22:57:04 volumio volumio[3139]: info: camilladsp service started and running in background, instance 1 May 03 22:57:04 volumio volumio[3139]: info: camilladsp spawned new process with pid 3626, instance 1, run: true May 03 22:57:05 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 03 22:57:05 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 22:57:05 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 03 22:57:06 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 03 22:57:07 volumio volumio[3139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 May 03 22:57:07 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:07 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:08 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 22:57:08 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:57:08 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 03 22:57:08 volumio volumio-remote-updater[529]: No test mode May 03 22:57:08 volumio volumio-remote-updater[529]: No alpha test mode May 03 22:57:08 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 03 22:57:08 volumio volumio[3139]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 03 22:57:08 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 03 22:57:10 volumio volumio[3139]: info: camilladsp spawned new process with pid 3637, instance 1, run: true May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::volumioGetBrowseSources May 03 22:57:11 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 22:57:20 volumio volumio[3139]: info: CoreCommandRouter::volumioPlay May 03 22:57:20 volumio volumio[3139]: info: CoreStateMachine::play index undefined May 03 22:57:20 volumio volumio[3139]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 22:57:20 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:20 volumio volumio[3139]: info: CoreStateMachine::startPlaybackTimer May 03 22:57:20 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:20 volumio volumio[3139]: info: [1714769840156] ControllerQobuz::clearAddPlayTrack May 03 22:57:20 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand stop May 03 22:57:20 volumio volumio[3139]: info: sendMpdCommand stop took 2 milliseconds May 03 22:57:20 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand clear May 03 22:57:20 volumio volumio[3139]: info: May 03 22:57:20 volumio volumio[3139]: ---------------------------- MPD announces system playlist update May 03 22:57:20 volumio volumio[3139]: info: Ignoring MPD Status Update May 03 22:57:20 volumio volumio[3139]: info: sendMpdCommand clear took 3 milliseconds May 03 22:57:20 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/252462759" May 03 22:57:20 volumio volumio[3139]: error: updateQueue error: null May 03 22:57:20 volumio volumio[3139]: info: ------------------------------ 5ms May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Handling url /?data=qobuz://song/252462759 May 03 22:57:20 volumio volumio[3139]: info: Executing endpoint getStreamUrlqobuz May 03 22:57:20 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 03 22:57:20 volumio volumio[3139]: info: getStreamUrl took 309 milliseconds May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=776199&eid=252462759&fmt=7&profile=raw&app_id=539451548&cid=1322134&etsp=1714773440&hmac=S_rciYbyzvmza6nu5RNnLWpLvC8 May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Response: 200, length: 116935549 May 03 22:57:20 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/252462759" May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Client dropped request, destroying May 03 22:57:20 volumio volumio[3139]: info: May 03 22:57:20 volumio volumio[3139]: ---------------------------- MPD announces system playlist update May 03 22:57:20 volumio volumio[3139]: info: Ignoring MPD Status Update May 03 22:57:20 volumio volumio[3139]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/252462759" took 2 milliseconds May 03 22:57:20 volumio volumio[3139]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 22:57:20 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand play May 03 22:57:20 volumio volumio[3139]: info: ------------------------------ 6ms May 03 22:57:20 volumio volumio[3139]: info: sendMpdCommand play took 4 milliseconds May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Handling url /?data=qobuz://song/252462759 May 03 22:57:20 volumio volumio[3139]: info: Executing endpoint getStreamUrlqobuz May 03 22:57:20 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 03 22:57:20 volumio volumio[3139]: info: getStreamUrl took 276 milliseconds May 03 22:57:20 volumio volumio[3139]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=776199&eid=252462759&fmt=7&profile=raw&app_id=539451548&cid=1322134&etsp=1714773440&hmac=S_rciYbyzvmza6nu5RNnLWpLvC8 May 03 22:57:21 volumio volumio[3139]: STREAMING PROXY: Response: 200, length: 116935549 May 03 22:57:21 volumio volumio[3139]: info: May 03 22:57:21 volumio volumio[3139]: ---------------------------- MPD announces state update: player May 03 22:57:21 volumio volumio[3139]: info: ControllerMpd::getState May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand status May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - ---- read samplerate from file: 44100 May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - crossfeed chumoy May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - Effects disabled May 03 22:57:21 volumio volumio[3139]: info: May 03 22:57:21 volumio volumio[3139]: ---------------------------- MPD announces state update: player May 03 22:57:21 volumio volumio[3139]: info: sendMpdCommand status took 47 milliseconds May 03 22:57:21 volumio volumio[3139]: info: ControllerMpd::getState May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand status May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::parseState May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 22:57:21 volumio volumio[3139]: info: sendMpdCommand status took 6 milliseconds May 03 22:57:21 volumio volumio[3139]: info: sendMpdCommand playlistinfo took 4 milliseconds May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::parseState May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::parseTrackInfo May 03 22:57:21 volumio volumio[3139]: info: ControllerMpd::pushState May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:57:21 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:21 volumio volumio[3139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":563,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252462759","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/252462759","trackType":"qobuz"} May 03 22:57:21 volumio volumio[3139]: verbose: CURRENT POSITION 0 May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::syncState stateService play May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::syncState currentStatus stop May 03 22:57:21 volumio volumio[3139]: info: ------------------------------ 65ms May 03 22:57:21 volumio volumio[3139]: info: sendMpdCommand playlistinfo took 9 milliseconds May 03 22:57:21 volumio volumio[3139]: verbose: ControllerMpd::parseTrackInfo May 03 22:57:21 volumio volumio[3139]: info: ControllerMpd::pushState May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:57:21 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:57:21 volumio volumio[3139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":563,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252462759","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/252462759","trackType":"qobuz"} May 03 22:57:21 volumio volumio[3139]: verbose: CURRENT POSITION 0 May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::syncState stateService play May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::syncState currentStatus play May 03 22:57:21 volumio volumio[3139]: info: Received an update from plugin. extracting info from payload May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:57:21 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:57:21 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:57:21 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:57:21 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:57:21 volumio volumio[3139]: info: ------------------------------ 108ms May 03 22:57:21 volumio volumio[3139]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:21 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:21 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:21 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:22 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:22 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:22 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:22 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:23 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:23 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:23 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:23 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:24 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:24 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:24 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:24 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:25 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:25 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:25 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:25 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:26 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:26 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:26 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:26 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:27 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:27 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:27 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:27 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:28 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:28 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:28 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:28 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:29 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:29 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:29 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:29 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:30 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:30 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:30 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:30 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:31 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:31 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:31 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:31 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:32 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:32 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:32 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:32 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:33 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:33 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:33 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:33 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:33 volumio volumio[3139]: info: camilladsp spawned new process with pid 3656, instance 1, run: true May 03 22:57:34 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:34 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:34 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:34 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:35 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:35 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:35 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:35 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:36 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:36 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:36 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:36 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:37 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:37 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:37 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:37 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:38 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:38 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:38 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:38 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:39 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:39 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:39 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:39 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:40 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:40 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:40 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:40 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:41 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:41 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:41 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:41 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:42 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:42 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:42 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:42 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:43 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:43 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:43 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:43 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:44 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:44 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:44 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:44 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:45 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:45 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:45 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:45 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:46 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:46 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:46 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:46 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:47 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:47 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:47 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:47 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:48 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:48 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:48 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:48 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:49 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:49 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:49 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:49 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:50 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:50 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:50 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:50 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:51 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:51 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:51 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:51 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:52 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:52 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:52 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:52 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:53 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:53 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:53 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:53 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:54 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:54 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:54 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:54 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:55 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:55 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:55 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:55 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:56 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:56 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:56 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:56 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:57 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:57 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:57 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:57 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:58 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:58 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:58 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:58 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:59 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:57:59 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:57:59 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:57:59 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:57:59 volumio volumio[3139]: STREAMING PROXY: Client dropped request, destroying May 03 22:58:00 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:00 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:00 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:00 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:01 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:01 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:01 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:01 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:02 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:02 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:02 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:02 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:03 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:03 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:03 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:03 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:04 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:04 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:04 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:04 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:05 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:05 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:05 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:05 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:06 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:06 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:06 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:06 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:07 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:07 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:07 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:07 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:08 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:08 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:08 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:08 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:09 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:09 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:09 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:09 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:10 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:10 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:10 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:10 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:11 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:11 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:11 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:11 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:12 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:12 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:12 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:12 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:13 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:13 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:13 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:13 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:14 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:14 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:14 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:14 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:15 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:15 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:15 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:15 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:16 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:16 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:16 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:16 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:17 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:17 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:17 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:17 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:18 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:18 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:18 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:18 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:19 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:19 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:19 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:19 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:20 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:20 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:20 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:20 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:21 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:21 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:21 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:21 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:22 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:22 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 22:58:22 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 22:58:22 volumio volumio[3139]: info: Getting Alsa Cards List without I2S DAC May 03 22:58:22 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 03 22:58:22 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:22 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:23 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:23 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:23 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:23 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:24 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:24 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:24 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:24 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:25 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:25 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:25 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:25 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:26 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:26 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:26 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:26 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:27 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:27 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:27 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:27 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:28 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:28 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:28 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:28 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:29 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:29 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:29 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:29 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:30 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:30 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:30 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:30 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:31 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:31 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:31 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:31 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:32 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:32 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:32 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:32 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:33 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:33 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:33 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:33 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:34 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:34 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:34 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:34 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:35 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:35 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:35 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:35 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:36 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:36 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:36 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:36 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:37 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:37 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:37 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:37 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:38 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:38 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:38 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:38 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:39 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:39 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:39 volumio volumio[3139]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 03 22:58:39 volumio volumio[3139]: info: Preparing to save Alsa Options, stopping services first May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::volumioPause May 03 22:58:39 volumio volumio[3139]: info: CoreStateMachine::pause May 03 22:58:39 volumio volumio[3139]: info: CoreStateMachine::stPlaybackTimer May 03 22:58:39 volumio volumio[3139]: info: CoreStateMachine::servicePause May 03 22:58:39 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::servicePause May 03 22:58:39 volumio volumio[3139]: info: [1714769919658] ControllerQobuz::pause May 03 22:58:39 volumio volumio[3139]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 22:58:39 volumio volumio[3139]: info: ControllerMpd::pause May 03 22:58:39 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand pause May 03 22:58:39 volumio volumio[3139]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"chipdip-master-dac","label":"ChipDip DAC"}} May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:39 volumio volumio[3139]: info: Enabling I2S DAC: ChipDip DAC May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:39 volumio sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 03 22:58:39 volumio sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:39 volumio sudo[3756]: pam_unix(sudo:session): session closed for user root May 03 22:58:39 volumio volumio[3139]: info: No Overlays Loaded May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 03 22:58:39 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:39 volumio sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay chipdip-dac May 03 22:58:39 volumio sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/sr0-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/sr1-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/sr2-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/res0-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/res1-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/mute-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/sdwn-gpios May 03 22:58:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 03 22:58:39 volumio sudo[3759]: pam_unix(sudo:session): session closed for user root May 03 22:58:39 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:39 volumio volumio[3139]: warn: Unable to locate the device HDMI Out a reboot may be required. May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: Setting default mixerSoftMaster for Softvolume device May 03 22:58:40 volumio vtcs[3592]: [2024-05-03 22:58:40.083] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 03 22:58:40 volumio vtcs[3592]: [2024-05-03 22:58:40.090] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 03 22:58:40 volumio vtcs[3592]: [2024-05-03 22:58:40.090] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 03 22:58:40 volumio sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:40 volumio sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:40 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 03 22:58:40 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 03 22:58:40 volumio systemd[1]: vtcs.service: Succeeded. May 03 22:58:40 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 03 22:58:40 volumio sudo[3777]: pam_unix(sudo:session): session closed for user root May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 03 22:58:40 volumio volumio[3139]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 03 22:58:40 volumio volumio[3139]: info: Disabling external Volume Control May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 22:58:40 volumio volumio[3139]: info: Enable softmixer device for audio device undefined May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 03 22:58:40 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:40 volumio volumio[3139]: info: May 03 22:58:40 volumio volumio[3139]: ---------------------------- MPD announces state update: player May 03 22:58:40 volumio sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:40 volumio volumio[3139]: info: sendMpdCommand pause took 762 milliseconds May 03 22:58:40 volumio sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:40 volumio volumio[3139]: info: ControllerMpd::getState May 03 22:58:40 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand status May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:40 volumio sudo[3791]: pam_unix(sudo:session): session closed for user root May 03 22:58:40 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:40 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:40 volumio volumio[3139]: info: I2S Param [object Object] successfully enabled May 03 22:58:40 volumio volumio[3139]: info: sendMpdCommand status took 96 milliseconds May 03 22:58:40 volumio volumio[3139]: verbose: ControllerMpd::parseState May 03 22:58:40 volumio volumio[3139]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 22:58:40 volumio volumio[3139]: info: Software Volume ALSA configuration written May 03 22:58:40 volumio volumio[3139]: info: Preparing to generate the ALSA configuration file May 03 22:58:40 volumio volumio[3139]: info: sendMpdCommand playlistinfo took 59 milliseconds May 03 22:58:40 volumio volumio[3139]: verbose: ControllerMpd::parseTrackInfo May 03 22:58:40 volumio volumio[3139]: info: ControllerMpd::pushState May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:58:40 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:58:40 volumio volumio[3139]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":80228,"duration":563,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1664 Kbps","isStreaming":false,"title":"252462759","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/252462759","trackType":"qobuz"} May 03 22:58:40 volumio volumio[3139]: verbose: CURRENT POSITION 0 May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::syncState stateService pause May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::syncState currentStatus pause May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:40 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:40 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::stPlaybackTimer May 03 22:58:40 volumio volumio[3139]: info: ------------------------------ 211ms May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 03 22:58:40 volumio volumio[3139]: info: Getting Alsa Cards List without I2S DAC May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 03 22:58:40 volumio volumio[3139]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 03 22:58:40 volumio volumio[3139]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 03 22:58:40 volumio volumio[3139]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 03 22:58:40 volumio volumio[3139]: info: Reading ALSA contributions from plugins. May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::volumioGetState May 03 22:58:40 volumio volumio[3139]: info: CoreCommandRouter::volumioGetQueue May 03 22:58:40 volumio volumio[3139]: info: CoreStateMachine::getQueue May 03 22:58:40 volumio volumio[3139]: info: CorePlayQueue::getQueue May 03 22:58:41 volumio volumio[3139]: info: camilladsp spawned new process with pid 3802, instance 1, run: true May 03 22:58:41 volumio volumio[3139]: info: Asound.conf file written May 03 22:58:41 volumio sudo[3810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 03 22:58:41 volumio sudo[3810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio sudo[3810]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:41 volumio volumio[3139]: info: Output device has changed, restarting MPD May 03 22:58:41 volumio sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 22:58:41 volumio sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio volumio[3139]: info: Output device has changed, restarting Shairport Sync May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:41 volumio sudo[3818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 22:58:41 volumio sudo[3818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio systemd[1]: Stopping Music Player Daemon... May 03 22:58:41 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:41 volumio volumio[3139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 03 22:58:41 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:41 volumio volumio[3139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio systemd[1]: mpd.service: Succeeded. May 03 22:58:41 volumio systemd[1]: Stopped Music Player Daemon. May 03 22:58:41 volumio volumio[3139]: info: Output device has changed, restarting MPD May 03 22:58:41 volumio systemd[1]: Starting Music Player Daemon... May 03 22:58:41 volumio sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 22:58:41 volumio sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio volumio[3139]: info: Output device has changed, restarting Shairport Sync May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:41 volumio sudo[3829]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:41 volumio sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio sudo[3827]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio sudo[3833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 22:58:41 volumio sudo[3833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:41 volumio volumio[3139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 03 22:58:41 volumio volumio[3139]: xcb_connection_has_error() returned true May 03 22:58:41 volumio volumio[3139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 03 22:58:41 volumio systemd[1]: mpd.service: Succeeded. May 03 22:58:41 volumio systemd[1]: Stopped Music Player Daemon. May 03 22:58:41 volumio systemd[1]: Starting Music Player Daemon... May 03 22:58:41 volumio sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:41 volumio sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio sudo[3845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:41 volumio sudo[3845]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:41 volumio sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 22:58:41 volumio sudo[3843]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:41 volumio sudo[3845]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 22:58:41 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:41 volumio sudo[3848]: pam_unix(sudo:session): session closed for user root May 03 22:58:41 volumio volumio[3139]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 03 22:58:41 volumio volumio[3139]: info: MPD Permissions set May 03 22:58:41 volumio volumio[3139]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 03 22:58:41 volumio volumio[3139]: info: MPD Permissions set May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:41 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 22:58:42 volumio volumio[3139]: info: Starting Shairport Sync May 03 22:58:42 volumio volumio[3139]: info: Starting Shairport Sync May 03 22:58:42 volumio sudo[3863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 22:58:42 volumio sudo[3863]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 03 22:58:42 volumio systemd[1]: shairport-sync.service: Succeeded. May 03 22:58:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 03 22:58:42 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:42 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 22:58:42 volumio sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 22:58:42 volumio sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 03 22:58:42 volumio sudo[3863]: pam_unix(sudo:session): session closed for user root May 03 22:58:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 03 22:58:42 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 03 22:58:42 volumio systemd[1]: shairport-sync.service: Succeeded. May 03 22:58:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 03 22:58:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 03 22:58:42 volumio sudo[3866]: pam_unix(sudo:session): session closed for user root May 03 22:58:42 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 22:58:42 volumio volumio[3139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 22:58:42 volumio volumio[3139]: info: Shairport-Sync Started May 03 22:58:42 volumio volumio[3139]: info: Shairport-Sync Started May 03 22:58:43 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:43 volumio mpd[3839]: May 03 22:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 03 22:58:43 volumio systemd[1]: Started Music Player Daemon. May 03 22:58:43 volumio sudo[3833]: pam_unix(sudo:session): session closed for user root May 03 22:58:43 volumio sudo[3818]: pam_unix(sudo:session): session closed for user root May 03 22:58:43 volumio volumio[3139]: error: MPD error: The expression evaluated to a falsy value: May 03 22:58:43 volumio volumio[3139]: assert.ok(self.idling) May 03 22:58:43 volumio volumio[3139]: error: The expression evaluated to a falsy value: May 03 22:58:43 volumio volumio[3139]: assert.ok(self.idling) May 03 22:58:43 volumio volumio[3139]: error: updateQueue error: null May 03 22:58:43 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:43 volumio volumio[3139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:43 volumio volumio[3139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:43 volumio sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 03 22:58:43 volumio sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:43 volumio systemd[1]: Started Volumio Tidal Connect Service. May 03 22:58:43 volumio sudo[3883]: pam_unix(sudo:session): session closed for user root May 03 22:58:43 volumio volumio[3139]: info: Executing endpoint tc_getconfig May 03 22:58:43 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 03 22:58:43 volumio vtcs[3885]: STARTING TidalConnect services, version: 1.4.0.34 May 03 22:58:44 volumio vtcs[3885]: STARTED TidalConnect services. May 03 22:58:44 volumio volumio[3139]: info: Executing endpoint tc_connect May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 03 22:58:44 volumio volumio[3139]: info: Connecting to TidalConnect May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:44 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:58:44 volumio volumio[3139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::servicePushState May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:44 volumio volumio[3139]: info: CorePlayQueue::getTrack 0 May 03 22:58:44 volumio volumio[3139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::getcurrentVolume May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioRetrievevolume May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::updateTrackBlock May 03 22:58:44 volumio volumio[3139]: info: CorePlayQueue::getTrackBlock May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioRetrievevolume May 03 22:58:44 volumio volumio[3139]: info: CoreStateMachine::pushState May 03 22:58:44 volumio volumio[3139]: info: CoreCommandRouter::volumioPushState May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output update for this device May 03 22:58:44 volumio volumio[3139]: info: MRS: Pushing multiroomSync output May 03 22:58:44 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:44 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:44 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:44 volumio volumio[3139]: info: TidalConnect service stoped! May 03 22:58:44 volumio volumio[3139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:44 volumio volumio[3139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:45 volumio sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 03 22:58:45 volumio sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:45 volumio volumio[3139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:45 volumio volumio[3139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 03 22:58:45 volumio sudo[3929]: pam_unix(sudo:session): session closed for user root May 03 22:58:45 volumio sudo[3937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 03 22:58:45 volumio sudo[3937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 22:58:45 volumio sudo[3937]: pam_unix(sudo:session): session closed for user root May 03 22:58:45 volumio volumio[3139]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 03 22:58:45 volumio volumio[3139]: info: PLUGIN onReboot : networkfs May 03 22:58:45 volumio volumio[3139]: info: PLUGIN onReboot : audiophonicsonoff May 03 22:58:45 volumio volumio[3139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 22:58:45 volumio volumio[3139]: TypeError: Cannot read property 'writeSync' of undefined May 03 22:58:45 volumio volumio[3139]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 03 22:58:45 volumio volumio[3139]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 03 22:58:45 volumio volumio[3139]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 03 22:58:45 volumio volumio[3139]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 03 22:58:45 volumio volumio[3139]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 03 22:58:45 volumio volumio[3139]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 03 22:58:45 volumio volumio[3139]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) May 03 22:58:45 volumio volumio[3139]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) May 03 22:58:45 volumio volumio[3139]: at Socket.emit (events.js:315:20) May 03 22:58:45 volumio volumio[3139]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 03 22:58:45 volumio volumio[3139]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 03 22:58:45 volumio volumio[3139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 22:58:46 volumio sudo[3949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 22:57 May 03 22:58:46 volumio sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"