-- Logs begin at Thu 2019-02-14 18:12:02 CST, end at Thu 2024-11-28 16:22:34 CST. -- Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 28 16:21:00 smsl-d400n sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 28 16:21:00 smsl-d400n sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n sudo[2390]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 28 16:21:00 smsl-d400n sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n systemd[1]: Stopping RAAT DAEMON... Nov 28 16:21:00 smsl-d400n systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 28 16:21:00 smsl-d400n systemd[1]: raat-daemon.service: Succeeded. Nov 28 16:21:00 smsl-d400n systemd[1]: Stopped RAAT DAEMON. Nov 28 16:21:00 smsl-d400n volumio[922]: info: Asound.conf file unchanged, so no further update is needed Nov 28 16:21:00 smsl-d400n volumio[922]: info: Output device has changed, restarting MPD Nov 28 16:21:00 smsl-d400n sudo[2397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 28 16:21:00 smsl-d400n sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n systemd[1]: Started RAAT DAEMON. Nov 28 16:21:00 smsl-d400n sudo[2394]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 16:21:00 smsl-d400n sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n sudo[2397]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n sudo[2403]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n volumio[922]: info: Output device has changed, restarting Shairport Sync Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 16:21:00 smsl-d400n sudo[2410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 16:21:00 smsl-d400n sudo[2410]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n systemd[1]: Stopping Music Player Daemon... Nov 28 16:21:00 smsl-d400n sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 28 16:21:00 smsl-d400n sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n systemd[1]: Stopping RAAT DAEMON... Nov 28 16:21:00 smsl-d400n systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 28 16:21:00 smsl-d400n systemd[1]: raat-daemon.service: Succeeded. Nov 28 16:21:00 smsl-d400n systemd[1]: Stopped RAAT DAEMON. Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n volumio[922]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n volumio[922]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n systemd[1]: Started RAAT DAEMON. Nov 28 16:21:00 smsl-d400n systemd[1]: mpd.service: Succeeded. Nov 28 16:21:00 smsl-d400n systemd[1]: Stopped Music Player Daemon. Nov 28 16:21:00 smsl-d400n sudo[2415]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n volumio[922]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:00 smsl-d400n systemd[1]: Starting Music Player Daemon... Nov 28 16:21:00 smsl-d400n sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 28 16:21:00 smsl-d400n sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n volumio[922]: info: Got it Nov 28 16:21:00 smsl-d400n volumio[922]: info: Got Tidal Cloud Configuration Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:00 smsl-d400n sudo[2443]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 28 16:21:00 smsl-d400n sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:00 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:00 smsl-d400n sudo[2433]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 16:21:00 smsl-d400n sudo[2433]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:00 smsl-d400n sudo[2433]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 28 16:21:00 smsl-d400n sudo[2453]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Nov 28 16:21:00 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 28 16:21:00 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:00 smsl-d400n volumio[922]: info: MPD Permissions set Nov 28 16:21:00 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:01 smsl-d400n volumio[922]: info: Raat Daemon started successfully Nov 28 16:21:01 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:01 smsl-d400n volumio[922]: info: Raat Daemon started successfully Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:01 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:01 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:01 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:01 smsl-d400n volumio[922]: info: Executing endpoint restartRAATSocket Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 28 16:21:01 smsl-d400n volumio[922]: info: Executing endpoint restartRAATSocket Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:01 smsl-d400n volumio[922]: info: Retrieving Cloud Streaming UI Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:01 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:01 smsl-d400n volumio[922]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:01 smsl-d400n volumio[922]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:01 smsl-d400n volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 28 16:21:01 smsl-d400n volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 28 16:21:01 smsl-d400n sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 28 16:21:01 smsl-d400n sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:01 smsl-d400n volumio[922]: info: Starting Shairport Sync Nov 28 16:21:01 smsl-d400n volumio[922]: info: Getting Tidal Cloud Configuration Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:01 smsl-d400n volumio[922]: info: Getting Qobuz Cloud Configuration Nov 28 16:21:01 smsl-d400n volumio[922]: info: Asking plugin for UI Config Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:01 smsl-d400n sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 16:21:01 smsl-d400n sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:01 smsl-d400n systemd[1]: Started Volumio Tidal Connect Service. Nov 28 16:21:01 smsl-d400n volumio[922]: info: Getting Spotify Cloud Configuration Nov 28 16:21:01 smsl-d400n volumio[922]: info: Asking plugin for UI Config Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:01 smsl-d400n volumio[922]: info: Saving Spotify Acccount Nov 28 16:21:01 smsl-d400n volumio[922]: info: Got it Nov 28 16:21:01 smsl-d400n volumio[922]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 28 16:21:01 smsl-d400n sudo[2486]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:01 smsl-d400n systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 16:21:01 smsl-d400n systemd[1]: shairport-sync.service: Succeeded. Nov 28 16:21:01 smsl-d400n systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 16:21:01 smsl-d400n systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 16:21:01 smsl-d400n sudo[2489]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:01 smsl-d400n volumio[922]: xcb_connection_has_error() returned true Nov 28 16:21:01 smsl-d400n sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 28 16:21:01 smsl-d400n sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:01 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:01 smsl-d400n sudo[2499]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:01 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:01 smsl-d400n volumio[922]: info: Shairport-Sync Started Nov 28 16:21:01 smsl-d400n sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 28 16:21:01 smsl-d400n sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:01 smsl-d400n volumio[922]: info: Got Tidal Cloud Configuration Nov 28 16:21:01 smsl-d400n volumio[922]: info: Got it Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:01 smsl-d400n systemd[1]: Stopping RAAT DAEMON... Nov 28 16:21:01 smsl-d400n systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 28 16:21:01 smsl-d400n systemd[1]: raat-daemon.service: Succeeded. Nov 28 16:21:01 smsl-d400n systemd[1]: Stopped RAAT DAEMON. Nov 28 16:21:01 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:01 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:01 smsl-d400n systemd[1]: Started RAAT DAEMON. Nov 28 16:21:01 smsl-d400n sudo[2502]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:01 smsl-d400n volumio[922]: info: Raat Daemon started successfully Nov 28 16:21:01 smsl-d400n volumio[922]: info: Executing endpoint tc_getconfig Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 28 16:21:01 smsl-d400n vtcs[2490]: STARTING TidalConnect services, version: 1.4.0.34 Nov 28 16:21:01 smsl-d400n volumio[922]: info: Executing endpoint restartRAATSocket Nov 28 16:21:01 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 28 16:21:02 smsl-d400n vtcs[2490]: STARTED TidalConnect services. Nov 28 16:21:02 smsl-d400n volumio[922]: info: Executing endpoint tc_connect Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 28 16:21:02 smsl-d400n volumio[922]: info: Connecting to TidalConnect Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: 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} Nov 28 16:21:02 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService stop Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: No code Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: 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} Nov 28 16:21:02 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService stop Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: No code Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:02 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:02 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:02 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:02 smsl-d400n volumio[922]: info: Discovery: Started advertising with name: SMSL D400N Nov 28 16:21:02 smsl-d400n volumio[922]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Nov 28 16:21:02 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Nov 28 16:21:02 smsl-d400n volumio[922]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Nov 28 16:21:02 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:02 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:02 smsl-d400n volumio[922]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:02 smsl-d400n volumio[922]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:02 smsl-d400n volumio[922]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:02 smsl-d400n volumio[922]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:02 smsl-d400n sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 28 16:21:02 smsl-d400n sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:02 smsl-d400n sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 28 16:21:02 smsl-d400n sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:02 smsl-d400n sudo[2549]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:02 smsl-d400n sudo[2552]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:03 smsl-d400n volumio[922]: info: RAAT: Requesting Headphone Status Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 28 16:21:03 smsl-d400n mpd[2458]: Nov 28 16:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 16:21:03 smsl-d400n systemd[1]: Started Music Player Daemon. Nov 28 16:21:03 smsl-d400n sudo[2410]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:03 smsl-d400n volumio[922]: error: updateQueue error: null Nov 28 16:21:03 smsl-d400n volumio[922]: info: RAAT: Requesting Headphone Status Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreStateMachine::getcurrentVolume Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:03 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:03 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:03 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:03 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:03 smsl-d400n volumio[922]: info: Discovery: adding 52a3211c-232e-4bb6-848a-8f68165efd64 Nov 28 16:21:03 smsl-d400n volumio[922]: info: Discovery: Found device SMSL D400N Nov 28 16:21:03 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:03 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:03 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:03 smsl-d400n volumio[922]: info: TidalConnect service stoped! Nov 28 16:21:03 smsl-d400n volumio[922]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:03 smsl-d400n volumio[922]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 28 16:21:03 smsl-d400n sudo[2568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 28 16:21:03 smsl-d400n sudo[2568]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:04 smsl-d400n sudo[2568]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:04 smsl-d400n volumio[922]: info: Retrieving Cloud Streaming UI Nov 28 16:21:04 smsl-d400n volumio[922]: info: Getting Tidal Cloud Configuration Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:04 smsl-d400n volumio[922]: info: Getting Qobuz Cloud Configuration Nov 28 16:21:04 smsl-d400n volumio[922]: info: Asking plugin for UI Config Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:04 smsl-d400n volumio[922]: info: Getting Spotify Cloud Configuration Nov 28 16:21:04 smsl-d400n volumio[922]: info: Asking plugin for UI Config Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:04 smsl-d400n volumio[922]: info: Saving Spotify Acccount Nov 28 16:21:04 smsl-d400n volumio[922]: info: Got it Nov 28 16:21:04 smsl-d400n volumio[922]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 28 16:21:04 smsl-d400n volumio[922]: info: Got Tidal Cloud Configuration Nov 28 16:21:04 smsl-d400n volumio[922]: info: Got it Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::Reload Ui Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Nov 28 16:21:04 smsl-d400n volumio[922]: info: Wizard terminated Successfully Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::volumiosetStartupVolume Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 16:21:04 smsl-d400n volumio[922]: info: TidalConnect service started! Nov 28 16:21:04 smsl-d400n volumio[922]: info: RAAT: Requesting Headphone Status Nov 28 16:21:04 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 28 16:21:05 smsl-d400n sudo[2575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 16:21:05 smsl-d400n sudo[2575]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:05 smsl-d400n sudo[2575]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:05 smsl-d400n sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 16:21:05 smsl-d400n sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:05 smsl-d400n sudo[2578]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:05 smsl-d400n volumio[922]: info: TidalConnect service started! Nov 28 16:21:05 smsl-d400n volumio[922]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.179 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 10 Nov 28 16:21:05 smsl-d400n volumio[922]: info: TidalConnect service started! Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 16:21:06 smsl-d400n volumio[922]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 28 16:21:06 smsl-d400n volumio[922]: info: Received Get System Info Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 16:21:06 smsl-d400n volumio[922]: info: Discovery: Getting this device information Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: Listing playlists Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 16:21:06 smsl-d400n sudo[2583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 16:21:06 smsl-d400n sudo[2583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:06 smsl-d400n sudo[2583]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:06 smsl-d400n sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 16:21:06 smsl-d400n sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:06 smsl-d400n sudo[2586]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:06 smsl-d400n volumio[922]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.179 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 10 Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 16:21:06 smsl-d400n volumio[922]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 28 16:21:06 smsl-d400n volumio[922]: info: Received Get System Info Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 16:21:06 smsl-d400n volumio[922]: info: Discovery: Getting this device information Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:06 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:06 smsl-d400n volumio[922]: info: Listing playlists Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 16:21:06 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 16:21:07 smsl-d400n volumio[922]: info: TidalConnect service started! Nov 28 16:21:07 smsl-d400n sudo[2591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Nov 28 16:21:07 smsl-d400n sudo[2591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n sudo[2591]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:07 smsl-d400n volumio[922]: info: smb.conf Permissions set Nov 28 16:21:07 smsl-d400n sudo[2594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Nov 28 16:21:07 smsl-d400n sudo[2594]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n sudo[2594]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:07 smsl-d400n volumio[922]: info: Internal perms successfully set Nov 28 16:21:07 smsl-d400n sudo[2597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Nov 28 16:21:07 smsl-d400n sudo[2597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n nmbd[872]: [2024/11/28 16:21:07.626474, 0] ../source3/nmbd/nmbd.c:58(terminate) Nov 28 16:21:07 smsl-d400n nmbd[872]: Got SIGTERM: going down... Nov 28 16:21:07 smsl-d400n systemd[1]: Stopping Samba NMB Daemon... Nov 28 16:21:07 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:07 smsl-d400n systemd[1]: nmbd.service: Succeeded. Nov 28 16:21:07 smsl-d400n systemd[1]: Stopped Samba NMB Daemon. Nov 28 16:21:07 smsl-d400n sudo[2600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 16:21:07 smsl-d400n sudo[2600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n systemd[1]: Starting Samba NMB Daemon... Nov 28 16:21:07 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 28 16:21:07 smsl-d400n sudo[2600]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:07 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 28 16:21:07 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:07 smsl-d400n sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 16:21:07 smsl-d400n sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:07 smsl-d400n sudo[2604]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:07 smsl-d400n sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 16:21:07 smsl-d400n sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:07 smsl-d400n sudo[2610]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:07 smsl-d400n volumio[922]: info: Upmpdcli Daemon Started Nov 28 16:21:07 smsl-d400n nmbd[2605]: [2024/11/28 16:21:07.986960, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 28 16:21:07 smsl-d400n nmbd[2605]: started asyncdns process 2612 Nov 28 16:21:07 smsl-d400n nmbd[2605]: [2024/11/28 16:21:07.993291, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 16:21:07 smsl-d400n nmbd[2605]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 28 16:21:07 smsl-d400n systemd[1]: Started Samba NMB Daemon. Nov 28 16:21:07 smsl-d400n sudo[2597]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:08 smsl-d400n nmbd[2605]: [2024/11/28 16:21:08.007760, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 28 16:21:08 smsl-d400n nmbd[2605]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.10 for name WORKGROUP<1d>. Nov 28 16:21:08 smsl-d400n nmbd[2605]: This response was from IP 192.168.2.95, reporting an IP address of 192.168.2.95. Nov 28 16:21:08 smsl-d400n sudo[2614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Nov 28 16:21:08 smsl-d400n sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:08 smsl-d400n systemd[1]: Stopping Samba SMB Daemon... Nov 28 16:21:08 smsl-d400n systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Nov 28 16:21:08 smsl-d400n systemd[1]: smbd.service: Succeeded. Nov 28 16:21:08 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:08 smsl-d400n systemd[1]: Stopped Samba SMB Daemon. Nov 28 16:21:08 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 28 16:21:08 smsl-d400n systemd[1]: Starting Samba SMB Daemon... Nov 28 16:21:08 smsl-d400n volumio[922]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 16:21:08 smsl-d400n volumio[922]: info: Received Get System Info Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 16:21:08 smsl-d400n volumio[922]: info: Discovery: Getting this device information Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetState Nov 28 16:21:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 16:21:08 smsl-d400n volumio-remote-updater[731]: No test mode Nov 28 16:21:08 smsl-d400n volumio-remote-updater[731]: No alpha test mode Nov 28 16:21:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 28 16:21:08 smsl-d400n winbindd[1405]: [2024/11/28 16:21:08.932950, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Nov 28 16:21:08 smsl-d400n winbindd[1405]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1855304790-311451866-3447901999-501 Nov 28 16:21:08 smsl-d400n winbindd[1405]: [2024/11/28 16:21:08.933949, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Nov 28 16:21:08 smsl-d400n winbindd[1405]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1855304790-311451866-3447901999-514 Nov 28 16:21:08 smsl-d400n smbd[2626]: [2024/11/28 16:21:08.956192, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 16:21:08 smsl-d400n smbd[2626]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 28 16:21:08 smsl-d400n systemd[1]: Started Samba SMB Daemon. Nov 28 16:21:08 smsl-d400n sudo[2614]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:08 smsl-d400n volumio[922]: info: SAMBA Restarted Nov 28 16:21:09 smsl-d400n volumio[922]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 28 16:21:09 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 28 16:21:09 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Nov 28 16:21:09 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetQueue Nov 28 16:21:09 smsl-d400n volumio[922]: info: CoreStateMachine::getQueue Nov 28 16:21:09 smsl-d400n volumio[922]: info: CorePlayQueue::getQueue Nov 28 16:21:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 28 16:21:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 16:21:12 smsl-d400n volumio-remote-updater[731]: No test mode Nov 28 16:21:12 smsl-d400n volumio-remote-updater[731]: No alpha test mode Nov 28 16:21:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 28 16:21:12 smsl-d400n volumio[922]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 28 16:21:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 16:21:14 smsl-d400n systemd[1]: systemd-timedated.service: Succeeded. Nov 28 16:21:14 smsl-d400n volumio-remote-updater[731]: No test mode Nov 28 16:21:14 smsl-d400n volumio-remote-updater[731]: No alpha test mode Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 16:21:14 smsl-d400n volumio[922]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 28 16:21:14 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Nov 28 16:21:15 smsl-d400n sudo[2657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Nov 28 16:21:15 smsl-d400n sudo[2657]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2657]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 16:21:15 smsl-d400n sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 16:21:15 smsl-d400n sudo[2673]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2663]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 28 16:21:15 smsl-d400n sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2673]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 16:21:15 smsl-d400n sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 28 16:21:15 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 28 16:21:15 smsl-d400n sudo[2693]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2683]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 16:21:15 smsl-d400n sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n volumio[922]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 28 16:21:15 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 28 16:21:15 smsl-d400n sudo[2700]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 16:21:15 smsl-d400n sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2705]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n volumio[922]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 28 16:21:15 smsl-d400n volumio[922]: wlan0 Interface doesn't support scanning. Nov 28 16:21:15 smsl-d400n volumio[922]: info: Cannot use regular scanning, forcing with ap-force Nov 28 16:21:15 smsl-d400n sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 28 16:21:15 smsl-d400n sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 16:21:15 smsl-d400n sudo[2715]: pam_unix(sudo:session): session closed for user root Nov 28 16:21:15 smsl-d400n volumio[922]: command failed: No such device (-19) Nov 28 16:21:15 smsl-d400n volumio[922]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 28 16:21:15 smsl-d400n volumio[922]: command failed: No such device (-19) Nov 28 16:21:16 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 28 16:21:23 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPlay Nov 28 16:21:23 smsl-d400n volumio[922]: info: CoreStateMachine::play index undefined Nov 28 16:21:23 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:23 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:34 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 28 16:21:34 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 16:21:35 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:39 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 28 16:21:42 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:43 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::ClearQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::clearPlayQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::addQueueItems Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::addQueueItems Nov 28 16:21:43 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:43 smsl-d400n volumio[922]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPlay Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::play index 0 Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::play index undefined Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreStateMachine::startPlaybackTimer Nov 28 16:21:43 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:21:43 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 16:21:43 smsl-d400n volumio[922]: info: [1732782103482] ControllerWebradio::clearAddPlayTrack Nov 28 16:21:43 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:21:43 smsl-d400n volumio[922]: info: sendMpdCommand stop took 8 milliseconds Nov 28 16:21:43 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 16:21:43 smsl-d400n volumio[922]: info: Nov 28 16:21:43 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:43 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:43 smsl-d400n volumio[922]: info: sendMpdCommand clear took 5 milliseconds Nov 28 16:21:43 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Nov 28 16:21:43 smsl-d400n volumio[922]: error: updateQueue error: null Nov 28 16:21:43 smsl-d400n volumio[922]: info: ------------------------------ 9ms Nov 28 16:21:44 smsl-d400n volumio[922]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 880 milliseconds Nov 28 16:21:44 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 16:21:44 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand play Nov 28 16:21:44 smsl-d400n volumio[922]: info: Nov 28 16:21:44 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:44 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:44 smsl-d400n volumio[922]: info: sendMpdCommand play took 7 milliseconds Nov 28 16:21:44 smsl-d400n volumio[922]: info: ------------------------------ 6ms Nov 28 16:21:46 smsl-d400n volumio[922]: info: Nov 28 16:21:46 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:46 smsl-d400n volumio[922]: info: Nov 28 16:21:46 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand status took 8 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand status took 5 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:46 smsl-d400n volumio[922]: 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":"antenne","artist":"(#1 - 5740/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:21:46 smsl-d400n volumio[922]: info: ------------------------------ 30ms Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:46 smsl-d400n volumio[922]: 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":"antenne","artist":"(#1 - 5740/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:46 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:46 smsl-d400n volumio[922]: info: ------------------------------ 71ms Nov 28 16:21:46 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:46 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:46 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:46 smsl-d400n volumio[922]: info: Nov 28 16:21:46 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:46 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:46 smsl-d400n volumio[922]: info: Nov 28 16:21:46 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:46 smsl-d400n volumio[922]: info: ------------------------------ 10ms Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand status took 3 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bryan Adams - Run to you","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Nov 28 16:21:46 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:46 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:46 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:46 smsl-d400n volumio[922]: info: ------------------------------ 62ms Nov 28 16:21:46 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:46 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:46 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:47 smsl-d400n volumio[922]: info: Nov 28 16:21:47 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:47 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:47 smsl-d400n volumio[922]: info: Nov 28 16:21:47 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:47 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:47 smsl-d400n volumio[922]: info: ------------------------------ 6ms Nov 28 16:21:47 smsl-d400n volumio[922]: info: sendMpdCommand status took 3 milliseconds Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:47 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:47 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bryan Adams - Run to you","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Nov 28 16:21:47 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:47 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:47 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:47 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:47 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:47 smsl-d400n volumio[922]: info: ------------------------------ 52ms Nov 28 16:21:47 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:47 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:47 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:56 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::ClearQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::stPlaybackTimer Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:56 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:56 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::serviceStop Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::serviceStop Nov 28 16:21:56 smsl-d400n volumio[922]: info: [1732782116483] ControllerWebradio::stop Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::clearPlayQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::addQueueItems Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::addQueueItems Nov 28 16:21:56 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:21:56 smsl-d400n volumio[922]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPlay Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::play index 0 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::play index undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreStateMachine::startPlaybackTimer Nov 28 16:21:56 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:21:56 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 16:21:56 smsl-d400n volumio[922]: info: [1732782116527] ControllerWebradio::clearAddPlayTrack Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:21:56 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:56 smsl-d400n volumio[922]: info: Nov 28 16:21:56 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:56 smsl-d400n volumio[922]: info: sendMpdCommand stop took 71 milliseconds Nov 28 16:21:56 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:56 smsl-d400n volumio[922]: info: sendMpdCommand stop took 27 milliseconds Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 16:21:56 smsl-d400n volumio[922]: info: Nov 28 16:21:56 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:56 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:56 smsl-d400n volumio[922]: info: sendMpdCommand status took 6 milliseconds Nov 28 16:21:56 smsl-d400n volumio[922]: info: sendMpdCommand clear took 4 milliseconds Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276" Nov 28 16:21:56 smsl-d400n volumio[922]: error: updateQueue error: null Nov 28 16:21:56 smsl-d400n volumio[922]: info: ------------------------------ 10ms Nov 28 16:21:56 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 28 16:21:56 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:56 smsl-d400n volumio[922]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 16:21:56 smsl-d400n volumio[922]: info: ------------------------------ 21ms Nov 28 16:21:56 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:57 smsl-d400n volumio[922]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1454276" took 916 milliseconds Nov 28 16:21:57 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 16:21:57 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand play Nov 28 16:21:57 smsl-d400n volumio[922]: info: Nov 28 16:21:57 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:57 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:57 smsl-d400n volumio[922]: info: sendMpdCommand play took 3 milliseconds Nov 28 16:21:57 smsl-d400n volumio[922]: info: ------------------------------ 4ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand status took 4 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand status took 5 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: 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":"stream","artist":"(#1 - 529/2500) Radio Manele Romania wWw.FMRadioManele.Ro","album":null,"uri":"http://141.94.96.97:8123/stream","trackType":""} Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 23ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: 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":"stream","artist":"(#1 - 529/2500) Radio Manele Romania wWw.FMRadioManele.Ro","album":null,"uri":"http://141.94.96.97:8123/stream","trackType":""} Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:58 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 75ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:58 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 10ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand status took 4 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio Manele Romania wWw.FMRadioManele.Ro","album":null,"uri":"http://141.94.96.97:8123/stream","trackType":""} Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:58 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 55ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:21:58 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:21:58 smsl-d400n volumio[922]: info: Nov 28 16:21:58 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 6ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand status took 4 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:21:58 smsl-d400n volumio[922]: 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":"128 Kbps","isStreaming":false,"title":"Denisa Botiș - DENISA BOTIȘ - Am o viață, numai una | Colaj de petrecere | NOU ✨ COVER","artist":"Radio Manele Romania wWw.FMRadioManele.Ro","album":null,"uri":"http://141.94.96.97:8123/stream","trackType":""} Nov 28 16:21:58 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:21:58 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:21:58 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:21:58 smsl-d400n volumio[922]: info: ------------------------------ 61ms Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:21:58 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:08 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::ClearQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::stPlaybackTimer Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:08 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:08 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::serviceStop Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::serviceStop Nov 28 16:22:08 smsl-d400n volumio[922]: info: [1732782128749] ControllerWebradio::stop Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::clearPlayQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::addQueueItems Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::addQueueItems Nov 28 16:22:08 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:08 smsl-d400n volumio[922]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPlay Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::play index 0 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::play index undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreStateMachine::startPlaybackTimer Nov 28 16:22:08 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:22:08 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 16:22:08 smsl-d400n volumio[922]: info: [1732782128787] ControllerWebradio::clearAddPlayTrack Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:22:08 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:08 smsl-d400n volumio[922]: info: Nov 28 16:22:08 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:08 smsl-d400n volumio[922]: info: sendMpdCommand stop took 53 milliseconds Nov 28 16:22:08 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:08 smsl-d400n volumio[922]: info: sendMpdCommand stop took 23 milliseconds Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 16:22:08 smsl-d400n volumio[922]: info: Nov 28 16:22:08 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:08 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:08 smsl-d400n volumio[922]: info: sendMpdCommand status took 6 milliseconds Nov 28 16:22:08 smsl-d400n volumio[922]: info: sendMpdCommand clear took 4 milliseconds Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" Nov 28 16:22:08 smsl-d400n volumio[922]: error: updateQueue error: null Nov 28 16:22:08 smsl-d400n volumio[922]: info: ------------------------------ 8ms Nov 28 16:22:08 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 28 16:22:08 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:08 smsl-d400n volumio[922]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 16:22:08 smsl-d400n volumio[922]: info: ------------------------------ 24ms Nov 28 16:22:08 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:09 smsl-d400n volumio[922]: info: Nov 28 16:22:09 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:09 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:09 smsl-d400n volumio[922]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" took 343 milliseconds Nov 28 16:22:09 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 16:22:09 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand play Nov 28 16:22:09 smsl-d400n volumio[922]: info: ------------------------------ 4ms Nov 28 16:22:09 smsl-d400n volumio[922]: info: sendMpdCommand play took 3 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand status took 4 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand status took 14 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: 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":"chillout","artist":"(#1 - 961/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 29ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: 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":"chillout","artist":"(#1 - 961/500000) ANTENNE BAYERN Chillout (Germany)","album":null,"uri":"http://stream.antenne.de:80/chillout","trackType":""} Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:10 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 71ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:10 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:10 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:10 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::ClearQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::stPlaybackTimer Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:10 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::serviceStop Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::serviceStop Nov 28 16:22:10 smsl-d400n volumio[922]: info: [1732782130607] ControllerWebradio::stop Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::clearPlayQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::addQueueItems Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::addQueueItems Nov 28 16:22:10 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:10 smsl-d400n volumio[922]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::saveQueue Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::updateTrackBlock Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrackBlock Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPlay Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::play index 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::stop Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::play index undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::startPlaybackTimer Nov 28 16:22:10 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 16:22:10 smsl-d400n volumio[922]: info: [1732782130643] ControllerWebradio::clearAddPlayTrack Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand stop took 41 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand stop took 7 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 16:22:10 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 22ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand status took 18 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 21ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand status took 18 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand clear took 16 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" Nov 28 16:22:10 smsl-d400n volumio[922]: error: updateQueue error: null Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 16ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:10 smsl-d400n volumio[922]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 16:22:10 smsl-d400n volumio[922]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 40ms Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 40ms Nov 28 16:22:10 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 230 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 16:22:10 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand play Nov 28 16:22:10 smsl-d400n volumio[922]: info: Nov 28 16:22:10 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:10 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:10 smsl-d400n volumio[922]: info: sendMpdCommand play took 3 milliseconds Nov 28 16:22:10 smsl-d400n volumio[922]: info: ------------------------------ 3ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand status took 3 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: 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":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus stop Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 16ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand status took 7 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: 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":"oldies-but-goldies","artist":"(#1 - 20/500000) ANTENNE BAYERN Oldies but Goldies (Germany)","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:11 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 57ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:11 smsl-d400n volumio[922]: info: Nov 28 16:22:11 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 11ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand status took 8 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 13ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand status took 9 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 11ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand status took 8 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1113,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Soulsister - Ain't that simple (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:11 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 64ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 45 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 44 milliseconds Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1113,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Soulsister - Ain't that simple (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:11 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1113,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Soulsister - Ain't that simple (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:11 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:11 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:11 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 124ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: ------------------------------ 123ms Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:11 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:12 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:12 smsl-d400n volumio[922]: info: Nov 28 16:22:12 smsl-d400n volumio[922]: ---------------------------- MPD announces system playlist update Nov 28 16:22:12 smsl-d400n volumio[922]: info: Ignoring MPD Status Update Nov 28 16:22:12 smsl-d400n volumio[922]: info: Nov 28 16:22:12 smsl-d400n volumio[922]: ---------------------------- MPD announces state update: player Nov 28 16:22:12 smsl-d400n volumio[922]: info: ControllerMpd::getState Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand status Nov 28 16:22:12 smsl-d400n volumio[922]: info: ------------------------------ 5ms Nov 28 16:22:12 smsl-d400n volumio[922]: info: sendMpdCommand status took 3 milliseconds Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: ControllerMpd::parseState Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 16:22:12 smsl-d400n volumio[922]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: ControllerMpd::parseTrackInfo Nov 28 16:22:12 smsl-d400n volumio[922]: info: ControllerMpd::pushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreCommandRouter::servicePushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: CorePlayQueue::getTrack 0 Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2608,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Soulsister - Ain't that simple (1993)","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""} Nov 28 16:22:12 smsl-d400n volumio[922]: verbose: CURRENT POSITION 0 Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreStateMachine::syncState stateService play Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreStateMachine::syncState currentStatus play Nov 28 16:22:12 smsl-d400n volumio[922]: info: Received an update from plugin. extracting info from payload Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:12 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreStateMachine::pushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: CoreCommandRouter::volumioPushState Nov 28 16:22:12 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output update for this device Nov 28 16:22:12 smsl-d400n volumio[922]: info: MRS: Pushing multiroomSync output Nov 28 16:22:13 smsl-d400n volumio[922]: info: ------------------------------ 45ms Nov 28 16:22:13 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:13 smsl-d400n volumio[922]: info: Updating RAAT Signal Path Nov 28 16:22:13 smsl-d400n volumio[922]: error: Serial API: Failed to decode command: GET, message: STATE Nov 28 16:22:19 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 28 16:22:19 smsl-d400n volumio[922]: info: CURURI: music-library Nov 28 16:22:19 smsl-d400n volumio[922]: error: Failed LSINFO: null Nov 28 16:22:19 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:27 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 28 16:22:27 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:29 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 28 16:22:29 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:30 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 28 16:22:31 smsl-d400n volumio[922]: info: Preload queue cleared Nov 28 16:22:33 smsl-d400n volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 28 16:22:33 smsl-d400n volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 16:22:33 smsl-d400n volumio[922]: TypeError: Cannot read property 'length' of undefined Nov 28 16:22:33 smsl-d400n volumio[922]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 28 16:22:33 smsl-d400n volumio[922]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 28 16:22:33 smsl-d400n volumio[922]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 28 16:22:33 smsl-d400n volumio[922]: at Parser.emit (events.js:400:28) Nov 28 16:22:33 smsl-d400n volumio[922]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 28 16:22:33 smsl-d400n volumio[922]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 28 16:22:33 smsl-d400n volumio[922]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 28 16:22:33 smsl-d400n volumio[922]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 28 16:22:33 smsl-d400n volumio[922]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 28 16:22:33 smsl-d400n volumio[922]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 28 16:22:33 smsl-d400n volumio[922]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 28 16:22:33 smsl-d400n volumio[922]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 28 16:22:33 smsl-d400n volumio[922]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 28 16:22:33 smsl-d400n volumio[922]: at IncomingMessage.emit (events.js:412:35) Nov 28 16:22:33 smsl-d400n volumio[922]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 28 16:22:33 smsl-d400n volumio[922]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Nov 28 16:22:33 smsl-d400n volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 16:22:34 smsl-d400n sudo[3081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 16:21 Nov 28 16:22:34 smsl-d400n sudo[3081]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 14 Nov 2024 10:37:31 AM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="693350a73c5f32caa813f2d9ff9306ea"