-- Logs begin at Wed 2024-05-22 13:46:29 +08, end at Sun 2024-08-18 11:09:44 +08. -- Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: MyVolumio token set successfully Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: MYVOLUMIO: Adding device Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: MYVOLUMIO: Evaluating Server Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: MyVolumio status changed Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: Streaming services startup Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: Starting Streaming Daemon Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: Removing browser output: myVolumio user plan is not superstar Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: Removing audio output: Aug 18 11:08:00 speakers-in-the-front-room volumio[680]: info: Stoppping Tunnel 1 Aug 18 11:08:00 speakers-in-the-front-room sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 11:08:00 speakers-in-the-front-room sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:08:01 speakers-in-the-front-room sudo[1235]: pam_unix(sudo:session): session closed for user root Aug 18 11:08:01 speakers-in-the-front-room sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 18 11:08:01 speakers-in-the-front-room sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: info: Setting Geolocation for MyVolumio to as1 Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:01 speakers-in-the-front-room sudo[1238]: pam_unix(sudo:session): session closed for user root Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: error: Cannot start Volumio Streaming Daemon Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: info: Remote SSH Stopped Aug 18 11:08:01 speakers-in-the-front-room volumio[680]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:08:02 speakers-in-the-front-room volumio[680]: info: Updating MyVolumio device info Aug 18 11:08:02 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:02 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:02 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:02 speakers-in-the-front-room volumio[680]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:08:04 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:08:04 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:05 speakers-in-the-front-room volumio[680]: info: MYVOLUMIO: Adding device Aug 18 11:08:05 speakers-in-the-front-room volumio[680]: info: MYVOLUMIO: Evaluating Server Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: error: MyVolumio Plugin failed to start in a timely fashion Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: BOOT COMPLETED Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: [Metrics] CommandRouter: 60s 476.52ms Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumiosetStartupVolume Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: VolumeController:: Setting startup Volume 85 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: msSurfaceDial volumioupdatevolume callback: {"vol":85,"dbVolume":-19,"mute":false,"disableVolumeControl":false} Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::pushState Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushState Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: SPOTIFY: SPOTIFY VOLUME 81 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: SPOTIFY: VOLUMIO VOLUME 85 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: Setting Spotify Volume from Volumio: 85 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: Setting Geolocation for MyVolumio to as1 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: Received Get System Version Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: Received Get System Info Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: Discovery: Getting this device information Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 11:08:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: Received Get System Version Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: Updating MyVolumio device info Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:08 speakers-in-the-front-room volumio[680]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:08:08 speakers-in-the-front-room volumio[680]: SPOTIFY: SETTING SPOTIFY VOLUME 85 Aug 18 11:08:08 speakers-in-the-front-room volumio[680]: info: Sending Spotify command with payload to local API: /player/volume Aug 18 11:08:08 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:08:08+08:00" level=debug msg="update volume to 55704/65535" Aug 18 11:08:08 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:08:08+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 18 11:08:08 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:08:08+08:00" level=trace msg="emitting websocket event: volume" Aug 18 11:08:08 speakers-in-the-front-room volumio[680]: SPOTIFY: received: {"type":"volume","data":{"value":85,"max":100}} Aug 18 11:08:08 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED SPOTIFY VOLUME 85 Aug 18 11:08:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:08:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:08:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:08:10 speakers-in-the-front-room volumio-remote-updater[455]: No test mode Aug 18 11:08:10 speakers-in-the-front-room volumio-remote-updater[455]: No alpha test mode Aug 18 11:08:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 18 11:08:10 speakers-in-the-front-room volumio[680]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 18 11:08:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 18 11:08:12 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:08:12 speakers-in-the-front-room volumio[680]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Aug 18 11:08:12 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:08:12 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:12 speakers-in-the-front-room volumio-remote-updater[455]: No test mode Aug 18 11:08:12 speakers-in-the-front-room volumio-remote-updater[455]: No alpha test mode Aug 18 11:08:13 speakers-in-the-front-room volumio[680]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 18 11:08:13 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 18 11:08:15 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:08:17 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:20 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:08:20 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:21 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:08:22 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:27 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:08:27 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:30 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:08:30 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:32 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:08:33 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::ClearQueue Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::clearPlayQueue Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::addQueueItems Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::addQueueItems Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: Adding Item to queue: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:08:46 speakers-in-the-front-room volumio[680]: info: Exploding uri upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 in service upnp_browser Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::updateTrackBlock Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrackBlock Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPlay Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index 0 Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index undefined Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:47 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::ClearQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::clearPlayQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::addQueueItems Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::addQueueItems Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: Adding Item to queue: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: Using cached record of: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::updateTrackBlock Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrackBlock Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPlay Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index 0 Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index undefined Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:50 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::ClearQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::clearPlayQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::addQueueItems Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::addQueueItems Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: Adding Item to queue: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: Using cached record of: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::updateTrackBlock Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrackBlock Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPlay Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index 0 Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index undefined Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:08:55 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:08:57 speakers-in-the-front-room volumiologrotate[447]: ls: cannot access '/var/log/samba/log.wb-SPEAKERS': No such file or directory Aug 18 11:08:57 speakers-in-the-front-room volumiologrotate[447]: ls: cannot access 'IN': No such file or directory Aug 18 11:08:57 speakers-in-the-front-room volumiologrotate[447]: ls: cannot access 'THE': No such file or directory Aug 18 11:08:57 speakers-in-the-front-room volumiologrotate[447]: ls: cannot access 'FRONT': No such file or directory Aug 18 11:08:57 speakers-in-the-front-room volumiologrotate[447]: ls: cannot access 'ROOM': No such file or directory Aug 18 11:09:06 speakers-in-the-front-room volumio[680]: info: msSurfaceDial org.bluez.Device1 PropertiesChanged. Aug 18 11:09:06 speakers-in-the-front-room volumio[680]: info: msSurfaceDial /org/bluez/hci0/dev_A0_4A_5E_EF_24_B7 property Connected changed to true Aug 18 11:09:06 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::Reload Ui Aug 18 11:09:07 speakers-in-the-front-room bluetoothd[449]: Discover Service Changed handle: No attribute found within the given range Aug 18 11:09:07 speakers-in-the-front-room sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 11:09:07 speakers-in-the-front-room sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:09:07 speakers-in-the-front-room sudo[1315]: pam_unix(sudo:session): session closed for user root Aug 18 11:09:07 speakers-in-the-front-room sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 11:09:07 speakers-in-the-front-room sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:09:07 speakers-in-the-front-room sudo[1317]: pam_unix(sudo:session): session closed for user root Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: verbose: New Socket.io Connection to 192.168.50.135 from 192.168.50.249 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/127.0.6533.107 Mobile/15E148 Safari/604.1 Total Clients: 7 Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetQueue Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::getQueue Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getQueue Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: Listing playlists Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: Received Get System Info Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: Discovery: Getting this device information Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:07 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:08 speakers-in-the-front-room kernel: hid-generic 0005:045E:091B.0001: unsupported Resolution Multiplier 0 Aug 18 11:09:08 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:08 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: Received Get System Info Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: Discovery: Getting this device information Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:09:09 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: Received Get System Info Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: Discovery: Getting this device information Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:10 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[497]: eth0: carrier lost Aug 18 11:09:11 speakers-in-the-front-room kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[792]: eth0: carrier lost Aug 18 11:09:11 speakers-in-the-front-room avahi-daemon[457]: Withdrawing address record for 192.168.50.135 on eth0. Aug 18 11:09:11 speakers-in-the-front-room avahi-daemon[457]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.50.135. Aug 18 11:09:11 speakers-in-the-front-room avahi-daemon[457]: Interface eth0.IPv4 no longer relevant for mDNS. Aug 18 11:09:11 speakers-in-the-front-room volumio[680]: info: Discovery: A device disappeared from network Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[497]: eth0: deleting route to 192.168.50.0/24 Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[792]: eth0: deleting route to 192.168.50.0/24 Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[792]: eth0: deleting default route via 192.168.50.1 Aug 18 11:09:11 speakers-in-the-front-room dhcpcd[497]: eth0: deleting default route via 192.168.50.1 Aug 18 11:09:11 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:11 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:12 speakers-in-the-front-room ifplugd(eth0)[656]: Link beat lost. Aug 18 11:09:12 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:12 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: Deleting interface #3 eth0, 192.168.50.135#123, interface stats: received=120, sent=121, dropped=0, active_time=125 secs Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 106.10.186.201 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 45.77.243.81 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 17.253.60.253 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 162.159.200.1 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 162.159.200.123 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 94.237.79.110 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 47.241.41.246 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 194.36.178.157 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 144.126.242.176 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 172.104.44.120 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 203.123.48.1 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 137.184.250.82 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room ntpd[791]: 128.199.243.248 local addr 192.168.50.135 -> Aug 18 11:09:13 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:13 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:14 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:14 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:15 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:15 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:16 speakers-in-the-front-room kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Aug 18 11:09:16 speakers-in-the-front-room dhcpcd[497]: eth0: carrier acquired Aug 18 11:09:16 speakers-in-the-front-room dhcpcd[792]: eth0: carrier acquired Aug 18 11:09:16 speakers-in-the-front-room dhcpcd[792]: eth0: IAID b8:ef:9d:b2 Aug 18 11:09:16 speakers-in-the-front-room dhcpcd[497]: eth0: IAID b8:ef:9d:b2 Aug 18 11:09:16 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:16 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[792]: eth0: soliciting an IPv6 router Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[497]: eth0: soliciting a DHCP lease Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[497]: eth0: offered 192.168.50.135 from 192.168.50.1 Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[497]: eth0: probing address 192.168.50.135/24 Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[792]: eth0: soliciting a DHCP lease Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[792]: eth0: offered 192.168.50.135 from 192.168.50.1 Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[792]: eth0: probing address 192.168.50.135/24 Aug 18 11:09:17 speakers-in-the-front-room dhcpcd[497]: eth0: soliciting an IPv6 router Aug 18 11:09:17 speakers-in-the-front-room ifplugd(eth0)[656]: Link beat detected. Aug 18 11:09:17 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:17 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:18 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:18 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:19 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:19 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:20 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:21 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:21 speakers-in-the-front-room dhcpcd[497]: eth0: leased 192.168.50.135 for 86400 seconds Aug 18 11:09:21 speakers-in-the-front-room avahi-daemon[457]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.135. Aug 18 11:09:21 speakers-in-the-front-room dhcpcd[497]: eth0: adding route to 192.168.50.0/24 Aug 18 11:09:21 speakers-in-the-front-room avahi-daemon[457]: New relevant interface eth0.IPv4 for mDNS. Aug 18 11:09:21 speakers-in-the-front-room dhcpcd[497]: eth0: adding default route via 192.168.50.1 Aug 18 11:09:21 speakers-in-the-front-room avahi-daemon[457]: Registering new address record for 192.168.50.135 on eth0.IPv4. Aug 18 11:09:21 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:22 speakers-in-the-front-room dhcpcd[792]: eth0: leased 192.168.50.135 for 86400 seconds Aug 18 11:09:22 speakers-in-the-front-room dhcpcd[792]: eth0: adding route to 192.168.50.0/24 Aug 18 11:09:22 speakers-in-the-front-room dhcpcd[792]: eth0: adding default route via 192.168.50.1 Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Stopping Network Time Service... Aug 18 11:09:22 speakers-in-the-front-room ntpd[791]: ntpd exiting on signal 15 (Terminated) Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: ntp.service: Succeeded. Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Stopped Network Time Service. Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Starting Network Time Service... Aug 18 11:09:22 speakers-in-the-front-room ntpd[1418]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 18 11:09:22 speakers-in-the-front-room ntpd[1418]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Started Network Time Service. Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: proto: precision = 1.166 usec (-20) Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 600 days ago Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: Listen and drop on 0 v6wildcard [::]:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: Listen normally on 2 lo 127.0.0.1:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: Listen normally on 3 eth0 192.168.50.135:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: Listening on routing socket on fd #20 for interface updates Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 18 11:09:22 speakers-in-the-front-room ntpd[1436]: ntpd exiting on signal 15 (Terminated) Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Stopping Network Time Service... Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: ntp.service: Succeeded. Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Stopped Network Time Service. Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Starting Network Time Service... Aug 18 11:09:22 speakers-in-the-front-room ntpd[1454]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 18 11:09:22 speakers-in-the-front-room ntpd[1454]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 18 11:09:22 speakers-in-the-front-room systemd[1]: Started Network Time Service. Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: proto: precision = 1.166 usec (-20) Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 600 days ago Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: Listen and drop on 0 v6wildcard [::]:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: Listen normally on 2 lo 127.0.0.1:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: Listen normally on 3 eth0 192.168.50.135:123 Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: Listening on routing socket on fd #20 for interface updates Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 18 11:09:22 speakers-in-the-front-room ntpd[1460]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: info: Discovery: adding de030a07-29f1-4dfa-8643-676bc2f55a90 Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: info: Discovery: Found device Speakers in the Front Room Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:22 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:23 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:23 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 162.159.200.1 Aug 18 11:09:23 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:24 speakers-in-the-front-room volumio[680]: warn: msSurfaceDial Cannot find Surface-Dial input-event-path Aug 18 11:09:24 speakers-in-the-front-room kernel: input: Surface Dial System Multi Axis as /devices/virtual/misc/uhid/0005:045E:091B.0001/input/input1 Aug 18 11:09:24 speakers-in-the-front-room kernel: input: Surface Dial as /devices/virtual/misc/uhid/0005:045E:091B.0001/input/input2 Aug 18 11:09:24 speakers-in-the-front-room kernel: input: Surface Dial System Control as /devices/virtual/misc/uhid/0005:045E:091B.0001/input/input3 Aug 18 11:09:24 speakers-in-the-front-room kernel: input: Surface Dial as /devices/virtual/misc/uhid/0005:045E:091B.0001/input/input4 Aug 18 11:09:24 speakers-in-the-front-room kernel: hid-generic 0005:045E:091B.0001: input,hidraw0: BLUETOOTH HID v2.00 Device [Surface Dial] on 5C:F3:70:A6:84:3D Aug 18 11:09:24 speakers-in-the-front-room systemd-udevd[1467]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 18 11:09:24 speakers-in-the-front-room systemd-udevd[1462]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 18 11:09:24 speakers-in-the-front-room systemd-udevd[1464]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 18 11:09:24 speakers-in-the-front-room systemd-udevd[1463]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 18 11:09:24 speakers-in-the-front-room systemd-logind[454]: Watching system buttons on /dev/input/event3 (Surface Dial System Control) Aug 18 11:09:24 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 47.241.41.246 Aug 18 11:09:24 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 203.123.48.1 Aug 18 11:09:24 speakers-in-the-front-room volumio[680]: info: msSurfaceDial calling findSurfaceDialInputEventPath() Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: msSurfaceDial opening /dev/input/event1 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::ClearQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::clearPlayQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::addQueueItems Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::addQueueItems Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Adding Item to queue: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Using cached record of: upnp/folder/http://192.168.50.180:8200/ctl/ContentDir@64$0$0$19 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::saveQueue Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::updateTrackBlock Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrackBlock Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPlay Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index 0 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::stop Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::play index undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 18 11:09:25 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 144.126.242.176 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Received Get System Version Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Received Get System Info Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: Discovery: Getting this device information Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioGetState Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:25 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 11:09:25 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 137.184.250.82 Aug 18 11:09:26 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 165.173.24.214 Aug 18 11:09:26 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 47.241.41.246 Aug 18 11:09:26 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 162.159.200.123 Aug 18 11:09:27 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 106.10.186.200 Aug 18 11:09:27 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 17.253.60.253 Aug 18 11:09:28 speakers-in-the-front-room ntpd[1460]: Soliciting pool server 45.77.243.81 Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: msSurfaceDial changeVol calling commandRouter.volumiosetvolume(+) Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: msSurfaceDial volumioupdatevolume callback: {"vol":86,"dbVolume":-18,"mute":false,"disableVolumeControl":false} Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::pushState Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:09:28 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: SPOTIFY VOLUME 85 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: msSurfaceDial volumioupdatevolume callback: {"vol":86,"dbVolume":-18,"mute":false,"disableVolumeControl":false} Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::pushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: SPOTIFY VOLUME 85 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: msSurfaceDial changeVol calling commandRouter.volumiosetvolume(+) Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: msSurfaceDial volumioupdatevolume callback: {"vol":87,"dbVolume":-16.5,"mute":false,"disableVolumeControl":false} Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::pushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: SPOTIFY VOLUME 85 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: VOLUMIO VOLUME 87 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: Setting Spotify Volume from Volumio: 87 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: msSurfaceDial changeVol calling commandRouter.volumiosetvolume(-) Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: msSurfaceDial volumioupdatevolume callback: {"vol":86,"dbVolume":-18,"mute":false,"disableVolumeControl":false} Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreStateMachine::pushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CorePlayQueue::getTrack 0 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::volumioPushState Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: SPOTIFY VOLUME 87 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: VOLUMIO VOLUME 86 Aug 18 11:09:29 speakers-in-the-front-room volumio[680]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 18 11:09:30 speakers-in-the-front-room volumio[680]: SPOTIFY: SETTING SPOTIFY VOLUME 87 Aug 18 11:09:30 speakers-in-the-front-room volumio[680]: info: Sending Spotify command with payload to local API: /player/volume Aug 18 11:09:30 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:09:30+08:00" level=debug msg="update volume to 57015/65535" Aug 18 11:09:30 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:09:30+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 18 11:09:30 speakers-in-the-front-room go-librespot[1088]: time="2024-08-18T11:09:30+08:00" level=trace msg="emitting websocket event: volume" Aug 18 11:09:30 speakers-in-the-front-room volumio[680]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Aug 18 11:09:30 speakers-in-the-front-room volumio[680]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Aug 18 11:09:40 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:09:41 speakers-in-the-front-room volumio[680]: info: Preload queue cleared Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: TypeError: Cannot read property 'length' of undefined Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at Parser.emit (events.js:400:28) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at IncomingMessage.emit (events.js:412:35) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 18 11:09:43 speakers-in-the-front-room volumio[680]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:09:44 speakers-in-the-front-room sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 11:08 Aug 18 11:09:44 speakers-in-the-front-room sudo[1485]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"