-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Mon 2024-07-15 12:27:48 IST. -- Jul 15 12:26:02 volumio ntpd[796]: 192.46.215.60 local addr 192.168.68.61 -> Jul 15 12:26:02 volumio volumio[897]: info: Enabling plugin fusiondsp Jul 15 12:26:02 volumio volumio[897]: info: Loading plugin "fusiondsp"... Jul 15 12:26:03 volumio volumio[897]: info: Preparing to generate the ALSA configuration file Jul 15 12:26:03 volumio volumio[897]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 15 12:26:03 volumio volumio[897]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 15 12:26:03 volumio volumio[897]: info: Reading ALSA contributions from plugins. Jul 15 12:26:03 volumio volumio[897]: info: Asound.conf file written Jul 15 12:26:03 volumio sudo[4212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 15 12:26:03 volumio sudo[4212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio sudo[4212]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:03 volumio volumio[897]: No state is present for card b1 Jul 15 12:26:03 volumio volumio[897]: /usr/share/alsa/init/bcm2835:14: control element not found Jul 15 12:26:03 volumio volumio[897]: No state is present for card b1 Jul 15 12:26:03 volumio volumio[897]: No state is present for card Headphones Jul 15 12:26:03 volumio volumio[897]: /usr/share/alsa/init/bcm2835:20: control element not found Jul 15 12:26:03 volumio volumio[897]: No state is present for card Headphones Jul 15 12:26:03 volumio volumio[897]: No state is present for card IQaudIODAC Jul 15 12:26:03 volumio volumio[897]: Found hardware: "IQaudIODAC" "" "" "" "" Jul 15 12:26:03 volumio volumio[897]: Hardware is initialized using a generic method Jul 15 12:26:03 volumio volumio[897]: No state is present for card IQaudIODAC Jul 15 12:26:03 volumio volumio[897]: info: Output device has changed, restarting MPD Jul 15 12:26:03 volumio sudo[4230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 12:26:03 volumio sudo[4230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio sudo[4230]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio volumio[897]: info: Output device has changed, restarting Shairport Sync Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:26:03 volumio sudo[4233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 12:26:03 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio systemd[1]: Stopping Music Player Daemon... Jul 15 12:26:03 volumio systemd[1]: mpd.service: Succeeded. Jul 15 12:26:03 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:03 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 12:26:03 volumio volumio[897]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 12:26:03 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:03 volumio volumio[897]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio vtcs[2340]: [2024-07-15 12:26:03.451] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jul 15 12:26:03 volumio vtcs[2340]: [2024-07-15 12:26:03.452] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jul 15 12:26:03 volumio vtcs[2340]: [2024-07-15 12:26:03.452] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jul 15 12:26:03 volumio systemd[1]: Starting Music Player Daemon... Jul 15 12:26:03 volumio volumio[897]: info: PLUGIN START: fusiondsp Jul 15 12:26:03 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:26:03 volumio volumio[897]: info: Loading i18n strings for locale en Jul 15 12:26:03 volumio sudo[4243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 15 12:26:03 volumio sudo[4243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: FusionDsp - mixtype--------------------- Hardware Jul 15 12:26:03 volumio volumio[897]: info: Preparing to generate the ALSA configuration file Jul 15 12:26:03 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jul 15 12:26:03 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 15 12:26:03 volumio systemd[1]: vtcs.service: Succeeded. Jul 15 12:26:03 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jul 15 12:26:03 volumio volumio[897]: info: Done. Jul 15 12:26:03 volumio sudo[4243]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio volumio[897]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 15 12:26:03 volumio volumio[897]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 15 12:26:03 volumio volumio[897]: info: Reading ALSA contributions from plugins. Jul 15 12:26:03 volumio sudo[4238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 12:26:03 volumio sudo[4238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio sudo[4238]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio sudo[4247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 15 12:26:03 volumio sudo[4247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:03 volumio volumio[897]: info: MPD Permissions set Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:03 volumio volumio[897]: info: Starting Shairport Sync Jul 15 12:26:03 volumio sudo[4247]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio sudo[4257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 12:26:03 volumio sudo[4257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 12:26:03 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 15 12:26:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 12:26:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 12:26:03 volumio sudo[4257]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 15 12:26:03 volumio volumio[897]: info: Asound.conf file unchanged, so no further update is needed Jul 15 12:26:03 volumio volumio[897]: info: Output device has changed, restarting MPD Jul 15 12:26:03 volumio sudo[4263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 12:26:03 volumio sudo[4263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio volumio[897]: info: Output device has changed, restarting Shairport Sync Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:26:03 volumio sudo[4263]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio sudo[4266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 12:26:03 volumio sudo[4266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 15 12:26:03 volumio systemd[1]: mpd.service: Succeeded. Jul 15 12:26:03 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 12:26:03 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:03 volumio volumio[897]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 12:26:03 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:03 volumio volumio[897]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 15 12:26:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:03 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:26:03 volumio systemd[1]: Starting Music Player Daemon... Jul 15 12:26:03 volumio sudo[4276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 15 12:26:03 volumio sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio sudo[4279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 15 12:26:03 volumio sudo[4279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:03 volumio sudo[4276]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:03 volumio volumio[897]: info: Shairport-Sync Started Jul 15 12:26:03 volumio volumio[897]: (node:897) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 15 12:26:03 volumio volumio[897]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 15 12:26:03 volumio volumio[897]: (node:897) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 15 12:26:03 volumio volumio[897]: (node:897) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 15 12:26:03 volumio volumio[897]: (node:897) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 15 12:26:03 volumio volumio[897]: (node:897) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 15 12:26:03 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:03 volumio sudo[4272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 12:26:03 volumio sudo[4272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:04 volumio sudo[4279]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:04 volumio volumio[897]: info: MPD Permissions set Jul 15 12:26:04 volumio sudo[4272]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:04 volumio volumio[897]: info: Starting Shairport Sync Jul 15 12:26:04 volumio sudo[4290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 12:26:04 volumio sudo[4290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 12:26:04 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 15 12:26:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 12:26:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 12:26:04 volumio sudo[4290]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:04 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 15 12:26:04 volumio volumio[897]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 15 12:26:04 volumio volumio[897]: info: Shairport-Sync Started Jul 15 12:26:04 volumio mpd[4284]: Jul 15 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 15 12:26:04 volumio systemd[1]: Started Music Player Daemon. Jul 15 12:26:04 volumio sudo[4233]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:04 volumio sudo[4266]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:04 volumio volumio[897]: error: MPD error: The expression evaluated to a falsy value: Jul 15 12:26:04 volumio volumio[897]: assert.ok(self.idling) Jul 15 12:26:04 volumio volumio[897]: error: The expression evaluated to a falsy value: Jul 15 12:26:04 volumio volumio[897]: assert.ok(self.idling) Jul 15 12:26:04 volumio volumio[897]: error: updateQueue error: null Jul 15 12:26:05 volumio volumio[897]: info: camilladsp spawned new process with pid 4306, instance 1, run: true Jul 15 12:26:05 volumio volumio[897]: info: camilladsp service started and running in background, instance 1 Jul 15 12:26:05 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 12:26:05 volumio volumio[897]: xcb_connection_has_error() returned true Jul 15 12:26:05 volumio volumio[897]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jul 15 12:26:05 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:26:05 volumio volumio[897]: info: FusionDsp loaded Jul 15 12:26:05 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 12:26:05 volumio sudo[4312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 15 12:26:05 volumio sudo[4312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:05 volumio volumio[897]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 15 12:26:05 volumio volumio[897]: info: Adding Signal Path Element [object Object] Jul 15 12:26:05 volumio volumio[897]: info: Adding fusiondspeq DSP Signal Path Element Jul 15 12:26:05 volumio volumio[897]: info: FusionDsp - ---- installed callbackRead Jul 15 12:26:05 volumio volumio[897]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 15 12:26:05 volumio volumio[897]: error: FusionDsp - WebSocket error: [object Object] Jul 15 12:26:05 volumio volumio[897]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 15 12:26:05 volumio systemd[1]: Started FusionDsp Daemon. Jul 15 12:26:05 volumio sudo[4312]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:06 volumio volumio[897]: info: TidalConnect service stoped! Jul 15 12:26:06 volumio volumio[897]: 0+0 records in Jul 15 12:26:06 volumio volumio[897]: 0+0 records out Jul 15 12:26:06 volumio volumio[897]: 0 bytes copied, 9.5943e-05 s, 0.0 kB/s Jul 15 12:26:06 volumio volumio[897]: info: camilladsp spawned new process with pid 4317, instance 1, run: true Jul 15 12:26:06 volumio volumio[897]: info: TidalConnect service stoped! Jul 15 12:26:06 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:26:06 volumio volumio[897]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 15 12:26:06 volumio volumio[897]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 15 12:26:06 volumio sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 15 12:26:06 volumio sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:06 volumio systemd[1]: Started Volumio Tidal Connect Service. Jul 15 12:26:06 volumio sudo[4333]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:06 volumio volumio[897]: info: TidalConnect service stoped! Jul 15 12:26:06 volumio volumio[897]: info: Executing endpoint tc_getconfig Jul 15 12:26:06 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 15 12:26:06 volumio vtcs[4335]: STARTING TidalConnect services, version: 1.4.0.34 Jul 15 12:26:07 volumio volumio[897]: info: TidalConnect service stoped! Jul 15 12:26:07 volumio vtcs[4335]: STARTED TidalConnect services. Jul 15 12:26:07 volumio volumio[897]: info: Executing endpoint tc_connect Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 15 12:26:07 volumio volumio[897]: info: Connecting to TidalConnect Jul 15 12:26:07 volumio volumio[897]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 15 12:26:07 volumio volumio[897]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 15 12:26:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio volumio[897]: info: No code Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 15 12:26:07 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::syncState stateService stop Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio volumio[897]: info: No code Jul 15 12:26:07 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:26:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:07 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:26:07 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:26:07 volumio sudo[4363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 15 12:26:07 volumio sudo[4363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 12:26:07 volumio sudo[4363]: pam_unix(sudo:session): session closed for user root Jul 15 12:26:09 volumio volumio[897]: info: TidalConnect service started! Jul 15 12:26:10 volumio volumio[897]: info: TidalConnect service started! Jul 15 12:26:17 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Jul 15 12:26:17 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:19 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 15 12:26:19 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 15 12:26:20 volumio ntpd[796]: 95.216.192.15 local addr 192.168.68.61 -> Jul 15 12:26:22 volumio ntpd[796]: 192.46.211.253 local addr 192.168.68.61 -> Jul 15 12:26:22 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Jul 15 12:26:22 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: Retrieving Cloud Streaming UI Jul 15 12:26:26 volumio volumio[897]: info: Getting Tidal Cloud Configuration Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: Getting Qobuz Cloud Configuration Jul 15 12:26:26 volumio volumio[897]: info: Asking plugin for UI Config Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: Getting Spotify Cloud Configuration Jul 15 12:26:26 volumio volumio[897]: info: Asking plugin for UI Config Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: Saving Spotify Acccount Jul 15 12:26:26 volumio volumio[897]: info: Got it Jul 15 12:26:26 volumio volumio[897]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 15 12:26:26 volumio volumio[897]: info: Got Tidal Cloud Configuration Jul 15 12:26:26 volumio volumio[897]: info: Got it Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:26 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 15 12:26:28 volumio ntpd[796]: 192.46.212.117 local addr 192.168.68.61 -> Jul 15 12:26:30 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:34 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:38 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:46 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:50 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:54 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 12:26:56 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Jul 15 12:26:56 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:26:59 volumio volumio[897]: info: CoreCommandRouter::volumioGetQueue Jul 15 12:26:59 volumio volumio[897]: info: CoreStateMachine::getQueue Jul 15 12:26:59 volumio volumio[897]: info: CorePlayQueue::getQueue Jul 15 12:27:01 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 15 12:27:01 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:04 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 15 12:27:04 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:07 volumio volumio[897]: info: CoreCommandRouter::volumioGetState Jul 15 12:27:07 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:12 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 15 12:27:12 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:14 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 15 12:27:14 volumio volumio[897]: info: TuneIn handleBrowseUri: tunein/local Jul 15 12:27:15 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:15 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:19 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:19 volumio volumio[897]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 15 12:27:19 volumio volumio[897]: info: CoreStateMachine::ClearQueue Jul 15 12:27:19 volumio volumio[897]: info: CoreStateMachine::stop Jul 15 12:27:19 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:27:19 volumio volumio[897]: info: CorePlayQueue::clearPlayQueue Jul 15 12:27:19 volumio volumio[897]: info: CorePlayQueue::saveQueue Jul 15 12:27:19 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Jul 15 12:27:19 volumio volumio[897]: info: CoreStateMachine::addQueueItems Jul 15 12:27:19 volumio volumio[897]: info: CorePlayQueue::addQueueItems Jul 15 12:27:19 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:19 volumio volumio[897]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s289580 Jul 15 12:27:19 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 15 12:27:20 volumio volumio[897]: info: CoreCommandRouter::volumioPushQueue Jul 15 12:27:20 volumio volumio[897]: info: CorePlayQueue::saveQueue Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Jul 15 12:27:20 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Jul 15 12:27:20 volumio volumio[897]: info: CoreCommandRouter::volumioPlay Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::play index 0 Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::stop Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::play index undefined Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:27:20 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:20 volumio volumio[897]: info: CoreStateMachine::startPlaybackTimer Jul 15 12:27:20 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:20 volumio volumio[897]: info: CoreCommandRouter::volumioGetVisibleSources Jul 15 12:27:20 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 12:27:20 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 15 12:27:20 volumio volumio[897]: info: [1721026640491] ControllerWebradio::clearAddPlayTrack Jul 15 12:27:20 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Jul 15 12:27:20 volumio volumio[897]: info: sendMpdCommand stop took 3 milliseconds Jul 15 12:27:20 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand clear Jul 15 12:27:20 volumio volumio[897]: info: Jul 15 12:27:20 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:20 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:20 volumio volumio[897]: info: sendMpdCommand clear took 1 milliseconds Jul 15 12:27:20 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand load "http://stream.zeno.fm/kc4wg3ent1duv" Jul 15 12:27:20 volumio volumio[897]: info: Jul 15 12:27:20 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:20 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:20 volumio volumio[897]: error: updateQueue error: null Jul 15 12:27:20 volumio volumio[897]: info: ------------------------------ 4ms Jul 15 12:27:23 volumio volumio[897]: error: updateQueue error: null Jul 15 12:27:23 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand add "http://stream.zeno.fm/kc4wg3ent1duv" Jul 15 12:27:23 volumio volumio[897]: info: ------------------------------ 2761ms Jul 15 12:27:23 volumio volumio[897]: info: Jul 15 12:27:23 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:23 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:23 volumio volumio[897]: info: sendMpdCommand add "http://stream.zeno.fm/kc4wg3ent1duv" took 1 milliseconds Jul 15 12:27:23 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 15 12:27:23 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand play Jul 15 12:27:23 volumio volumio[897]: info: Jul 15 12:27:23 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:23 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:23 volumio volumio[897]: info: ------------------------------ 3ms Jul 15 12:27:23 volumio volumio[897]: info: sendMpdCommand play took 3 milliseconds Jul 15 12:27:23 volumio volumio[897]: info: ------------------------------ 3ms Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 15 12:27:24 volumio volumio[897]: info: camilladsp stopping service pid 4317... Jul 15 12:27:24 volumio volumio[897]: info: camilladsp service terminated, instance 1 Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 15 12:27:24 volumio volumio[897]: info: camilladsp service started and running in background, instance 1 Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 15 12:27:24 volumio volumio[897]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:24 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 60 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 60 milliseconds Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:24 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 8ms Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 6 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 4ms Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kc4wg3ent1duv","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus stop Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"kc4wg3ent1duv","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:27:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 85ms Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 92ms Jul 15 12:27:24 volumio volumio[897]: error: FusionDsp - WebSocket error: [object Object] Jul 15 12:27:24 volumio volumio[897]: info: camilladsp spawned new process with pid 4446, instance 1, run: true Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"kc4wg3ent1duv","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:27:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"kc4wg3ent1duv","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:27:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 84ms Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 80ms Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:24 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces system playlist update Jul 15 12:27:24 volumio volumio[897]: info: Ignoring MPD Status Update Jul 15 12:27:24 volumio volumio[897]: info: Jul 15 12:27:24 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 6ms Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 4ms Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand status took 2 milliseconds Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 15 12:27:24 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":" - 01 Chennudi.mp3","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:27:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:24 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:24 volumio volumio[897]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":" - 01 Chennudi.mp3","artist":"NammRadio USA","album":null,"uri":"http://stream.zeno.fm/kc4wg3ent1duv","trackType":""} Jul 15 12:27:24 volumio volumio[897]: verbose: CURRENT POSITION 0 Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState stateService play Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::syncState currentStatus play Jul 15 12:27:24 volumio volumio[897]: info: Received an update from plugin. extracting info from payload Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:24 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:24 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 34ms Jul 15 12:27:24 volumio volumio[897]: info: ------------------------------ 32ms Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::volumioStop Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::stop Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::stPlaybackTimer Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::updateTrackBlock Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrackBlock Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::serviceStop Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::serviceStop Jul 15 12:27:32 volumio volumio[897]: info: [1721026652415] ControllerWebradio::stop Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand stop Jul 15 12:27:32 volumio volumio[897]: info: Jul 15 12:27:32 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:32 volumio volumio[897]: info: sendMpdCommand stop took 17 milliseconds Jul 15 12:27:32 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:32 volumio volumio[897]: info: sendMpdCommand status took 4 milliseconds Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:32 volumio volumio[897]: info: Jul 15 12:27:32 volumio volumio[897]: ---------------------------- MPD announces state update: player Jul 15 12:27:32 volumio volumio[897]: info: ControllerMpd::getState Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand status Jul 15 12:27:32 volumio volumio[897]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 15 12:27:32 volumio volumio[897]: info: sendMpdCommand status took 1 milliseconds Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::parseState Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 12:27:32 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 15 12:27:32 volumio volumio[897]: info: ------------------------------ 18ms Jul 15 12:27:32 volumio volumio[897]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 15 12:27:32 volumio volumio[897]: verbose: ControllerMpd::parseTrackInfo Jul 15 12:27:32 volumio volumio[897]: info: ControllerMpd::pushState Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::servicePushState Jul 15 12:27:32 volumio volumio[897]: info: CoreStateMachine::pushState Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: CoreCommandRouter::volumioPushState Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output update for this device Jul 15 12:27:32 volumio volumio[897]: info: MRS: Pushing multiroomSync output Jul 15 12:27:32 volumio volumio[897]: info: CorePlayQueue::getTrack 0 Jul 15 12:27:32 volumio volumio[897]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 15 12:27:32 volumio volumio[897]: info: ------------------------------ 24ms Jul 15 12:27:33 volumio volumio[897]: info: camilladsp spawned new process with pid 4456, instance 1, run: true Jul 15 12:27:33 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 15 12:27:42 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:27:42 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:43 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:27:43 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:44 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:27:45 volumio volumio[897]: info: Preload queue cleared Jul 15 12:27:48 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 15 12:27:48 volumio volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 12:27:48 volumio volumio[897]: TypeError: Cannot read property 'length' of undefined Jul 15 12:27:48 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 15 12:27:48 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 15 12:27:48 volumio volumio[897]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 15 12:27:48 volumio volumio[897]: at Parser.emit (events.js:315:20) Jul 15 12:27:48 volumio volumio[897]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 15 12:27:48 volumio volumio[897]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 15 12:27:48 volumio volumio[897]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 15 12:27:48 volumio volumio[897]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 15 12:27:48 volumio volumio[897]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 15 12:27:48 volumio volumio[897]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 15 12:27:48 volumio volumio[897]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 15 12:27:48 volumio volumio[897]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 15 12:27:48 volumio volumio[897]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 15 12:27:48 volumio volumio[897]: at IncomingMessage.emit (events.js:327:22) Jul 15 12:27:48 volumio volumio[897]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 15 12:27:48 volumio volumio[897]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 15 12:27:48 volumio volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 12:27:48 volumio sudo[4478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 12:26 Jul 15 12:27:48 volumio sudo[4478]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"