-- Logs begin at Thu 2025-09-18 16:53:00 NZST, end at Thu 2025-09-18 17:50:13 NZST. -- Sep 18 17:49:00 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:00 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:00 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:00 smsl-n100 volumio[951]: info: TidalConnect service started! Sep 18 17:49:00 smsl-n100 volumio[951]: info: Completed starting Core Plugins Sep 18 17:49:00 smsl-n100 volumio[951]: info: ------------------------------------------- Sep 18 17:49:00 smsl-n100 volumio[951]: info: ----- MyVolumio plugins startup ---- Sep 18 17:49:00 smsl-n100 volumio[951]: info: ------------------------------------------- Sep 18 17:49:00 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 18 17:49:00 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:00 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:00 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:00 smsl-n100 volumio[1211]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 18 17:49:02 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:02 smsl-n100 volumio[1211]: ................++++ Sep 18 17:49:02 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:02 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:03 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:03 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:03 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:04 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:04 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:04 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:06 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:06 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:06 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:07 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:07 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:07 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:07 smsl-n100 volumio[1211]: ......................................................................++++ Sep 18 17:49:07 smsl-n100 volumio[1211]: e is 65537 (0x010001) Sep 18 17:49:07 smsl-n100 volumio[1211]: writing RSA key Sep 18 17:49:08 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:08 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:08 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 18 17:49:09 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 18 17:49:11 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 18 17:49:11 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 18 17:49:11 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 17:49:11 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 17:49:11 smsl-n100 volumio[951]: info: Starting MyVolumio Remote Streaming Endpoints Sep 18 17:49:11 smsl-n100 volumio[951]: info: MyVolumio not started Sep 18 17:49:11 smsl-n100 volumio[951]: info: Initializing device activation check Sep 18 17:49:11 smsl-n100 volumio[951]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 18 17:49:11 smsl-n100 volumio[951]: 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' Sep 18 17:49:11 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:11 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:11 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:12 smsl-n100 volumio[951]: info: MPD Database updated - AlbumList cache refreshed Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: database Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: update Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: MPD Database updated - AlbumList cache refreshed Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: database Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: update Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: MPD Database updated - AlbumList cache refreshed Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: database Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: Sep 18 17:49:12 smsl-n100 volumio[951]: ---------------------------- MPD announces state update: update Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: ControllerMpd::getState Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::sendMpdCommand status Sep 18 17:49:12 smsl-n100 volumio[951]: info: sendMpdCommand status took 144 milliseconds Sep 18 17:49:12 smsl-n100 volumio[951]: info: sendMpdCommand status took 141 milliseconds Sep 18 17:49:12 smsl-n100 volumio[951]: info: sendMpdCommand status took 138 milliseconds Sep 18 17:49:12 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: Command Router : Notfying DB Updatefalse Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::Close All Modals sent Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 376ms Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 375ms Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 446 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 442 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 442 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: Command Router : Notfying DB Updatefalse Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::Close All Modals sent Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 671ms Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 672ms Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 700 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 697 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: info: sendMpdCommand status took 695 milliseconds Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: Command Router : Notfying DB Updatefalse Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::Close All Modals sent Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: ControllerMpd::parseState Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ControllerMpd::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::servicePushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: CURRENT POSITION 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState stateService stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::syncState currentStatus stop Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: No code Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:13 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 891ms Sep 18 17:49:13 smsl-n100 volumio[951]: info: ------------------------------ 891ms Sep 18 17:49:13 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:13 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:13 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:14 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:14 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:14 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:15 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:15 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:15 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:16 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:16 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:16 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:17 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:17 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:17 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:19 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 18 17:49:19 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:19 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:20 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:20 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:20 smsl-n100 volumio[951]: error: MyVolumio Plugin failed to start in a timely fashion Sep 18 17:49:20 smsl-n100 volumio[951]: [Metrics] CommandRouter: 64s 500.94ms Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::volumiosetStartupVolume Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::Close All Modals sent Sep 18 17:49:20 smsl-n100 volumio[951]: info: CoreCommandRouter::Close All Modals sent Sep 18 17:49:20 smsl-n100 volumio[951]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Sep 18 17:49:20 smsl-n100 volumio[951]: xcb_connection_has_error() returned true Sep 18 17:49:20 smsl-n100 volumio[951]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Sep 18 17:49:20 smsl-n100 volumio[951]: 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 Sep 18 17:49:20 smsl-n100 volumio[951]: aplay: main:828: audio open error: No such device Sep 18 17:49:21 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:21 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:21 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:21 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 18 17:49:21 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 18 17:49:21 smsl-n100 volumio[951]: xcb_connection_has_error() returned true Sep 18 17:49:21 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 18 17:49:21 smsl-n100 volumio[951]: info: Stopping RAAT Plugin Sep 18 17:49:21 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 18 17:49:21 smsl-n100 sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Sep 18 17:49:21 smsl-n100 sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 17:49:21 smsl-n100 sudo[1531]: pam_unix(sudo:session): session closed for user root Sep 18 17:49:21 smsl-n100 volumio[951]: info: Raat Daemon stopped successfully Sep 18 17:49:22 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:22 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 17:49:22 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 17:49:22 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 18 17:49:22 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:22 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:22 smsl-n100 volumio-remote-updater[543]: No test mode Sep 18 17:49:22 smsl-n100 volumio-remote-updater[543]: No alpha test mode Sep 18 17:49:22 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 18 17:49:23 smsl-n100 volumio[951]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 18 17:49:23 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 18 17:49:23 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:23 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:23 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:25 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:25 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:25 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:25 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:25 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:25 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:26 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 18 17:49:26 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:26 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:26 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:26 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:26 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:26 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:27 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded. Sep 18 17:49:27 smsl-n100 volumio[951]: info: BOOT COMPLETED Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 17:49:27 smsl-n100 volumio[951]: info: Not Reporting Auto name since its the default one Sep 18 17:49:27 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 18 17:49:28 smsl-n100 volumio[951]: xcb_connection_has_error() returned true Sep 18 17:49:28 smsl-n100 volumio[951]: info: Configured Audio card not found, not starting RAAT Sep 18 17:49:28 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:28 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:28 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:29 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:29 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:29 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:30 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:30 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:30 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:31 smsl-n100 volumio[951]: info: RAAT: Requesting Headphone Status Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:31 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:31 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:31 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:31 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:49:31 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 18 17:49:31 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:49:31 smsl-n100 volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 18 17:49:31 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:31 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetBrowseSources Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 17:49:31 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:31 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:31 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:32 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:33 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:33 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:34 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:34 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:34 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:35 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:35 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:35 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:36 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b0:e4:d5:28:d1:81 reason=5 Sep 18 17:49:36 smsl-n100 dhcpcd[632]: wlan0: carrier lost Sep 18 17:49:36 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 18 17:49:36 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=AU Sep 18 17:49:36 smsl-n100 avahi-daemon[548]: Withdrawing address record for 192.168.86.31 on wlan0. Sep 18 17:49:36 smsl-n100 avahi-daemon[548]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.31. Sep 18 17:49:36 smsl-n100 avahi-daemon[548]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 18 17:49:36 smsl-n100 dhcpcd[632]: wlan0: deleting route to 192.168.86.0/24 Sep 18 17:49:36 smsl-n100 dhcpcd[632]: wlan0: deleting default route via 192.168.86.1 Sep 18 17:49:36 smsl-n100 volumio[951]: info: Discovery: A device disappeared from network Sep 18 17:49:36 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:36 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:36 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:37 smsl-n100 wpa_supplicant[841]: wlan0: Trying to associate with b0:e4:d5:28:d1:85 (SSID='Jiang-Family' freq=2462 MHz) Sep 18 17:49:37 smsl-n100 wpa_supplicant[841]: wlan0: Associated with b0:e4:d5:28:d1:85 Sep 18 17:49:37 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 18 17:49:37 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU Sep 18 17:49:38 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:38 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:38 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:38 smsl-n100 ntpd[946]: Deleting interface #3 wlan0, 192.168.86.31#123, interface stats: received=145, sent=145, dropped=10, active_time=87 secs Sep 18 17:49:38 smsl-n100 ntpd[946]: 103.239.8.20 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 192.107.172.19 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 103.158.134.33 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 103.242.70.4 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 114.110.37.123 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 162.159.200.1 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 192.107.172.20 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 132.181.2.72 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 103.242.70.5 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 45.15.140.68 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 203.190.214.199 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 103.242.68.68 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 162.159.200.123 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 192.107.172.18 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 202.124.96.215 local addr 192.168.86.31 -> Sep 18 17:49:38 smsl-n100 ntpd[946]: 203.114.153.177 local addr 192.168.86.31 -> Sep 18 17:49:39 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:39 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:39 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:40 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:40 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:40 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:41 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:41 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:41 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:41 smsl-n100 volumio[951]: info: Reporting MCU Network Status: 0 Sep 18 17:49:41 smsl-n100 volumio[951]: info: Volumio Network Manager: Network status updated: 0 Sep 18 17:49:41 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:49:41 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:41 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:49:41 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:49:41 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:49:41 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:49:41 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 18 17:49:41 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:49:41 smsl-n100 volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 18 17:49:41 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 0, message: undefined Sep 18 17:49:42 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:42 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:42 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:44 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:49:44 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:44 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:45 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:45 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:45 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:46 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:46 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:46 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:48 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:48 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:48 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:49 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:49 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:49 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:49 smsl-n100 wpa_supplicant[841]: wlan0: Authentication with b0:e4:d5:28:d1:85 timed out. Sep 18 17:49:50 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b0:e4:d5:28:d1:85 reason=3 locally_generated=1 Sep 18 17:49:50 smsl-n100 wpa_supplicant[841]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Sep 18 17:49:50 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Jiang-Family" auth_failures=1 duration=10 reason=WRONG_KEY Sep 18 17:49:50 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 18 17:49:50 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=AU Sep 18 17:49:50 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:50 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:50 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:51 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:51 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:51 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:52 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:52 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:52 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:53 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:53 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:53 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:54 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:54 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:54 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:55 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:55 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:55 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:56 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:56 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:56 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:57 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:57 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:57 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:49:58 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:49:58 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:49:58 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:00 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:00 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:00 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:01 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:01 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:01 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:03 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:03 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:03 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:04 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:04 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:04 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Jiang-Family" Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: Trying to associate with b0:e4:d5:28:d1:81 (SSID='Jiang-Family' freq=5745 MHz) Sep 18 17:50:05 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: Associated with b0:e4:d5:28:d1:81 Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: WPA: Key negotiation completed with b0:e4:d5:28:d1:81 [PTK=CCMP GTK=CCMP] Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:e4:d5:28:d1:81 completed [id=0 id_str=] Sep 18 17:50:05 smsl-n100 dhcpcd[632]: wlan0: carrier acquired Sep 18 17:50:05 smsl-n100 dhcpcd[632]: wlan0: IAID 2b:ee:c9:f9 Sep 18 17:50:05 smsl-n100 wpa_supplicant[841]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU Sep 18 17:50:05 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:05 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:05 smsl-n100 dhcpcd[632]: wlan0: rebinding lease of 192.168.86.31 Sep 18 17:50:05 smsl-n100 dhcpcd[632]: wlan0: probing address 192.168.86.31/24 Sep 18 17:50:05 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:05 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:05 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:05 smsl-n100 dhcpcd[632]: wlan0: soliciting an IPv6 router Sep 18 17:50:06 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:06 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:06 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:07 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:07 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:07 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:08 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:08 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:08 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:09 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:09 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:09 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:10 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:10 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:10 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:11 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:11 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:11 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:11 smsl-n100 dhcpcd[632]: wlan0: leased 192.168.86.31 for 86400 seconds Sep 18 17:50:11 smsl-n100 dhcpcd[632]: wlan0: adding route to 192.168.86.0/24 Sep 18 17:50:11 smsl-n100 dhcpcd[632]: wlan0: adding default route via 192.168.86.1 Sep 18 17:50:11 smsl-n100 avahi-daemon[548]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.31. Sep 18 17:50:11 smsl-n100 avahi-daemon[548]: New relevant interface wlan0.IPv4 for mDNS. Sep 18 17:50:11 smsl-n100 avahi-daemon[548]: Registering new address record for 192.168.86.31 on wlan0.IPv4. Sep 18 17:50:11 smsl-n100 ntpd[946]: ntpd exiting on signal 15 (Terminated) Sep 18 17:50:11 smsl-n100 systemd[1]: Stopping Network Time Service... Sep 18 17:50:11 smsl-n100 systemd[1]: ntp.service: Succeeded. Sep 18 17:50:11 smsl-n100 systemd[1]: Stopped Network Time Service. Sep 18 17:50:11 smsl-n100 systemd[1]: Starting Network Time Service... Sep 18 17:50:11 smsl-n100 volumio[951]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 18 17:50:11 smsl-n100 volumio[951]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 18 17:50:11 smsl-n100 volumio[951]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 18 17:50:11 smsl-n100 ntpd[1718]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 18 17:50:11 smsl-n100 ntpd[1718]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 18 17:50:11 smsl-n100 systemd[1]: Started Network Time Service. Sep 18 17:50:11 smsl-n100 ntpd[1725]: proto: precision = 0.875 usec (-20) Sep 18 17:50:11 smsl-n100 ntpd[1725]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 18 17:50:11 smsl-n100 ntpd[1725]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 18 17:50:11 smsl-n100 volumio[951]: verbose: New Socket.io Connection to 192.168.86.31 from 192.168.86.35 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 4 Sep 18 17:50:11 smsl-n100 ntpd[1725]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 996 days ago Sep 18 17:50:11 smsl-n100 ntpd[1725]: Listen and drop on 0 v6wildcard [::]:123 Sep 18 17:50:11 smsl-n100 ntpd[1725]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 18 17:50:11 smsl-n100 ntpd[1725]: Listen normally on 2 lo 127.0.0.1:123 Sep 18 17:50:11 smsl-n100 ntpd[1725]: Listen normally on 3 wlan0 192.168.86.31:123 Sep 18 17:50:11 smsl-n100 ntpd[1725]: Listening on routing socket on fd #20 for interface updates Sep 18 17:50:11 smsl-n100 ntpd[1725]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Sep 18 17:50:11 smsl-n100 ntpd[1725]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Sep 18 17:50:12 smsl-n100 volumio[951]: info: Reporting MCU Network Status: 2 Sep 18 17:50:12 smsl-n100 volumio[951]: info: Volumio Network Manager: Network status updated: 2 Sep 18 17:50:12 smsl-n100 volumio[951]: info: MRS: Found cast device: Nest-Wifi-point-3487f5dbd0f1ee2eb658e8981041780e Sep 18 17:50:12 smsl-n100 volumio[951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 18 17:50:12 smsl-n100 volumio[951]: info: CoreStateMachine::pushState Sep 18 17:50:12 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:12 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioPushState Sep 18 17:50:12 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output update for this device Sep 18 17:50:12 smsl-n100 volumio[951]: info: MRS: Pushing multiroomSync output Sep 18 17:50:12 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:50:12 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 18 17:50:12 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Sep 18 17:50:12 smsl-n100 volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 18 17:50:12 smsl-n100 volumio[951]: error: Serial API: Failed to decode command: 2, message: undefined Sep 18 17:50:12 smsl-n100 volumio[951]: info: MRS: Found cast device: Chromecast-HD-3f5c2e05c6b4aafb50e1e2d2f4e6131f Sep 18 17:50:12 smsl-n100 volumio[951]: info: MRS: Found cast device: Chromecast-b32674d852551e964121296a229c3ce8 Sep 18 17:50:12 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:12 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:12 smsl-n100 volumio[951]: info: Discovery: adding 113dcf8c-e10b-4330-8daf-9119c5f1c4b6 Sep 18 17:50:12 smsl-n100 volumio[951]: info: Discovery: Found device SMSL N100 Sep 18 17:50:12 smsl-n100 volumio[951]: info: CoreCommandRouter::volumioGetState Sep 18 17:50:12 smsl-n100 volumio[951]: info: CorePlayQueue::getTrack 0 Sep 18 17:50:12 smsl-n100 volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 17:50:12 smsl-n100 volumio[951]: Error: The value NaN is not a number Sep 18 17:50:12 smsl-n100 volumio[951]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Sep 18 17:50:12 smsl-n100 volumio[951]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Sep 18 17:50:12 smsl-n100 volumio[951]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Sep 18 17:50:12 smsl-n100 volumio[951]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Sep 18 17:50:12 smsl-n100 volumio[951]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 18 17:50:12 smsl-n100 volumio[951]: at Browser.emit (events.js:400:28) Sep 18 17:50:12 smsl-n100 volumio[951]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 18 17:50:12 smsl-n100 volumio[951]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 18 17:50:12 smsl-n100 volumio[951]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 18 17:50:12 smsl-n100 volumio[951]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 18 17:50:12 smsl-n100 volumio[951]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Sep 18 17:50:12 smsl-n100 volumio[951]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Sep 18 17:50:12 smsl-n100 volumio[951]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Sep 18 17:50:12 smsl-n100 volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 17:50:13 smsl-n100 ntpd[1725]: Soliciting pool server 203.114.153.177 Sep 18 17:50:13 smsl-n100 sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-18 17:49 Sep 18 17:50:13 smsl-n100 sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="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"