-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Tue 2024-10-22 17:31:22 CEST. -- Oct 22 17:30:02 volumio-boss kernel: hwmon hwmon1: Voltage normalised Oct 22 17:30:04 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:04 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 1 Oct 22 17:30:10 volumio-boss volumio[980]: info: Oct 22 17:30:10 volumio-boss volumio[980]: ---------------------------- MPD announces state update: player Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::getState Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 22 17:30:10 volumio-boss volumio[980]: info: Oct 22 17:30:10 volumio-boss volumio[980]: ---------------------------- MPD announces state update: player Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::getState Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 22 17:30:10 volumio-boss volumio[980]: info: Oct 22 17:30:10 volumio-boss volumio[980]: ---------------------------- MPD announces state update: player Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::getState Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::sendMpdCommand status Oct 22 17:30:10 volumio-boss volumio[980]: info: sendMpdCommand status took 7 milliseconds Oct 22 17:30:10 volumio-boss volumio[980]: info: sendMpdCommand status took 6 milliseconds Oct 22 17:30:10 volumio-boss volumio[980]: info: sendMpdCommand status took 4 milliseconds Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::parseState Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::parseState Oct 22 17:30:10 volumio-boss volumio[980]: verbose: ControllerMpd::parseState Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::servicePushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 17:30:10 volumio-boss volumio[980]: verbose: CURRENT POSITION 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState stateService stop Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState currentStatus play Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::stPlaybackTimer Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::servicePushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 17:30:10 volumio-boss volumio[980]: verbose: CURRENT POSITION 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState stateService stop Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState currentStatus stop Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: No code Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: ControllerMpd::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::servicePushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 17:30:10 volumio-boss volumio[980]: verbose: CURRENT POSITION 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState stateService stop Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::syncState currentStatus stop Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: No code Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreStateMachine::pushState Oct 22 17:30:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:30:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioPushState Oct 22 17:30:10 volumio-boss volumio[980]: info: ------------------------------ 166ms Oct 22 17:30:10 volumio-boss volumio[980]: info: ------------------------------ 165ms Oct 22 17:30:10 volumio-boss volumio[980]: info: ------------------------------ 171ms Oct 22 17:30:10 volumio-boss kernel: hwmon hwmon1: Undervoltage detected! Oct 22 17:30:16 volumio-boss kernel: hwmon hwmon1: Voltage normalised Oct 22 17:30:21 volumio-boss ntpd[810]: 212.45.144.88 local addr 192.168.1.58 -> Oct 22 17:30:24 volumio-boss ntpd[810]: 31.14.133.122 local addr 192.168.1.58 -> Oct 22 17:30:52 volumio-boss kernel: hwmon hwmon1: Undervoltage detected! Oct 22 17:30:57 volumio-boss ntpd[810]: 188.213.165.209 local addr 192.168.1.58 -> Oct 22 17:30:58 volumio-boss kernel: hwmon hwmon1: Voltage normalised Oct 22 17:31:00 volumio-boss kernel: hwmon hwmon1: Undervoltage detected! Oct 22 17:31:00 volumio-boss sudo[3370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 17:31:00 volumio-boss sudo[3370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:00 volumio-boss sudo[3370]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:00 volumio-boss sudo[3372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 17:31:00 volumio-boss sudo[3372]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:00 volumio-boss sudo[3372]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:00 volumio-boss volumio[980]: verbose: New Socket.io Connection to 192.168.1.58 from 192.168.1.52 UA: Mozilla/5.0 (Linux; NetCast; U) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/94.0.4606.128 Safari/537.36 SmartTV/10.0 Colt/2.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 22 17:31:01 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 17:31:01 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 22 17:31:02 volumio-boss volumio[980]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Oct 22 17:31:04 volumio-boss sudo[3376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 17:31:04 volumio-boss sudo[3376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:04 volumio-boss sudo[3376]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:04 volumio-boss sudo[3378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 17:31:04 volumio-boss sudo[3378]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:04 volumio-boss sudo[3378]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:05 volumio-boss volumio[980]: verbose: New Socket.io Connection to 192.168.1.58 from 192.168.1.52 UA: Mozilla/5.0 (Linux; NetCast; U) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/94.0.4606.128 Safari/537.36 SmartTV/10.0 Colt/2.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 22 17:31:06 volumio-boss sudo[3382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 17:31:06 volumio-boss sudo[3382]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:06 volumio-boss sudo[3382]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:06 volumio-boss sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 17:31:06 volumio-boss sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:06 volumio-boss sudo[3384]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:06 volumio-boss volumio[980]: verbose: New Socket.io Connection to 192.168.1.58 from 192.168.1.52 UA: Mozilla/5.0 (Linux; NetCast; U) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/94.0.4606.128 Safari/537.36 SmartTV/10.0 Colt/2.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 22 17:31:06 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:07 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetQueue Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreStateMachine::getQueue Oct 22 17:31:07 volumio-boss volumio[980]: info: CorePlayQueue::getQueue Oct 22 17:31:07 volumio-boss volumio[980]: info: Listing playlists Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 17:31:07 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:07 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:07 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:07 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 22 17:31:07 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 17:31:09 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:09 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:09 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:09 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 22 17:31:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 17:31:10 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:10 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:10 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:10 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:10 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:11 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 22 17:31:11 volumio-boss volumio[980]: info: MyVolumio login type: Token Oct 22 17:31:11 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 22 17:31:11 volumio-boss volumio[980]: info: MyVolumio login type: Token Oct 22 17:31:12 volumio-boss volumio[980]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 22 17:31:12 volumio-boss volumio[980]: info: MyVolumio token set successfully Oct 22 17:31:12 volumio-boss volumio[980]: info: MYVOLUMIO: Adding device Oct 22 17:31:12 volumio-boss volumio[980]: info: MYVOLUMIO: Evaluating Server Oct 22 17:31:13 volumio-boss volumio[980]: info: MyVolumio Plan changed: premium Oct 22 17:31:13 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 22 17:31:13 volumio-boss volumio[980]: info: Removing browser output: myVolumio user plan is not superstar Oct 22 17:31:13 volumio-boss volumio[980]: info: Removing audio output: Oct 22 17:31:13 volumio-boss volumio[980]: info: MYVOLUMIO: Adding device Oct 22 17:31:13 volumio-boss volumio[980]: info: MYVOLUMIO: Evaluating Server Oct 22 17:31:13 volumio-boss volumio[980]: info: Remote config written successfully Oct 22 17:31:13 volumio-boss volumio[980]: info: Starting Tunnel 1 Oct 22 17:31:13 volumio-boss volumio[980]: info: Starting Tunnel Connection Checker Oct 22 17:31:14 volumio-boss volumio[980]: info: MYVolumio Device enabled Oct 22 17:31:14 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 22 17:31:14 volumio-boss volumio[980]: info: MyVolumio status changed Oct 22 17:31:14 volumio-boss volumio[980]: info: Streaming services startup Oct 22 17:31:14 volumio-boss volumio[980]: info: Starting Streaming Daemon Oct 22 17:31:14 volumio-boss volumio[980]: info: Setting Geolocation for MyVolumio to eu6 Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:14 volumio-boss sudo[3420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:14 volumio-boss sudo[3420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:14 volumio-boss sudo[3420]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 22 17:31:14 volumio-boss volumio[980]: error: [MyVolumio PluginManager] Cache data is invalid! Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 17:31:14 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:14 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:14 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:14 volumio-boss volumio[980]: error: Cannot start Volumio Streaming Daemon Oct 22 17:31:14 volumio-boss volumio[980]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 17:31:14 volumio-boss volumio[980]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 17:31:14 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:14 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:14 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:14 volumio-boss volumio[980]: info: Setting Geolocation for MyVolumio to eu4 Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 17:31:14 volumio-boss volumio[980]: info: Received Get System Info Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 17:31:14 volumio-boss volumio[980]: info: Discovery: Getting this device information Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::volumioGetState Oct 22 17:31:14 volumio-boss volumio[980]: info: CorePlayQueue::getTrack 0 Oct 22 17:31:14 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 17:31:14 volumio-boss volumio[980]: info: Successfully Added MyVolumio device Oct 22 17:31:14 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 22 17:31:15 volumio-boss volumio[980]: info: [1729611075269] Starting BluetoothController Oct 22 17:31:15 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 22 17:31:18 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 22 17:31:18 volumio-boss sudo[3425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 22 17:31:18 volumio-boss sudo[3425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:18 volumio-boss sudo[3425]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:18 volumio-boss volumio[980]: info: MRS: MultiRoom plugin initialized Oct 22 17:31:18 volumio-boss volumio[980]: info: MRS: STOPPING SNAPCLIENT Oct 22 17:31:18 volumio-boss volumio[980]: info: MRS: Snap server stop Oct 22 17:31:19 volumio-boss sudo[3441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 22 17:31:19 volumio-boss sudo[3441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:19 volumio-boss volumio[980]: info: MRS: STOPPING volumioStreaming Oct 22 17:31:19 volumio-boss sudo[3443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 22 17:31:19 volumio-boss sudo[3443]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 22 17:31:19 volumio-boss sudo[3441]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:19 volumio-boss sudo[3446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 22 17:31:19 volumio-boss sudo[3446]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:19 volumio-boss sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 22 17:31:19 volumio-boss sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:19 volumio-boss sudo[3443]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:19 volumio-boss sudo[3449]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:19 volumio-boss sudo[3446]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 22 17:31:19 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 22 17:31:19 volumio-boss volumio[980]: info: Preparing to generate the ALSA configuration file Oct 22 17:31:19 volumio-boss volumio[980]: info: Updating MyVolumio device info Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: Updating MyVolumio device info Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:19 volumio-boss volumio[980]: info: Successfully Added MyVolumio device Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 22 17:31:19 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 22 17:31:19 volumio-boss volumio[980]: info: MRS: Removed streaming files Oct 22 17:31:19 volumio-boss volumio[980]: info: MRS: volumioStreaming STOPPED Oct 22 17:31:19 volumio-boss volumio[980]: info: MRS: SNAPSERVER STOPPED Oct 22 17:31:19 volumio-boss volumio[980]: info: MRS: SNAPCLIENT STOPPED Oct 22 17:31:19 volumio-boss volumio[980]: info: Asound.conf file unchanged, so no further update is needed Oct 22 17:31:19 volumio-boss volumio[980]: info: Output device has changed, restarting MPD Oct 22 17:31:20 volumio-boss sudo[3453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 17:31:20 volumio-boss sudo[3453]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:20 volumio-boss volumio[980]: info: Output device has changed, restarting Shairport Sync Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:20 volumio-boss sudo[3453]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:20 volumio-boss sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 17:31:20 volumio-boss sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:20 volumio-boss systemd[1]: Stopping Music Player Daemon... Oct 22 17:31:20 volumio-boss volumio[980]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 17:31:20 volumio-boss volumio[980]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 22 17:31:20 volumio-boss volumio[980]: info: MRS: Pushing multiroomSync output for this device Oct 22 17:31:20 volumio-boss volumio[980]: info: MRS: Pushing multiroomSync output Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding audio output: Oct 22 17:31:20 volumio-boss sudo[3464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-boss Oct 22 17:31:20 volumio-boss sudo[3464]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding audio output: Oct 22 17:31:20 volumio-boss sudo[3464]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:20 volumio-boss kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 22 17:31:20 volumio-boss volumio[980]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:20 volumio-boss systemd[1]: mpd.service: Succeeded. Oct 22 17:31:20 volumio-boss systemd[1]: Stopped Music Player Daemon. Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding METAVOLUMIO REST API Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 22 17:31:20 volumio-boss volumio[980]: info: Preparing CD Folders Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding CD REST API Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 22 17:31:20 volumio-boss volumio[980]: info: Starting UDEV Watcher for CD Oct 22 17:31:20 volumio-boss volumio[980]: info: Detecting CD presence with UDEV Oct 22 17:31:20 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 22 17:31:20 volumio-boss systemd[1]: Starting Music Player Daemon... Oct 22 17:31:20 volumio-boss kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding inputs REST Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 22 17:31:20 volumio-boss volumio[980]: info: Scanning Audio Inputs Oct 22 17:31:20 volumio-boss volumio[980]: info: Checking against Known Cards name Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding Server instance for streaming Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 22 17:31:20 volumio-boss volumio[980]: error: Hi Res Audio Failed Login: Missing Login Data Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 22 17:31:20 volumio-boss volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 22 17:31:20 volumio-boss sudo[3469]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 22 17:31:20 volumio-boss sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:20 volumio-boss sudo[3473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 22 17:31:20 volumio-boss sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:20 volumio-boss sudo[3469]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:20 volumio-boss volumio[980]: info: Stopping AccessToken refresher cron Oct 22 17:31:20 volumio-boss volumio[980]: info: AccessToken refresher cron started Oct 22 17:31:20 volumio-boss volumio[980]: info: Stopping AccessToken refresher cron for QOBUZ Oct 22 17:31:20 volumio-boss sudo[3473]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:20 volumio-boss volumio[980]: info: AccessToken refresher cron started for QOBUZ Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding TIDAL REST API Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding QOBUZ REST API Endpoints Oct 22 17:31:20 volumio-boss volumio[980]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 22 17:31:21 volumio-boss volumio[980]: info: Bluetooth name changed to Volumio-boss Oct 22 17:31:21 volumio-boss volumio[980]: info: MPD Permissions set Oct 22 17:31:21 volumio-boss sudo[3483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 22 17:31:21 volumio-boss sudo[3483]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 17:31:21 volumio-boss volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 17:31:21 volumio-boss systemd[1]: Started MyVolumio SSH Tunnel. Oct 22 17:31:21 volumio-boss volumio[980]: TypeError: Cannot read property 'length' of undefined Oct 22 17:31:21 volumio-boss volumio[980]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 22 17:31:21 volumio-boss volumio[980]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 22 17:31:21 volumio-boss volumio[980]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 22 17:31:21 volumio-boss volumio[980]: at Parser.emit (events.js:315:20) Oct 22 17:31:21 volumio-boss volumio[980]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 22 17:31:21 volumio-boss volumio[980]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 22 17:31:21 volumio-boss volumio[980]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 22 17:31:21 volumio-boss volumio[980]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 22 17:31:21 volumio-boss volumio[980]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 22 17:31:21 volumio-boss volumio[980]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 22 17:31:21 volumio-boss volumio[980]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 22 17:31:21 volumio-boss volumio[980]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 22 17:31:21 volumio-boss volumio[980]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 22 17:31:21 volumio-boss volumio[980]: at IncomingMessage.emit (events.js:327:22) Oct 22 17:31:21 volumio-boss volumio[980]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 22 17:31:21 volumio-boss volumio[980]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 22 17:31:21 volumio-boss volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 17:31:21 volumio-boss sudo[3483]: pam_unix(sudo:session): session closed for user root Oct 22 17:31:21 volumio-boss autossh[3490]: port set to 0, monitoring disabled Oct 22 17:31:21 volumio-boss autossh[3490]: starting ssh (count 1) Oct 22 17:31:21 volumio-boss autossh[3490]: ssh child pid is 3495 Oct 22 17:31:22 volumio-boss sudo[3503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 17:30 Oct 22 17:31:22 volumio-boss sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"