-- 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"