-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sun 2025-11-02 10:38:27 EST. -- Nov 02 10:37:00 smsl-n100 sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 10:37:00 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 10:37:00 smsl-n100 sudo[1659]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:00 smsl-n100 sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:37:00 smsl-n100 sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 10:37:00 smsl-n100 sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:37:00 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 10:37:00 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Nov 02 10:37:00 smsl-n100 volumio[1204]: Upnp client error: Error: This socket has been ended by the other party Nov 02 10:37:00 smsl-n100 volumio[1204]: Upnp client error: Error: This socket has been ended by the other party Nov 02 10:37:00 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 10:37:00 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 10:37:00 smsl-n100 sudo[1663]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:00 smsl-n100 sudo[1666]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:00 smsl-n100 dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 02 10:37:00 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetState Nov 02 10:37:00 smsl-n100 systemd[1]: Started Hostname Service. Nov 02 10:37:00 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:00 smsl-n100 volumio[1204]: info: MRS: Found cast device: WiiM-Pro-Receiver-8259f4ff45a3fdcd9961fe12eead90f1 Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Adding audio output: Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:37:00 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 10:37:00 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetState Nov 02 10:37:00 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:00 smsl-n100 volumio[1204]: info: MPD running with PID1521 Nov 02 10:37:00 smsl-n100 volumio[1204]: ,establishing connection Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Shairport-Sync Started Nov 02 10:37:00 smsl-n100 volumio[1204]: Error adding Membership: Error: addMembership EINVAL Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Shairport-Sync Started Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Shairport-Sync Started Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 10:37:00 smsl-n100 volumio[1204]: info: MRS: Found cast device: VisionMaster-6a61c76bc8caf95267a564b5869f670c Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Adding audio output: Nov 02 10:37:00 smsl-n100 volumio[1204]: info: Successfully retrieved User Session From TIDAL Nov 02 10:37:00 smsl-n100 sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 10:37:00 smsl-n100 sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:00 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Nov 02 10:37:00 smsl-n100 sudo[1678]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:00 smsl-n100 volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Nov 02 10:37:00 smsl-n100 volumio[1204]: assert.ok(self.idling) Nov 02 10:37:01 smsl-n100 volumio[1204]: error: The expression evaluated to a falsy value: Nov 02 10:37:01 smsl-n100 volumio[1204]: assert.ok(self.idling) Nov 02 10:37:01 smsl-n100 volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Nov 02 10:37:01 smsl-n100 volumio[1204]: assert.ok(self.idling) Nov 02 10:37:01 smsl-n100 volumio[1204]: error: The expression evaluated to a falsy value: Nov 02 10:37:01 smsl-n100 volumio[1204]: assert.ok(self.idling) Nov 02 10:37:01 smsl-n100 volumio[1204]: info: [1762097821211] [Pandora] checkPing: ***LOOKS GOOD, ICE DOWN THE BEERS IN THE COOLER*** Nov 02 10:37:01 smsl-n100 volumio[1204]: info: [1762097821214] ControllerPandora::checkHTTP Nov 02 10:37:01 smsl-n100 volumio[1204]: info: go-librespot daemon successfully initialized Nov 02 10:37:01 smsl-n100 volumio[1204]: error: updateQueue error: null Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CoreStateMachine::pushState Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushState Nov 02 10:37:01 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Nov 02 10:37:01 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output Nov 02 10:37:01 smsl-n100 volumio[1204]: error: Help! Some callbacks for volumioPushState are crashing! Nov 02 10:37:01 smsl-n100 volumio[1204]: error: Cannot read property 'sendVolumeMute' of undefined Nov 02 10:37:01 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:01 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 10:37:01 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:01 smsl-n100 volumio[1204]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Successfully retrieved User Subscription From TIDAL Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Adding TIDAL to Browse Sources Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 10:37:01 smsl-n100 volumio[1204]: info: [1762097821580] CoreMusicLibrary::Adding element TIDAL Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source Calm Radio Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source Mixcloud Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source SoundCloud Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source Mother Earth Radio Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source Radio Nowy Swiat Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source Pandora Radio Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source RADIO 357 Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source QOBUZ Nov 02 10:37:01 smsl-n100 volumio[1204]: Cannot find translation for source TIDAL Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Stopping AccessToken refresher cron Nov 02 10:37:01 smsl-n100 volumio[1204]: info: AccessToken refresher cron started Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Adding TIDAL REST API Endpoints Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 02 10:37:01 smsl-n100 volumio[1204]: info: Executing endpoint tc_getconfig Nov 02 10:37:01 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 10:37:01 smsl-n100 volumio[1204]: info: MRS: Found cast device: Chromecast-Ultra-7d010674dbf25d7f86c934f2f644653d Nov 02 10:37:01 smsl-n100 vtcs[1680]: STARTING TidalConnect services, version: 1.4.0.34 Nov 02 10:37:01 smsl-n100 volumio[1204]: info: MRS: Found cast device: VisionMaster-6a61c76bc8caf95267a564b5869f670c Nov 02 10:37:02 smsl-n100 vtcs[1680]: STARTED TidalConnect services. Nov 02 10:37:02 smsl-n100 volumio[1204]: info: Executing endpoint tc_connect Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 10:37:02 smsl-n100 volumio[1204]: info: Connecting to TidalConnect Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreCommandRouter::servicePushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreStateMachine::pushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Nov 02 10:37:02 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:02 smsl-n100 volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received tidalconnect Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreCommandRouter::servicePushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreStateMachine::pushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushState Nov 02 10:37:02 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Nov 02 10:37:02 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output Nov 02 10:37:02 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:02 smsl-n100 volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received tidalconnect Nov 02 10:37:03 smsl-n100 volumio[1492]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 02 10:37:03 smsl-n100 volumio[1204]: info: TidalConnect service started! Nov 02 10:37:04 smsl-n100 volumio[1204]: info: Initializing connection to go-librespot Websocket Nov 02 10:37:04 smsl-n100 go-librespot[1593]: time="2025-11-02T10:37:04-05:00" level=debug msg="new websocket client" Nov 02 10:37:04 smsl-n100 volumio[1204]: info: Connection to go-librespot Websocket established Nov 02 10:37:05 smsl-n100 volumio[1204]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826388] [Pandora] checkHTTP: ***CRACK THE BEERS WE HAVE INTERNET*** Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826390] ControllerPandora::initializeMQTT Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826391] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826393] ControllerPandora::PandoraHandler::init Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826395] ControllerPandora::PandoraHandler::loadErrorCodes Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826400] ControllerPandora::validateAndSetAccountOptions Nov 02 10:37:06 smsl-n100 volumio[1204]: error: [1762097826403] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826407] [Pandora] PandoraHandler::setMaxStationTracks: 16 Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826410] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826412] ControllerPandora::flushPandora Nov 02 10:37:06 smsl-n100 volumio[1204]: info: CorePlayQueue::getQueue Nov 02 10:37:06 smsl-n100 volumio[1204]: info: CorePlayQueue::clearAddPlayQueue Nov 02 10:37:06 smsl-n100 volumio[1204]: info: CorePlayQueue::saveQueue Nov 02 10:37:06 smsl-n100 volumio[1204]: info: CoreCommandRouter::serviceClearAddPlayTracks Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [1762097826421] ControllerCalmRadio::clearAddPlayTracks Nov 02 10:37:06 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushQueue Nov 02 10:37:06 smsl-n100 volumio[1204]: info: Completed starting Core Plugins Nov 02 10:37:06 smsl-n100 volumio[1204]: info: ------------------------------------------- Nov 02 10:37:06 smsl-n100 volumio[1204]: info: ----- MyVolumio plugins startup ---- Nov 02 10:37:06 smsl-n100 volumio[1204]: info: ------------------------------------------- Nov 02 10:37:06 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 10:37:07 smsl-n100 volumio[1204]: info: Getting Spotify volume Nov 02 10:37:07 smsl-n100 volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 02 10:37:07 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetState Nov 02 10:37:07 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:10 smsl-n100 volumio[1492]: .....................................................................................................++++ Nov 02 10:37:11 smsl-n100 volumio[1492]: .....++++ Nov 02 10:37:11 smsl-n100 volumio[1492]: e is 65537 (0x010001) Nov 02 10:37:11 smsl-n100 volumio[1492]: writing RSA key Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 10:37:14 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 10:37:17 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 10:37:17 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 10:37:17 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:17 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:17 smsl-n100 volumio[1204]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 10:37:17 smsl-n100 volumio[1204]: info: MyVolumio login type: Token Nov 02 10:37:17 smsl-n100 volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 10:37:17 smsl-n100 volumio[1204]: 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' Nov 02 10:37:17 smsl-n100 volumio[1204]: error: MyVolumio Custom Token format not valid, refreshing it Nov 02 10:37:17 smsl-n100 volumio[1204]: info: MyVolumio login type: Token Nov 02 10:37:18 smsl-n100 volumio[1204]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 10:37:18 smsl-n100 volumio[1204]: info: MyVolumio token set successfully Nov 02 10:37:18 smsl-n100 volumio[1204]: info: MYVOLUMIO: Adding device Nov 02 10:37:18 smsl-n100 volumio[1204]: info: MYVOLUMIO: Evaluating Server Nov 02 10:37:19 smsl-n100 volumio[1204]: info: MyVolumio status changed Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Streaming services startup Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Starting Streaming Daemon Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Removing audio output: Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Stoppping Tunnel 1 Nov 02 10:37:19 smsl-n100 sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 10:37:19 smsl-n100 sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:19 smsl-n100 sudo[1792]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:19 smsl-n100 sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 10:37:19 smsl-n100 sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:19 smsl-n100 volumio[1204]: error: Cannot start Volumio Streaming Daemon Nov 02 10:37:19 smsl-n100 volumio[1204]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 10:37:19 smsl-n100 volumio[1204]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 10:37:19 smsl-n100 sudo[1795]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Remote SSH Stopped Nov 02 10:37:19 smsl-n100 volumio[1204]: info: Setting Geolocation for MyVolumio to us1 Nov 02 10:37:19 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:19 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:19 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:19 smsl-n100 volumio[1204]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 10:37:20 smsl-n100 volumio[1204]: info: Updating MyVolumio device info Nov 02 10:37:20 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:21 smsl-n100 volumio[1204]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 10:37:23 smsl-n100 volumio[1204]: info: MYVOLUMIO: Adding device Nov 02 10:37:23 smsl-n100 volumio[1204]: info: MYVOLUMIO: Evaluating Server Nov 02 10:37:24 smsl-n100 volumio[1204]: info: Setting Geolocation for MyVolumio to us1 Nov 02 10:37:24 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:24 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:24 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 10:37:25 smsl-n100 volumio-remote-updater[497]: No test mode Nov 02 10:37:25 smsl-n100 volumio-remote-updater[497]: No alpha test mode Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 10:37:25 smsl-n100 volumio[1204]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 10:37:25 smsl-n100 volumio[1204]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 10:37:25 smsl-n100 volumio[1204]: info: Updating MyVolumio device info Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:25 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:26 smsl-n100 volumio[1204]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 10:37:26 smsl-n100 volumio[1204]: error: MyVolumio Plugin failed to start in a timely fashion Nov 02 10:37:26 smsl-n100 volumio[1204]: [Metrics] CommandRouter: 84s 335.91ms Nov 02 10:37:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 10:37:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 10:37:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:37:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:37:27 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 10:37:27 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 10:37:27 smsl-n100 volumio[1204]: xcb_connection_has_error() returned true Nov 02 10:37:27 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 10:37:30 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded. Nov 02 10:37:32 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 10:37:32 smsl-n100 volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 02 10:37:32 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetState Nov 02 10:37:32 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:33 smsl-n100 volumio[1204]: info: BOOT COMPLETED Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 10:37:35 smsl-n100 volumio[1204]: info: Not Reporting Auto name since its the default one Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 02 10:37:35 smsl-n100 volumio[1204]: xcb_connection_has_error() returned true Nov 02 10:37:35 smsl-n100 sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 02 10:37:35 smsl-n100 sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:35 smsl-n100 sudo[1886]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:35 smsl-n100 sudo[1888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 02 10:37:35 smsl-n100 sudo[1888]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 10:37:35 smsl-n100 systemd[1]: Started RAAT DAEMON. Nov 02 10:37:35 smsl-n100 sudo[1888]: pam_unix(sudo:session): session closed for user root Nov 02 10:37:35 smsl-n100 volumio[1204]: info: Raat Daemon started successfully Nov 02 10:37:35 smsl-n100 volumio[1204]: info: Executing endpoint restartRAATSocket Nov 02 10:37:35 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 02 10:37:36 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:36 smsl-n100 volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 10:37:37 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 10:37:38 smsl-n100 volumio[1204]: info: RAAT: Requesting Headphone Status Nov 02 10:37:38 smsl-n100 volumio[1204]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 02 10:37:38 smsl-n100 volumio[1204]: info: CoreStateMachine::pushState Nov 02 10:37:38 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:38 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushState Nov 02 10:37:38 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Nov 02 10:37:38 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output Nov 02 10:37:38 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:38 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 10:37:38 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:38 smsl-n100 volumio[1204]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 10:37:51 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Nov 02 10:37:51 smsl-n100 dhcpcd[547]: eth0: carrier lost Nov 02 10:37:51 smsl-n100 dhcpcd[893]: eth0: carrier lost Nov 02 10:37:51 smsl-n100 avahi-daemon[510]: Withdrawing address record for 192.168.1.193 on eth0. Nov 02 10:37:51 smsl-n100 avahi-daemon[510]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.193. Nov 02 10:37:51 smsl-n100 avahi-daemon[510]: Interface eth0.IPv4 no longer relevant for mDNS. Nov 02 10:37:51 smsl-n100 volumio[1204]: info: Discovery: A device disappeared from network Nov 02 10:37:51 smsl-n100 dhcpcd[893]: eth0: deleting route to 192.168.1.0/24 Nov 02 10:37:51 smsl-n100 dhcpcd[547]: eth0: deleting route to 192.168.1.0/24 Nov 02 10:37:51 smsl-n100 dhcpcd[893]: eth0: deleting default route via 192.168.1.1 Nov 02 10:37:51 smsl-n100 dhcpcd[547]: eth0: deleting default route via 192.168.1.1 Nov 02 10:37:52 smsl-n100 ifplugd(eth0)[758]: Link beat lost. Nov 02 10:37:53 smsl-n100 ntpd[1193]: Deleting interface #3 eth0, 192.168.1.193#123, interface stats: received=126, sent=133, dropped=0, active_time=116 secs Nov 02 10:37:53 smsl-n100 ntpd[1193]: 172.104.28.175 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 96.231.54.40 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 149.28.200.179 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 69.89.207.99 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 192.189.65.187 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 45.33.83.31 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 23.186.168.125 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 85.209.17.10 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 162.159.200.1 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 51.81.226.229 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 141.11.234.198 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 102.129.185.135 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 65.100.46.166 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 172.234.37.140 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 142.202.190.19 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 104.131.155.175 local addr 192.168.1.193 -> Nov 02 10:37:53 smsl-n100 ntpd[1193]: 171.66.97.126 local addr 192.168.1.193 -> Nov 02 10:37:58 smsl-n100 volumio[1204]: info: Reporting MCU Network Status: 2 Nov 02 10:37:58 smsl-n100 volumio[1204]: info: Volumio Network Manager: Network status updated: 2 Nov 02 10:37:58 smsl-n100 volumio[1204]: info: CoreStateMachine::pushState Nov 02 10:37:58 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:37:58 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioPushState Nov 02 10:37:58 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output update for this device Nov 02 10:37:58 smsl-n100 volumio[1204]: info: MRS: Pushing multiroomSync output Nov 02 10:37:58 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:58 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 02 10:37:58 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 100, message: undefined Nov 02 10:37:58 smsl-n100 volumio[1204]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 02 10:37:58 smsl-n100 volumio[1204]: error: Serial API: Failed to decode command: 2, message: undefined Nov 02 10:38:02 smsl-n100 ifplugd(eth0)[758]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Nov 02 10:38:02 smsl-n100 ifplugd(eth0)[758]: client: sending commands to master dhcpcd process Nov 02 10:38:02 smsl-n100 dhcpcd[2028]: sending commands to master dhcpcd process Nov 02 10:38:02 smsl-n100 dhcpcd[547]: control command: /sbin/dhcpcd -k eth0 Nov 02 10:38:02 smsl-n100 dhcpcd[547]: eth0: removing interface Nov 02 10:38:02 smsl-n100 ifplugd(eth0)[758]: Program executed successfully. Nov 02 10:38:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Nov 02 10:38:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Nov 02 10:38:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Nov 02 10:38:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Nov 02 10:38:15 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Nov 02 10:38:15 smsl-n100 dhcpcd[893]: eth0: carrier acquired Nov 02 10:38:15 smsl-n100 dhcpcd[893]: eth0: IAID 63:9a:75:04 Nov 02 10:38:15 smsl-n100 dhcpcd[893]: eth0: soliciting a DHCP lease Nov 02 10:38:15 smsl-n100 ifplugd(eth0)[758]: Link beat detected. Nov 02 10:38:15 smsl-n100 dhcpcd[893]: eth0: soliciting an IPv6 router Nov 02 10:38:15 smsl-n100 ifplugd(eth0)[758]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 02 10:38:16 smsl-n100 ifplugd(eth0)[758]: client: sending commands to master dhcpcd process Nov 02 10:38:16 smsl-n100 dhcpcd[2095]: sending commands to master dhcpcd process Nov 02 10:38:16 smsl-n100 dhcpcd[547]: control command: /sbin/dhcpcd eth0 Nov 02 10:38:16 smsl-n100 dhcpcd[547]: eth0: carrier acquired Nov 02 10:38:16 smsl-n100 dhcpcd[547]: eth0: IAID 63:9a:75:04 Nov 02 10:38:16 smsl-n100 ifplugd(eth0)[758]: Program executed successfully. Nov 02 10:38:16 smsl-n100 dhcpcd[547]: eth0: soliciting a DHCP lease Nov 02 10:38:16 smsl-n100 dhcpcd[547]: eth0: soliciting an IPv6 router Nov 02 10:38:20 smsl-n100 dhcpcd[893]: eth0: offered 192.168.1.193 from 192.168.1.1 Nov 02 10:38:20 smsl-n100 dhcpcd[893]: eth0: probing address 192.168.1.193/24 Nov 02 10:38:20 smsl-n100 dhcpcd[547]: eth0: offered 192.168.1.193 from 192.168.1.1 Nov 02 10:38:20 smsl-n100 dhcpcd[547]: eth0: probing address 192.168.1.193/24 Nov 02 10:38:25 smsl-n100 dhcpcd[893]: eth0: leased 192.168.1.193 for 86236 seconds Nov 02 10:38:25 smsl-n100 avahi-daemon[510]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.193. Nov 02 10:38:25 smsl-n100 avahi-daemon[510]: New relevant interface eth0.IPv4 for mDNS. Nov 02 10:38:25 smsl-n100 dhcpcd[893]: eth0: adding route to 192.168.1.0/24 Nov 02 10:38:25 smsl-n100 avahi-daemon[510]: Registering new address record for 192.168.1.193 on eth0.IPv4. Nov 02 10:38:25 smsl-n100 dhcpcd[893]: eth0: adding default route via 192.168.1.1 Nov 02 10:38:25 smsl-n100 volumio[1204]: info: MRS: Found cast device: VisionMaster-6a61c76bc8caf95267a564b5869f670c Nov 02 10:38:25 smsl-n100 ntpd[1193]: ntpd exiting on signal 15 (Terminated) Nov 02 10:38:25 smsl-n100 ntpd[1193]: 171.66.97.126 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 systemd[1]: Stopping Network Time Service... Nov 02 10:38:25 smsl-n100 ntpd[1193]: 104.131.155.175 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 142.202.190.19 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 172.234.37.140 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 65.100.46.166 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 102.129.185.135 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 141.11.234.198 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 51.81.226.229 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 systemd[1]: ntp.service: Succeeded. Nov 02 10:38:25 smsl-n100 ntpd[1193]: 162.159.200.1 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 85.209.17.10 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 23.186.168.125 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 45.33.83.31 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 192.189.65.187 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 systemd[1]: Stopped Network Time Service. Nov 02 10:38:25 smsl-n100 ntpd[1193]: 69.89.207.99 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 149.28.200.179 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 96.231.54.40 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 ntpd[1193]: 172.104.28.175 local addr 192.168.1.194 -> Nov 02 10:38:25 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:38:25 smsl-n100 systemd[1]: Starting Network Time Service... Nov 02 10:38:25 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 10:38:25 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:38:25 smsl-n100 ntpd[2173]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 10:38:25 smsl-n100 ntpd[2173]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 10:38:25 smsl-n100 systemd[1]: Started Network Time Service. Nov 02 10:38:25 smsl-n100 ntpd[2179]: proto: precision = 1.166 usec (-20) Nov 02 10:38:25 smsl-n100 ntpd[2179]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 10:38:25 smsl-n100 ntpd[2179]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 10:38:25 smsl-n100 ntpd[2179]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1041 days ago Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listen normally on 3 eth0 192.168.1.193:123 Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listen normally on 4 wlan0 192.168.1.194:123 Nov 02 10:38:25 smsl-n100 ntpd[2179]: Listening on routing socket on fd #21 for interface updates Nov 02 10:38:25 smsl-n100 ntpd[2179]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 10:38:25 smsl-n100 ntpd[2179]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 10:38:25 smsl-n100 volumio[1204]: info: MRS: Found cast device: Chromecast-Ultra-7d010674dbf25d7f86c934f2f644653d Nov 02 10:38:25 smsl-n100 dhcpcd[547]: eth0: leased 192.168.1.193 for 86235 seconds Nov 02 10:38:25 smsl-n100 dhcpcd[547]: eth0: adding route to 192.168.1.0/24 Nov 02 10:38:25 smsl-n100 dhcpcd[547]: eth0: adding default route via 192.168.1.1 Nov 02 10:38:26 smsl-n100 ntpd[2179]: ntpd exiting on signal 15 (Terminated) Nov 02 10:38:26 smsl-n100 systemd[1]: Stopping Network Time Service... Nov 02 10:38:26 smsl-n100 systemd[1]: ntp.service: Succeeded. Nov 02 10:38:26 smsl-n100 systemd[1]: Stopped Network Time Service. Nov 02 10:38:26 smsl-n100 systemd[1]: Starting Network Time Service... Nov 02 10:38:26 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:38:26 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 02 10:38:26 smsl-n100 volumio[1204]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 10:38:26 smsl-n100 volumio[1204]: info: Discovery: adding e30a886e-8a7b-48a0-94b7-159aa72fd276 Nov 02 10:38:26 smsl-n100 volumio[1204]: info: Discovery: Found device SMSL N100 Nov 02 10:38:26 smsl-n100 volumio[1204]: info: CoreCommandRouter::volumioGetState Nov 02 10:38:26 smsl-n100 volumio[1204]: info: CorePlayQueue::getTrack 0 Nov 02 10:38:26 smsl-n100 volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 10:38:26 smsl-n100 volumio[1204]: Error: The value NaN is not a number Nov 02 10:38:26 smsl-n100 volumio[1204]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 02 10:38:26 smsl-n100 volumio[1204]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 02 10:38:26 smsl-n100 volumio[1204]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 02 10:38:26 smsl-n100 volumio[1204]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 02 10:38:26 smsl-n100 volumio[1204]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 02 10:38:26 smsl-n100 volumio[1204]: at Browser.emit (events.js:400:28) Nov 02 10:38:26 smsl-n100 volumio[1204]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 02 10:38:26 smsl-n100 volumio[1204]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 02 10:38:26 smsl-n100 volumio[1204]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 02 10:38:26 smsl-n100 volumio[1204]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 02 10:38:26 smsl-n100 volumio[1204]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 02 10:38:26 smsl-n100 volumio[1204]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 02 10:38:26 smsl-n100 volumio[1204]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 02 10:38:26 smsl-n100 volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 10:38:26 smsl-n100 ntpd[2213]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 02 10:38:26 smsl-n100 ntpd[2213]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 02 10:38:26 smsl-n100 systemd[1]: Started Network Time Service. Nov 02 10:38:26 smsl-n100 ntpd[2219]: proto: precision = 0.875 usec (-20) Nov 02 10:38:26 smsl-n100 ntpd[2219]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 02 10:38:26 smsl-n100 ntpd[2219]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 02 10:38:26 smsl-n100 ntpd[2219]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1041 days ago Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listen and drop on 0 v6wildcard [::]:123 Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listen normally on 2 lo 127.0.0.1:123 Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listen normally on 3 eth0 192.168.1.193:123 Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listen normally on 4 wlan0 192.168.1.194:123 Nov 02 10:38:26 smsl-n100 ntpd[2219]: Listening on routing socket on fd #21 for interface updates Nov 02 10:38:26 smsl-n100 ntpd[2219]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 10:38:26 smsl-n100 ntpd[2219]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Nov 02 10:38:27 smsl-n100 sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 10:37 Nov 02 10:38:27 smsl-n100 sudo[2231]: 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"