-- Logs begin at Fri 2025-07-04 19:28:02 CST, end at Fri 2025-07-04 19:45:19 CST. -- Jul 04 19:44:00 smsl-n100 volumio-remote-updater[2803]: [2025-07-04 19:44:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751629438 101 Jul 04 19:44:00 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 1, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:00 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:00 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:00 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:00 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 04 19:44:00 smsl-n100 sudo[3412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 04 19:44:00 smsl-n100 sudo[3412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:01 smsl-n100 sudo[3412]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Access Token successfully retrieved Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 19:44:01 smsl-n100 volumio[3086]: info: [1751629441249] CoreMusicLibrary::Adding element QOBUZ Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 19:44:01 smsl-n100 volumio[3086]: Cannot find translation for source QOBUZ Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Stopping AccessToken refresher cron for QOBUZ Jul 04 19:44:01 smsl-n100 volumio[3086]: info: AccessToken refresher cron started for QOBUZ Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Adding QOBUZ REST API Endpoints Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Volumio BT Module successfully started Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:01 smsl-n100 sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:01 smsl-n100 sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:01 smsl-n100 sudo[3417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:01 smsl-n100 sudo[3417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:01 smsl-n100 shairport-sync[2538]: {"time":1751629369342,"response":"startAirplayPlayback Success"} Jul 04 19:44:01 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 19:44:01 smsl-n100 sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:01 smsl-n100 sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:01 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:01 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:01 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:01 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:01 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:01 smsl-n100 sudo[3415]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:01 smsl-n100 sudo[3417]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:01 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:01 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:01 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:01 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 19:44:01 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:01 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 04 19:44:01 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:01 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:01 smsl-n100 sudo[3421]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:01 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:01 smsl-n100 volumio[3086]: info: Volumio called home Jul 04 19:44:02 smsl-n100 volumio[3086]: info: TidalConnect service stoped! Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Preparing to generate the ALSA configuration file Jul 04 19:44:02 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:02 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:02 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:02 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:02 smsl-n100 volumio[3086]: Error adding Membership: Error: addMembership EINVAL Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Access Token successfully retrieved Jul 04 19:44:02 smsl-n100 volumio[3086]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Reading ALSA contributions from plugins. Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:02 smsl-n100 sudo[3436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 04 19:44:02 smsl-n100 sudo[3436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:02 smsl-n100 volumio[3086]: info: Asound.conf file written Jul 04 19:44:02 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Jul 04 19:44:02 smsl-n100 sudo[3436]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:02 smsl-n100 sudo[3441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 04 19:44:02 smsl-n100 sudo[3441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:02 smsl-n100 sudo[3441]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:03 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Output device has changed, restarting MPD Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Output device has changed, restarting Shairport Sync Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:03 smsl-n100 sudo[3447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 19:44:03 smsl-n100 sudo[3447]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:03 smsl-n100 sudo[3447]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:03 smsl-n100 sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 19:44:03 smsl-n100 sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:03 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:03 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 04 19:44:03 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 04 19:44:03 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 04 19:44:03 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 19:44:03 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Preparing to generate the ALSA configuration file Jul 04 19:44:03 smsl-n100 sudo[3455]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 19:44:03 smsl-n100 sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:03 smsl-n100 sudo[3455]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:03 smsl-n100 sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 19:44:03 smsl-n100 sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Getting audio outputs on start Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Requesting all other devices output Jul 04 19:44:03 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Jul 04 19:44:03 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:03 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:03 smsl-n100 systemd[1]: vtcs.service: Succeeded. Jul 04 19:44:03 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Jul 04 19:44:03 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:03 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:03 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:03 smsl-n100 sudo[3466]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:03 smsl-n100 volumio[3086]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Reading ALSA contributions from plugins. Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MPD Permissions set Jul 04 19:44:03 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Help! Some callbacks for volumioPushState are crashing! Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Cannot read property 'sendVolumeMute' of undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Help! Some callbacks for volumioPushState are crashing! Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Cannot read property 'sendVolumeMute' of undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:03 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:03 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:03 smsl-n100 volumio[3086]: info: Executing endpoint tc_getconfig Jul 04 19:44:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Not Reporting Auto name since its the default one Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Successfully retrieved User Session From TIDAL Jul 04 19:44:04 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 04 19:44:04 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:04 smsl-n100 sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 04 19:44:04 smsl-n100 sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:04 smsl-n100 sudo[3494]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:04 smsl-n100 sudo[3497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:04 smsl-n100 sudo[3497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:04 smsl-n100 sudo[3498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 04 19:44:04 smsl-n100 sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:04 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 19:44:04 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Asound.conf file unchanged, so no further update is needed Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Output device has changed, restarting MPD Jul 04 19:44:04 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:04 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Jul 04 19:44:04 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:04 smsl-n100 sudo[3497]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:04 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:04 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Jul 04 19:44:04 smsl-n100 volumio[3086]: info: Output device has changed, restarting Shairport Sync Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:04 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:04 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Jul 04 19:44:04 smsl-n100 systemd[1]: Started RAAT DAEMON. Jul 04 19:44:04 smsl-n100 sudo[3498]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:04 smsl-n100 sudo[3503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 19:44:04 smsl-n100 sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:04 smsl-n100 sudo[3503]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:04 smsl-n100 sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 19:44:04 smsl-n100 sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:04 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:04 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 04 19:44:04 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 04 19:44:05 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 04 19:44:05 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 19:44:05 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:05 smsl-n100 volumio[3086]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Preparing to generate the ALSA configuration file Jul 04 19:44:05 smsl-n100 sudo[3522]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 19:44:05 smsl-n100 sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:05 smsl-n100 sudo[3522]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:05 smsl-n100 sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:05 smsl-n100 sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:05 smsl-n100 sudo[3527]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:05 smsl-n100 volumio[3086]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Reading ALSA contributions from plugins. Jul 04 19:44:05 smsl-n100 volumio[3086]: info: MPD Permissions set Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Raat Daemon started successfully Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:05 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:05 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Help! Some callbacks for volumioPushState are crashing! Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Cannot read property 'sendVolumeMute' of undefined Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:05 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Executing endpoint restartRAATSocket Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 04 19:44:05 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:05 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Jul 04 19:44:05 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Jul 04 19:44:05 smsl-n100 sudo[3329]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 sudo[3449]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 sudo[3505]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 sudo[3340]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Not Reporting Auto name since its the default one Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:05 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:05 smsl-n100 volumio[3086]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 04 19:44:05 smsl-n100 volumio[3086]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 04 19:44:05 smsl-n100 volumio[3086]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 04 19:44:05 smsl-n100 volumio[3086]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 04 19:44:05 smsl-n100 volumio[3086]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 04 19:44:05 smsl-n100 sudo[3539]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 04 19:44:05 smsl-n100 volumio[3086]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 04 19:44:05 smsl-n100 volumio[3086]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 04 19:44:05 smsl-n100 sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 04 19:44:05 smsl-n100 volumio[3086]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 04 19:44:05 smsl-n100 volumio[3086]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Successfully retrieved User Subscription From TIDAL Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Adding TIDAL to Browse Sources Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 19:44:05 smsl-n100 volumio[3086]: info: [1751629445959] CoreMusicLibrary::Adding element TIDAL Jul 04 19:44:05 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 19:44:05 smsl-n100 sudo[3539]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:05 smsl-n100 volumio[3086]: Cannot find translation for source QOBUZ Jul 04 19:44:05 smsl-n100 volumio[3086]: Cannot find translation for source TIDAL Jul 04 19:44:05 smsl-n100 volumio[3086]: info: Stopping AccessToken refresher cron Jul 04 19:44:06 smsl-n100 volumio[3086]: info: AccessToken refresher cron started Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Adding TIDAL REST API Endpoints Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 04 19:44:06 smsl-n100 volumio[3086]: info: TidalConnect service started! Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Completed starting Core Plugins Jul 04 19:44:06 smsl-n100 volumio[3086]: info: ------------------------------------------- Jul 04 19:44:06 smsl-n100 volumio[3086]: info: ----- MyVolumio plugins startup ---- Jul 04 19:44:06 smsl-n100 volumio[3086]: info: ------------------------------------------- Jul 04 19:44:06 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 19:44:06 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:06 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 04 19:44:06 smsl-n100 sudo[3549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:06 smsl-n100 sudo[3549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:06 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 19:44:06 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 04 19:44:06 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:06 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:06 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:06 smsl-n100 sudo[3549]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:06 smsl-n100 sudo[3555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 04 19:44:06 smsl-n100 sudo[3555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:06 smsl-n100 sudo[3555]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:06 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:06 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:06 smsl-n100 sudo[3559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 04 19:44:06 smsl-n100 sudo[3559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:06 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Asound.conf file written Jul 04 19:44:06 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:06 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Jul 04 19:44:06 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Jul 04 19:44:06 smsl-n100 systemd[1]: Started RAAT DAEMON. Jul 04 19:44:06 smsl-n100 sudo[3559]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:06 smsl-n100 sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 04 19:44:06 smsl-n100 sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:06 smsl-n100 sudo[3566]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:06 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:06 smsl-n100 volumio[3086]: info: Output device has changed, restarting MPD Jul 04 19:44:07 smsl-n100 sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 19:44:07 smsl-n100 sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Output device has changed, restarting Shairport Sync Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:07 smsl-n100 sudo[3577]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:07 smsl-n100 sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 19:44:07 smsl-n100 sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:07 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:07 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 04 19:44:07 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 04 19:44:07 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 04 19:44:07 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 19:44:07 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:07 smsl-n100 sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 19:44:07 smsl-n100 sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:07 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:07 smsl-n100 sudo[3585]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 19:44:07 smsl-n100 sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:07 smsl-n100 sudo[3585]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:07 smsl-n100 sudo[3589]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MPD Permissions set Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Raat Daemon started successfully Jul 04 19:44:07 smsl-n100 volumio[3086]: info: TidalConnect service stoped! Jul 04 19:44:07 smsl-n100 volumio[3086]: info: RAAT: Requesting Headphone Status Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Help! Some callbacks for volumioPushState are crashing! Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Cannot read property 'sendVolumeMute' of undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Help! Some callbacks for volumioPushState are crashing! Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Cannot read property 'sendVolumeMute' of undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:07 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:07 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Executing endpoint restartRAATSocket Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Not Reporting Auto name since its the default one Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:07 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:07 smsl-n100 volumio[3086]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:08 smsl-n100 sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 04 19:44:08 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 04 19:44:08 smsl-n100 sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Starting Shairport Sync Jul 04 19:44:08 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 04 19:44:08 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Jul 04 19:44:08 smsl-n100 sudo[3614]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:08 smsl-n100 sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 19:44:08 smsl-n100 sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:08 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 19:44:08 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:08 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 04 19:44:08 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:08 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 19:44:08 smsl-n100 sudo[3618]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:08 smsl-n100 sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 04 19:44:08 smsl-n100 sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:08 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:08 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:08 smsl-n100 sudo[3626]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Shairport-Sync Started Jul 04 19:44:08 smsl-n100 volumio[3086]: info: TidalConnect service stoped! Jul 04 19:44:08 smsl-n100 sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 04 19:44:08 smsl-n100 sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:08 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Jul 04 19:44:08 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:08 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Jul 04 19:44:08 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Jul 04 19:44:08 smsl-n100 systemd[1]: Started RAAT DAEMON. Jul 04 19:44:08 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:08 smsl-n100 sudo[3630]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:08 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Raat Daemon started successfully Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Executing endpoint tc_getconfig Jul 04 19:44:08 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 04 19:44:08 smsl-n100 sudo[3648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 04 19:44:08 smsl-n100 vtcs[3617]: STARTING TidalConnect services, version: 1.4.0.34 Jul 04 19:44:08 smsl-n100 sudo[3648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:08 smsl-n100 volumio[3086]: info: Executing endpoint restartRAATSocket Jul 04 19:44:08 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 04 19:44:09 smsl-n100 sudo[3648]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 04 19:44:09 smsl-n100 vtcs[3617]: STARTED TidalConnect services. Jul 04 19:44:09 smsl-n100 volumio[3086]: info: Executing endpoint tc_connect Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 04 19:44:09 smsl-n100 volumio[3086]: info: Connecting to TidalConnect Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 04 19:44:09 smsl-n100 volumio[3086]: verbose: CURRENT POSITION 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::syncState stateService stop Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::syncState currentStatus stop Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: No code Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 04 19:44:09 smsl-n100 volumio[3086]: verbose: CURRENT POSITION 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::syncState stateService stop Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::syncState currentStatus stop Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: No code Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: info: RAAT: Requesting Headphone Status Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:09 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:09 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:09 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:09 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:09 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:10 smsl-n100 volumio[3086]: info: TidalConnect service stoped! Jul 04 19:44:10 smsl-n100 volumio[3086]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:10 smsl-n100 volumio[3086]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 04 19:44:10 smsl-n100 sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 04 19:44:10 smsl-n100 sudo[3681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:10 smsl-n100 sudo[3681]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:11 smsl-n100 volumio[3086]: info: TidalConnect service started! Jul 04 19:44:11 smsl-n100 volumio[3086]: info: RAAT: Requesting Headphone Status Jul 04 19:44:11 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 04 19:44:11 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:11 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:11 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:11 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:11 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:11 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:11 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:11 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:11 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:11 smsl-n100 mpd[3592]: Jul 04 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 19:44:11 smsl-n100 systemd[1]: Started Music Player Daemon. Jul 04 19:44:11 smsl-n100 sudo[3579]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:11 smsl-n100 mpd_monitor.sh[504]: MPD restarted due to no mpc output. Jul 04 19:44:11 smsl-n100 volumio[3086]: error: MPD error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: MPD error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: MPD error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: The expression evaluated to a falsy value: Jul 04 19:44:11 smsl-n100 volumio[3086]: assert.ok(self.idling) Jul 04 19:44:11 smsl-n100 volumio[3086]: error: updateQueue error: null Jul 04 19:44:11 smsl-n100 volumio[3086]: error: updateQueue error: null Jul 04 19:44:12 smsl-n100 volumio[3086]: info: TidalConnect service started! Jul 04 19:44:13 smsl-n100 volumio[3086]: info: TidalConnect service started! Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 04 19:44:15 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 04 19:44:16 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 04 19:44:16 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 04 19:44:16 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:16 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:16 smsl-n100 volumio[3086]: info: Starting MyVolumio Remote Streaming Endpoints Jul 04 19:44:16 smsl-n100 volumio[3086]: info: MyVolumio login type: Token Jul 04 19:44:16 smsl-n100 volumio[3086]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 04 19:44:16 smsl-n100 volumio[3086]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jul 04 19:44:16 smsl-n100 volumio[3086]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 04 19:44:26 smsl-n100 volumio[3086]: error: MyVolumio Plugin failed to start in a timely fashion Jul 04 19:44:26 smsl-n100 volumio[3086]: [Metrics] CommandRouter: 56s 996.81ms Jul 04 19:44:26 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumiosetStartupVolume Jul 04 19:44:26 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:26 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:26 smsl-n100 volumio[3086]: info: CoreCommandRouter::Close All Modals sent Jul 04 19:44:26 smsl-n100 volumio[3086]: info: CoreCommandRouter::Close All Modals sent Jul 04 19:44:27 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 04 19:44:27 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 04 19:44:27 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:27 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 shairport-sync[3625]: Metadata queue is busy, dropping message of type 0x73736E63, code 0x70666672. Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Airplay started streaming, receiving metadatas Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioStop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::stop Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 19:44:28 smsl-n100 shairport-sync[3625]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Jul 04 19:44:28 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=11/SEGV Jul 04 19:44:28 smsl-n100 systemd[1]: shairport-sync.service: Failed with result 'signal'. Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Updating volume from AirPlay: -20; 33% Jul 04 19:44:28 smsl-n100 volumio[3086]: info: VolumeController::SetAlsaVolume33 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CorePlayQueue::getTrack 0 Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:28 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:28 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:28 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:28 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::servicePushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: Updating RAAT Signal Path Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:29 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:29 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:29 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:29 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:29 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:31 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 19:44:31 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 04 19:44:31 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:33 smsl-n100 volumio[3086]: info: BOOT COMPLETED Jul 04 19:44:34 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:34 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:34 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:34 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:34 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 19:44:35 smsl-n100 volumio[3086]: info: Not Reporting Auto name since its the default one Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 04 19:44:35 smsl-n100 volumio[3086]: xcb_connection_has_error() returned true Jul 04 19:44:35 smsl-n100 sudo[3798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 04 19:44:35 smsl-n100 sudo[3798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:35 smsl-n100 sudo[3798]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:35 smsl-n100 sudo[3800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 04 19:44:35 smsl-n100 sudo[3800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:44:35 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Jul 04 19:44:35 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 04 19:44:35 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:44:35 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Jul 04 19:44:35 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Jul 04 19:44:35 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 19:44:35 smsl-n100 systemd[1]: Started RAAT DAEMON. Jul 04 19:44:35 smsl-n100 sudo[3800]: pam_unix(sudo:session): session closed for user root Jul 04 19:44:35 smsl-n100 volumio[3086]: info: Raat Daemon started successfully Jul 04 19:44:35 smsl-n100 volumio[3086]: info: Executing endpoint restartRAATSocket Jul 04 19:44:35 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 04 19:44:35 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 19:44:36 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 19:44:38 smsl-n100 volumio[3086]: info: RAAT: Requesting Headphone Status Jul 04 19:44:38 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 04 19:44:38 smsl-n100 volumio[3086]: info: CoreStateMachine::pushState Jul 04 19:44:38 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 19:44:38 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioPushState Jul 04 19:44:38 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output update for this device Jul 04 19:44:38 smsl-n100 volumio[3086]: info: MRS: Pushing multiroomSync output Jul 04 19:44:38 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:38 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 04 19:44:38 smsl-n100 volumio[3086]: error: Serial API: Failed to decode command: 100, message: undefined Jul 04 19:44:38 smsl-n100 volumio[3086]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 04 19:44:46 smsl-n100 volumio[3086]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 04 19:44:46 smsl-n100 volumio[3086]: info: Retrying Login Due to Network Error Jul 04 19:44:53 smsl-n100 ntpd[940]: 193.182.111.141 local addr 192.168.31.100 -> Jul 04 19:44:55 smsl-n100 ntpd[940]: 84.16.67.12 local addr 192.168.31.100 -> Jul 04 19:44:56 smsl-n100 volumio[3086]: info: MyVolumio login type: Token Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 19:44:59 smsl-n100 volumio[3086]: info: Discovery: Getting this device information Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 19:44:59 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to 192.168.31.100:3000 from 192.168.31.30 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 19:44:59 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:00 smsl-n100 sudo[3849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 19:45:00 smsl-n100 sudo[3849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:00 smsl-n100 sudo[3849]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:00 smsl-n100 sudo[3852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 19:45:00 smsl-n100 sudo[3852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:00 smsl-n100 sudo[3852]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:01 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to 192.168.31.100 from 192.168.31.30 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 19:45:01 smsl-n100 sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 19:45:01 smsl-n100 sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:01 smsl-n100 sudo[3855]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:01 smsl-n100 sudo[3858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 19:45:01 smsl-n100 sudo[3858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:01 smsl-n100 sudo[3858]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:01 smsl-n100 volumio[3086]: verbose: New Socket.io Connection to 192.168.31.100 from 192.168.31.30 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 19:45:01 smsl-n100 volumio[3086]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 04 19:45:01 smsl-n100 volumio[3086]: info: Received Get System Info Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 19:45:01 smsl-n100 volumio[3086]: info: Discovery: Getting this device information Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:01 smsl-n100 volumio[3086]: info: Listing playlists Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 19:45:01 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 19:45:03 smsl-n100 volumio[3086]: info: Received Get System Info Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 19:45:03 smsl-n100 volumio[3086]: info: Discovery: Getting this device information Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 19:45:03 smsl-n100 volumio[3086]: info: Discovery: Getting this device information Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:03 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 19:45:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 19:45:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 04 19:45:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 04 19:45:07 smsl-n100 sudo[3881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 04 19:45:07 smsl-n100 sudo[3881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3881]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:07 smsl-n100 sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 04 19:45:07 smsl-n100 sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3887]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:07 smsl-n100 sudo[3894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 04 19:45:07 smsl-n100 sudo[3894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3894]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:07 smsl-n100 sudo[3900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 04 19:45:07 smsl-n100 sudo[3900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3900]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:07 smsl-n100 sudo[3907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 19:45:07 smsl-n100 sudo[3907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3907]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 04 19:45:07 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 04 19:45:07 smsl-n100 sudo[3910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 19:45:07 smsl-n100 sudo[3910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:07 smsl-n100 sudo[3910]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:11 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 19:45:12 smsl-n100 volumio[3086]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jul 04 19:45:12 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jul 04 19:45:12 smsl-n100 sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jul 04 19:45:12 smsl-n100 sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:12 smsl-n100 sudo[3916]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:12 smsl-n100 sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jul 04 19:45:12 smsl-n100 sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:12 smsl-n100 sudo[3918]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:12 smsl-n100 volumio[3086]: info: Permissions for /etc/network/interfaces set Jul 04 19:45:12 smsl-n100 volumio[3086]: info: Restarting Wireless Network Jul 04 19:45:12 smsl-n100 volumio[3086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jul 04 19:45:12 smsl-n100 volumio[3086]: info: Discovery: Restarting Advertising due to device name change Jul 04 19:45:12 smsl-n100 sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jul 04 19:45:12 smsl-n100 sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:12 smsl-n100 systemd[1]: Starting Wireless Services... Jul 04 19:45:12 smsl-n100 volumio[3086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 19:45:13 smsl-n100 wireless.js[3923]: Cleaning previous... Jul 04 19:45:13 smsl-n100 volumio[3086]: info: Discovery: A device disappeared from network Jul 04 19:45:13 smsl-n100 volumio[3086]: info: Discovery: Device smsl-n100 disappeared from network Jul 04 19:45:13 smsl-n100 sudo[3938]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 04 19:45:13 smsl-n100 sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:13 smsl-n100 sudo[3938]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:13 smsl-n100 sudo[3940]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 04 19:45:13 smsl-n100 sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:13 smsl-n100 sudo[3940]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:13 smsl-n100 wireless.js[3923]: Stopped aP Jul 04 19:45:13 smsl-n100 sudo[3947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 04 19:45:13 smsl-n100 sudo[3947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:14 smsl-n100 dhcpcd[597]: wlan0: carrier acquired Jul 04 19:45:14 smsl-n100 dhcpcd[597]: wlan0: confile file changed, expiring leases Jul 04 19:45:14 smsl-n100 sudo[3947]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:14 smsl-n100 dhcpcd[597]: wlan0: IAID 2b:ee:c8:f8 Jul 04 19:45:14 smsl-n100 dhcpcd[597]: wlan0: soliciting an IPv6 router Jul 04 19:45:14 smsl-n100 sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 04 19:45:14 smsl-n100 sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:14 smsl-n100 sudo[3954]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:14 smsl-n100 dhcpcd[597]: wlan0: soliciting a DHCP lease Jul 04 19:45:14 smsl-n100 sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 04 19:45:14 smsl-n100 sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:14 smsl-n100 sudo[3971]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:14 smsl-n100 sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 04 19:45:14 smsl-n100 sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:17 smsl-n100 volumio[3086]: info: Discovery: Started advertising with name: smsl-n100 Jul 04 19:45:18 smsl-n100 volumio[3086]: info: Discovery: adding 9b606f0f-8672-4094-a872-63bc2b8e06d3 Jul 04 19:45:18 smsl-n100 volumio[3086]: info: Discovery: Found device smsl-n100 Jul 04 19:45:18 smsl-n100 volumio[3086]: info: CoreCommandRouter::volumioGetState Jul 04 19:45:18 smsl-n100 volumio[3086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 19:45:18 smsl-n100 volumio[3086]: Error: The value NaN is not a number Jul 04 19:45:18 smsl-n100 volumio[3086]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 04 19:45:18 smsl-n100 volumio[3086]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 04 19:45:18 smsl-n100 volumio[3086]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 04 19:45:18 smsl-n100 volumio[3086]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 04 19:45:18 smsl-n100 volumio[3086]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 04 19:45:18 smsl-n100 volumio[3086]: at Browser.emit (events.js:400:28) Jul 04 19:45:18 smsl-n100 volumio[3086]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 04 19:45:18 smsl-n100 volumio[3086]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 04 19:45:18 smsl-n100 volumio[3086]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 04 19:45:18 smsl-n100 volumio[3086]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 04 19:45:18 smsl-n100 volumio[3086]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 04 19:45:18 smsl-n100 volumio[3086]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 04 19:45:18 smsl-n100 volumio[3086]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 04 19:45:18 smsl-n100 volumio[3086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 19:45:19 smsl-n100 dhcpcd[597]: wlan0: probing for an IPv4LL address Jul 04 19:45:19 smsl-n100 sudo[3973]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:19 smsl-n100 wireless.js[3923]: Start wireless flow Jul 04 19:45:19 smsl-n100 wireless.js[3923]: Stopped hotspot (if there).. Jul 04 19:45:19 smsl-n100 sudo[3988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 19:44 Jul 04 19:45:19 smsl-n100 sudo[3988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:19 smsl-n100 sudo[3990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 04 19:45:19 smsl-n100 sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:19 smsl-n100 sudo[3990]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:19 smsl-n100 sudo[3993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 04 19:45:19 smsl-n100 sudo[3993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 19:45:19 smsl-n100 dhcpcd[597]: wlan0: carrier lost Jul 04 19:45:19 smsl-n100 sudo[3993]: pam_unix(sudo:session): session closed for user root Jul 04 19:45:19 smsl-n100 wireless.js[3923]: DHCP IP Jul 04 19:45:19 smsl-n100 wireless.js[3923]: Start ap Jul 04 19:45:19 smsl-n100 wpa_supplicant[3999]: Successfully initialized wpa_supplicant 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"