-- Logs begin at Mon 2024-07-22 17:22:29 UTC, end at Fri 2024-08-30 16:43:54 UTC. -- Aug 30 16:43:43 volumio systemd[1]: Starting Daily apt download activities... Aug 30 16:43:44 volumio volumio[872]: Starting albumart workers Aug 30 16:43:44 volumio volumio[872]: Starting albumart workers Aug 30 16:43:44 volumio volumio[872]: Starting albumart workers Aug 30 16:43:45 volumio systemd[1]: apt-daily.service: Succeeded. Aug 30 16:43:45 volumio systemd[1]: Started Daily apt download activities. Aug 30 16:43:45 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Aug 30 16:43:45 volumio volumio[872]: events.js:292 Aug 30 16:43:45 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:45 volumio volumio[872]: ^ Aug 30 16:43:45 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:45 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:45 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:45 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:45 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:45 volumio volumio[872]: errno: -98, Aug 30 16:43:45 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:45 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:45 volumio volumio[872]: address: 'undefined', Aug 30 16:43:45 volumio volumio[872]: port: -1 Aug 30 16:43:45 volumio volumio[872]: } Aug 30 16:43:45 volumio volumio[872]: Worker 103 died :( Aug 30 16:43:45 volumio volumio[872]: events.js:292 Aug 30 16:43:45 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:45 volumio volumio[872]: ^ Aug 30 16:43:45 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:45 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:45 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:45 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:45 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:45 volumio volumio[872]: errno: -98, Aug 30 16:43:45 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:45 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:45 volumio volumio[872]: address: 'undefined', Aug 30 16:43:45 volumio volumio[872]: port: -1 Aug 30 16:43:45 volumio volumio[872]: } Aug 30 16:43:45 volumio volumio[872]: Worker 105 died :( Aug 30 16:43:45 volumio volumio[872]: events.js:292 Aug 30 16:43:45 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:45 volumio volumio[872]: ^ Aug 30 16:43:45 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:45 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:45 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:45 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:45 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:45 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:45 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:45 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:45 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:45 volumio volumio[872]: errno: -98, Aug 30 16:43:45 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:45 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:45 volumio volumio[872]: address: 'undefined', Aug 30 16:43:45 volumio volumio[872]: port: -1 Aug 30 16:43:45 volumio volumio[872]: } Aug 30 16:43:45 volumio volumio[872]: Worker 104 died :( Aug 30 16:43:46 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 30 16:43:46 volumio systemd[1]: Started Daily apt upgrade and clean activities. Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:46 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:46 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:46 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:46 volumio volumio[872]: info: Aug 30 16:43:46 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 23ms Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand status took 21 milliseconds Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 21ms Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand status took 18 milliseconds Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 17ms Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand status took 6 milliseconds Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 16:43:46 volumio volumio[872]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:46 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:46 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:46 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: Starting albumart workers Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:46 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:46 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:46 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:46 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:46 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 137ms Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 133ms Aug 30 16:43:46 volumio volumio[872]: info: ------------------------------ 131ms Aug 30 16:43:46 volumio volumio[872]: Starting albumart workers Aug 30 16:43:46 volumio volumio[872]: Starting albumart workers Aug 30 16:43:47 volumio volumio[872]: events.js:292 Aug 30 16:43:47 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:47 volumio volumio[872]: ^ Aug 30 16:43:47 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:47 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:47 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:47 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:47 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:47 volumio volumio[872]: errno: -98, Aug 30 16:43:47 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:47 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:47 volumio volumio[872]: address: 'undefined', Aug 30 16:43:47 volumio volumio[872]: port: -1 Aug 30 16:43:47 volumio volumio[872]: } Aug 30 16:43:47 volumio volumio[872]: Worker 106 died :( Aug 30 16:43:47 volumio volumio[872]: events.js:292 Aug 30 16:43:47 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:47 volumio volumio[872]: ^ Aug 30 16:43:47 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:47 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:47 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:47 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:47 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:47 volumio volumio[872]: errno: -98, Aug 30 16:43:47 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:47 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:47 volumio volumio[872]: address: 'undefined', Aug 30 16:43:47 volumio volumio[872]: port: -1 Aug 30 16:43:47 volumio volumio[872]: } Aug 30 16:43:47 volumio volumio[872]: Worker 107 died :( Aug 30 16:43:47 volumio volumio[872]: events.js:292 Aug 30 16:43:47 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:47 volumio volumio[872]: ^ Aug 30 16:43:47 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:47 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:47 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:47 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:47 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:47 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:47 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:47 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:47 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:47 volumio volumio[872]: errno: -98, Aug 30 16:43:47 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:47 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:47 volumio volumio[872]: address: 'undefined', Aug 30 16:43:47 volumio volumio[872]: port: -1 Aug 30 16:43:47 volumio volumio[872]: } Aug 30 16:43:47 volumio volumio[872]: Worker 108 died :( Aug 30 16:43:48 volumio volumio[872]: Starting albumart workers Aug 30 16:43:48 volumio volumio[872]: Starting albumart workers Aug 30 16:43:48 volumio volumio[872]: Starting albumart workers Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 16:43:48 volumio volumio[872]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 16:43:48 volumio volumio[872]: events.js:292 Aug 30 16:43:48 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:48 volumio volumio[872]: ^ Aug 30 16:43:48 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:48 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:48 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:48 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:48 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:48 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:48 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:48 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:48 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:48 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:48 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:48 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:48 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:48 volumio volumio[872]: errno: -98, Aug 30 16:43:48 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:48 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:48 volumio volumio[872]: address: 'undefined', Aug 30 16:43:48 volumio volumio[872]: port: -1 Aug 30 16:43:48 volumio volumio[872]: } Aug 30 16:43:48 volumio volumio[872]: Worker 109 died :( Aug 30 16:43:49 volumio volumio[872]: events.js:292 Aug 30 16:43:49 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:49 volumio volumio[872]: ^ Aug 30 16:43:49 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:49 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:49 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:49 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:49 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:49 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:49 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:49 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:49 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:49 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:49 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:49 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:49 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:49 volumio volumio[872]: errno: -98, Aug 30 16:43:49 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:49 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:49 volumio volumio[872]: address: 'undefined', Aug 30 16:43:49 volumio volumio[872]: port: -1 Aug 30 16:43:49 volumio volumio[872]: } Aug 30 16:43:49 volumio volumio[872]: Worker 110 died :( Aug 30 16:43:49 volumio volumio[872]: events.js:292 Aug 30 16:43:49 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:49 volumio volumio[872]: ^ Aug 30 16:43:49 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:49 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:49 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:49 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:49 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:49 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:49 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:49 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:49 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:49 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:49 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:49 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:49 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:49 volumio volumio[872]: errno: -98, Aug 30 16:43:49 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:49 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:49 volumio volumio[872]: address: 'undefined', Aug 30 16:43:49 volumio volumio[872]: port: -1 Aug 30 16:43:49 volumio volumio[872]: } Aug 30 16:43:49 volumio volumio[872]: Worker 111 died :( Aug 30 16:43:50 volumio volumio[872]: Starting albumart workers Aug 30 16:43:50 volumio volumio[872]: Starting albumart workers Aug 30 16:43:50 volumio volumio[872]: Starting albumart workers Aug 30 16:43:50 volumio volumio[872]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 16:43:50 volumio volumio[872]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 16:43:50 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 16:43:50 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 16:43:50 volumio volumio[872]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 16:43:50 volumio volumio[872]: info: MyVolumio not started Aug 30 16:43:50 volumio volumio[872]: info: Initializing device activation check Aug 30 16:43:50 volumio volumio[872]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 16:43:50 volumio volumio[872]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 16:43:50 volumio volumio[872]: events.js:292 Aug 30 16:43:50 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:50 volumio volumio[872]: ^ Aug 30 16:43:50 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:50 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:50 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:50 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:50 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:50 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:50 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:50 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:50 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:50 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:50 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:50 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:50 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:50 volumio volumio[872]: errno: -98, Aug 30 16:43:50 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:50 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:50 volumio volumio[872]: address: 'undefined', Aug 30 16:43:50 volumio volumio[872]: port: -1 Aug 30 16:43:50 volumio volumio[872]: } Aug 30 16:43:50 volumio volumio[872]: Worker 112 died :( Aug 30 16:43:51 volumio volumio[872]: events.js:292 Aug 30 16:43:51 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:51 volumio volumio[872]: ^ Aug 30 16:43:51 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:51 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:51 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:51 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:51 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:51 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:51 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:51 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:51 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:51 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:51 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:51 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:51 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:51 volumio volumio[872]: errno: -98, Aug 30 16:43:51 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:51 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:51 volumio volumio[872]: address: 'undefined', Aug 30 16:43:51 volumio volumio[872]: port: -1 Aug 30 16:43:51 volumio volumio[872]: } Aug 30 16:43:51 volumio volumio[872]: Worker 113 died :( Aug 30 16:43:51 volumio volumio[872]: events.js:292 Aug 30 16:43:51 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:51 volumio volumio[872]: ^ Aug 30 16:43:51 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:51 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:51 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:51 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:51 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:51 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:51 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:51 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:51 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:51 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:51 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:51 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:51 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:51 volumio volumio[872]: errno: -98, Aug 30 16:43:51 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:51 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:51 volumio volumio[872]: address: 'undefined', Aug 30 16:43:51 volumio volumio[872]: port: -1 Aug 30 16:43:51 volumio volumio[872]: } Aug 30 16:43:51 volumio volumio[872]: Worker 114 died :( Aug 30 16:43:52 volumio volumio[872]: Starting albumart workers Aug 30 16:43:52 volumio volumio[872]: Starting albumart workers Aug 30 16:43:52 volumio volumio[872]: Starting albumart workers Aug 30 16:43:52 volumio volumio[872]: info: Starting Streaming Service Transparent Proxy Aug 30 16:43:52 volumio volumio[872]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 16:43:52 volumio volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 16:43:52 volumio volumio[872]: info: Streaming services startup Aug 30 16:43:52 volumio volumio[872]: info: Starting Streaming Daemon Aug 30 16:43:52 volumio sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 16:43:52 volumio sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 16:43:52 volumio volumio[872]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 16:43:52 volumio sudo[3144]: pam_unix(sudo:session): session closed for user root Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: error: Cannot start Volumio Streaming Daemon Aug 30 16:43:52 volumio volumio[872]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 16:43:52 volumio volumio[872]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 62ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 60 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 59ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 56 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 56ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 53 milliseconds Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces system playlist update Aug 30 16:43:52 volumio volumio[872]: info: Ignoring MPD Status Update Aug 30 16:43:52 volumio volumio[872]: info: Aug 30 16:43:52 volumio volumio[872]: ---------------------------- MPD announces state update: player Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::getState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:43:52 volumio volumio[872]: info: Discovery: A device disappeared from network Aug 30 16:43:52 volumio volumio[872]: info: Discovery: Device volumio disappeared from network Aug 30 16:43:52 volumio volumio[872]: Upnp client error: Error: This socket has been ended by the other party Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 60ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 55 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 47ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 35 milliseconds Aug 30 16:43:52 volumio volumio[872]: info: ------------------------------ 35ms Aug 30 16:43:52 volumio volumio[872]: info: sendMpdCommand status took 32 milliseconds Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::parseState Aug 30 16:43:52 volumio volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:52 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:52 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:52 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:52 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:52 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:52 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:52 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:52 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:52 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 230ms Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 227ms Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 224ms Aug 30 16:43:53 volumio volumio[872]: info: Discovery: A device disappeared from network Aug 30 16:43:53 volumio volumio[872]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 30 16:43:53 volumio volumio[872]: info: sendMpdCommand playlistinfo took 130 milliseconds Aug 30 16:43:53 volumio volumio[872]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 30 16:43:53 volumio volumio[872]: info: sendMpdCommand playlistinfo took 126 milliseconds Aug 30 16:43:53 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:53 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:53 volumio volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:43:53 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:53 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:53 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:53 volumio volumio[872]: events.js:292 Aug 30 16:43:53 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:53 volumio volumio[872]: ^ Aug 30 16:43:53 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:53 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:53 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:53 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:53 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:53 volumio volumio[872]: errno: -98, Aug 30 16:43:53 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:53 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:53 volumio volumio[872]: address: 'undefined', Aug 30 16:43:53 volumio volumio[872]: port: -1 Aug 30 16:43:53 volumio volumio[872]: } Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: Worker 115 died :( Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:53 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:53 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: info: ControllerMpd::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::servicePushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RadioXLondonMP3","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} Aug 30 16:43:53 volumio volumio[872]: verbose: CURRENT POSITION 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState stateService play Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:43:53 volumio volumio[872]: info: Received an update from plugin. extracting info from payload Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CorePlayQueue::getTrack 0 Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: info: CoreStateMachine::pushState Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioPushState Aug 30 16:43:53 volumio volumio[872]: events.js:292 Aug 30 16:43:53 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:53 volumio volumio[872]: ^ Aug 30 16:43:53 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:53 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:53 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:53 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:53 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:53 volumio volumio[872]: errno: -98, Aug 30 16:43:53 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:53 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:53 volumio volumio[872]: address: 'undefined', Aug 30 16:43:53 volumio volumio[872]: port: -1 Aug 30 16:43:53 volumio volumio[872]: } Aug 30 16:43:53 volumio volumio[872]: Worker 116 died :( Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 386ms Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 363ms Aug 30 16:43:53 volumio volumio[872]: info: ------------------------------ 360ms Aug 30 16:43:53 volumio volumio[872]: events.js:292 Aug 30 16:43:53 volumio volumio[872]: throw er; // Unhandled 'error' event Aug 30 16:43:53 volumio volumio[872]: ^ Aug 30 16:43:53 volumio volumio[872]: Error: bind EADDRINUSE undefined Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1385:18) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: at Worker. (internal/cluster/child.js:105:7) Aug 30 16:43:53 volumio volumio[872]: at process.onInternalMessage (internal/cluster/utils.js:48:5) Aug 30 16:43:53 volumio volumio[872]: at process.emit (events.js:327:22) Aug 30 16:43:53 volumio volumio[872]: at emit (internal/child_process.js:903:12) Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 30 16:43:53 volumio volumio[872]: Emitted 'error' event on Server instance at: Aug 30 16:43:53 volumio volumio[872]: at listenOnMasterHandle (net.js:1386:21) Aug 30 16:43:53 volumio volumio[872]: at rr (internal/cluster/child.js:138:12) Aug 30 16:43:53 volumio volumio[872]: [... lines matching original stack trace ...] Aug 30 16:43:53 volumio volumio[872]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 30 16:43:53 volumio volumio[872]: errno: -98, Aug 30 16:43:53 volumio volumio[872]: code: 'EADDRINUSE', Aug 30 16:43:53 volumio volumio[872]: syscall: 'bind', Aug 30 16:43:53 volumio volumio[872]: address: 'undefined', Aug 30 16:43:53 volumio volumio[872]: port: -1 Aug 30 16:43:53 volumio volumio[872]: } Aug 30 16:43:53 volumio volumio[872]: Worker 117 died :( Aug 30 16:43:53 volumio volumio[872]: info: Discovery: adding 5b2c1368-4c0c-433b-99a0-71103a23547d Aug 30 16:43:53 volumio volumio[872]: info: Discovery: Found device Volumio Aug 30 16:43:53 volumio volumio[872]: info: CoreCommandRouter::volumioGetState Aug 30 16:43:53 volumio volumio[872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:43:53 volumio volumio[872]: Error: The value NaN is not a number Aug 30 16:43:53 volumio volumio[872]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Aug 30 16:43:53 volumio volumio[872]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Aug 30 16:43:53 volumio volumio[872]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Aug 30 16:43:53 volumio volumio[872]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Aug 30 16:43:53 volumio volumio[872]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Aug 30 16:43:53 volumio volumio[872]: at Browser.emit (events.js:315:20) Aug 30 16:43:53 volumio volumio[872]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 30 16:43:53 volumio volumio[872]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 30 16:43:53 volumio volumio[872]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Aug 30 16:43:53 volumio volumio[872]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Aug 30 16:43:53 volumio volumio[872]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 30 16:43:53 volumio volumio[872]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 30 16:43:53 volumio volumio[872]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 30 16:43:53 volumio volumio[872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:43:54 volumio volumio[872]: Starting albumart workers Aug 30 16:43:54 volumio sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 16:42 Aug 30 16:43:54 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 16:43:54 volumio volumio[872]: STREAMING PROXY: Starting server on port 3245 Aug 30 16:43:54 volumio volumio[872]: Node JS runtime: 14 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"