-- Logs begin at Wed 2024-08-14 00:49:08 CDT, end at Tue 2024-08-20 09:45:43 CDT. -- Aug 20 08:44:09 um700vol nmbd[837]: [2024/08/20 08:44:09.759206, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 08:44:09 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 08:44:09 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 08:49:19 um700vol nmbd[837]: [2024/08/20 08:49:19.993716, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 08:49:19 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 08:49:19 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 08:52:55 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 08:54:19 um700vol nmbd[837]: [2024/08/20 08:54:19.416456, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 08:54:19 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 08:54:19 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 08:59:26 um700vol nmbd[837]: [2024/08/20 08:59:26.186298, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 08:59:26 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 08:59:26 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:00:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 1 Aug 20 09:00:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:08 um700vol volumio[879]: info: Prefetching next song Aug 20 09:00:08 um700vol volumio[879]: info: DOING PREFETCH IN MPD Aug 20 09:00:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand add "NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/03 - Side 3.dsf" Aug 20 09:00:08 um700vol volumio[879]: info: sendMpdCommand add "NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/03 - Side 3.dsf" took 1 milliseconds Aug 20 09:00:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 20 09:00:08 um700vol volumio[879]: info: Aug 20 09:00:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:08 um700vol volumio[879]: info: sendMpdCommand consume 1 took 1 milliseconds Aug 20 09:00:08 um700vol volumio[879]: info: Aug 20 09:00:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:08 um700vol volumio[879]: info: Aug 20 09:00:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:08 um700vol volumio[879]: info: ------------------------------ 2ms Aug 20 09:00:08 um700vol volumio[879]: info: ------------------------------ 1ms Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::startPlaybackTimer Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:12 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:12 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:00:12 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:00:12 um700vol volumio[879]: info: Aug 20 09:00:12 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 3ms Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand status took 2 milliseconds Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 2ms Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand status took 1 milliseconds Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 1ms Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand status took 1 milliseconds Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 09:00:12 um700vol volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseTrackInfo Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseTrackInfo Aug 20 09:00:12 um700vol volumio[879]: verbose: ControllerMpd::parseTrackInfo Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":1436,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 3","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/03 - Side 3.dsf","trackType":"dsf"} Aug 20 09:00:12 um700vol volumio[879]: verbose: CURRENT POSITION 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:00:12 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":1436,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 3","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/03 - Side 3.dsf","trackType":"dsf"} Aug 20 09:00:12 um700vol volumio[879]: verbose: CURRENT POSITION 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:00:12 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":1436,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 3","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/03 - Side 3.dsf","trackType":"dsf"} Aug 20 09:00:12 um700vol volumio[879]: verbose: CURRENT POSITION 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:00:12 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:12 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:12 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 11ms Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 11ms Aug 20 09:00:12 um700vol volumio[879]: info: ------------------------------ 11ms Aug 20 09:00:13 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:00:13 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:00:13 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 09:00:13 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:01:11 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 09:04:26 um700vol nmbd[837]: [2024/08/20 09:04:26.375803, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:04:26 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:04:26 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:09:26 um700vol nmbd[837]: [2024/08/20 09:09:26.656272, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:09:26 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:09:26 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:14:36 um700vol nmbd[837]: [2024/08/20 09:14:36.935810, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:14:36 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:14:36 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:17:57 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 09:19:29 um700vol nmbd[837]: [2024/08/20 09:19:29.191065, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:19:29 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:19:29 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:24:03 um700vol volumio[879]: info: CorePlayQueue::getTrack 2 Aug 20 09:24:03 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:03 um700vol volumio[879]: info: Prefetching next song Aug 20 09:24:03 um700vol volumio[879]: info: DOING PREFETCH IN MPD Aug 20 09:24:03 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand add "NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/04 - Side 4.dsf" Aug 20 09:24:03 um700vol volumio[879]: info: sendMpdCommand add "NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/04 - Side 4.dsf" took 1 milliseconds Aug 20 09:24:03 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 20 09:24:03 um700vol volumio[879]: info: Aug 20 09:24:03 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:03 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:03 um700vol volumio[879]: info: Aug 20 09:24:03 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:03 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:03 um700vol volumio[879]: info: Aug 20 09:24:03 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:03 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:03 um700vol volumio[879]: info: sendMpdCommand consume 1 took 0 milliseconds Aug 20 09:24:03 um700vol volumio[879]: info: ------------------------------ 0ms Aug 20 09:24:03 um700vol volumio[879]: info: ------------------------------ 1ms Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::startPlaybackTimer Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces system playlist update Aug 20 09:24:08 um700vol volumio[879]: info: Ignoring MPD Status Update Aug 20 09:24:08 um700vol volumio[879]: info: Aug 20 09:24:08 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 1ms Aug 20 09:24:08 um700vol volumio[879]: info: sendMpdCommand status took 1 milliseconds Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 0ms Aug 20 09:24:08 um700vol volumio[879]: info: sendMpdCommand status took 0 milliseconds Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 0ms Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:24:08 um700vol volumio[879]: info: sendMpdCommand status took 1 milliseconds Aug 20 09:24:08 um700vol volumio[879]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::parseState Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::parseTrackInfo Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71,"duration":1489,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 4","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/04 - Side 4.dsf","trackType":"dsf"} Aug 20 09:24:08 um700vol volumio[879]: verbose: CURRENT POSITION 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:24:08 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71,"duration":1489,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 4","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/04 - Side 4.dsf","trackType":"dsf"} Aug 20 09:24:08 um700vol volumio[879]: verbose: CURRENT POSITION 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:24:08 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 4ms Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 4ms Aug 20 09:24:08 um700vol volumio[879]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 20 09:24:08 um700vol volumio[879]: verbose: ControllerMpd::parseTrackInfo Aug 20 09:24:08 um700vol volumio[879]: info: ControllerMpd::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::servicePushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":71,"duration":1489,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Side 4","artist":"The Beatles","album":"1967-1970","uri":"NAS/ssd0-music/Pop/The Beatles/DSD256L/1967-1970/04 - Side 4.dsf","trackType":"dsf"} Aug 20 09:24:08 um700vol volumio[879]: verbose: CURRENT POSITION 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState stateService play Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::syncState currentStatus play Aug 20 09:24:08 um700vol volumio[879]: info: Received an update from plugin. extracting info from payload Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:08 um700vol volumio[879]: info: ------------------------------ 7ms Aug 20 09:24:08 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:24:08 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 09:24:08 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:24:29 um700vol nmbd[837]: [2024/08/20 09:24:29.410331, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:24:29 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:24:29 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:29:48 um700vol nmbd[837]: [2024/08/20 09:29:48.563527, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:29:48 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:29:48 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:34:38 um700vol nmbd[837]: [2024/08/20 09:34:38.804556, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:34:38 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:34:38 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:37:21 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 09:37:21 um700vol volumio[879]: info: CURURI: music-library/NAS/ssd0-music/Classical Aug 20 09:37:21 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:38:00 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 09:38:00 um700vol volumio[879]: info: CURURI: music-library/NAS/ssd0-music/Classical/Sibelius Aug 20 09:38:00 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:38:03 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 09:38:03 um700vol volumio[879]: info: CURURI: music-library/NAS/ssd0-music/Classical/Sibelius/DSD256L Aug 20 09:38:03 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:38:03 um700vol volumio[879]: info: Preloading song: music-library/NAS/ssd0-music/Classical/Sibelius/DSD256L/01 - Sibelius Concerto in D minor.dsf Aug 20 09:38:03 um700vol volumio[879]: info: Exploding uri music-library/NAS/ssd0-music/Classical/Sibelius/DSD256L/01 - Sibelius Concerto in D minor.dsf in service mpd Aug 20 09:38:03 um700vol volumio[879]: info: ALBUMART /albumart?cacheid=512&web=Sibelius%20%2B%20Prokofiev/Concerto%20in%20D%20minor%20%2B%20Concerto%20in%20G%20minor/medium&path=%2Fmnt%2FNAS%2Fssd0-music%2FClassical%2FSibelius%2FDSD256L%2F01%20-%20Sibelius%20Concerto%20in%20D%20minor.dsf&metadata=false Aug 20 09:38:03 um700vol volumio[879]: info: URI /mnt/NAS/ssd0-music/Classical/Sibelius/DSD256L/01 - Sibelius Concerto in D minor.dsf Aug 20 09:38:09 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 09:38:09 um700vol volumio[879]: info: CURURI: music-library/NAS/ssd0-music/Classical/Sibelius/Internet Aug 20 09:38:09 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:38:19 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 09:38:19 um700vol volumio[879]: info: CURURI: music-library/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons Aug 20 09:38:19 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:38:19 um700vol volumio[879]: info: Preloading song: music-library/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons.flac Aug 20 09:38:19 um700vol volumio[879]: info: Exploding uri music-library/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons.flac in service mpd Aug 20 09:38:19 um700vol volumio[879]: info: ALBUMART /albumart?cacheid=512&web=Sibelius%2C%20Mendelssohn/Violin%20Concertos/medium&path=%2Fmnt%2FNAS%2Fssd0-music%2FClassical%2FSibelius%2FInternet%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons.flac&metadata=false Aug 20 09:38:19 um700vol volumio[879]: info: URI /mnt/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons.flac Aug 20 09:39:39 um700vol nmbd[837]: [2024/08/20 09:39:39.123503, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:39:39 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:39:39 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:44:48 um700vol nmbd[837]: [2024/08/20 09:44:48.563129, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 20 09:44:48 um700vol nmbd[837]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.17 for name WORKGROUP<1d>. Aug 20 09:44:48 um700vol nmbd[837]: This response was from IP 192.168.2.7, reporting an IP address of 192.168.2.7. Aug 20 09:45:43 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:45:43 um700vol volumio[879]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::ClearQueue Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::stop Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::stPlaybackTimer Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::updateTrackBlock Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::getTrackBlock Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::pushState Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:45:43 um700vol volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 09:45:43 um700vol volumio[879]: info: CoreCommandRouter::volumioPushState Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::serviceStop Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::getTrack 3 Aug 20 09:45:43 um700vol volumio[879]: info: CoreCommandRouter::serviceStop Aug 20 09:45:43 um700vol volumio[879]: info: ControllerMpd::stop Aug 20 09:45:43 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::clearPlayQueue Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::saveQueue Aug 20 09:45:43 um700vol volumio[879]: info: CoreCommandRouter::volumioPushQueue Aug 20 09:45:43 um700vol volumio[879]: info: CoreStateMachine::addQueueItems Aug 20 09:45:43 um700vol volumio[879]: info: CorePlayQueue::addQueueItems Aug 20 09:45:43 um700vol volumio[879]: info: Preload queue cleared Aug 20 09:45:43 um700vol volumio[879]: info: Adding Item to queue: music-library/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons Aug 20 09:45:43 um700vol volumio[879]: info: Exploding uri music-library/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons in service mpd Aug 20 09:45:43 um700vol volumio[879]: info: sendMpdCommand stop took 6 milliseconds Aug 20 09:45:43 um700vol volumio[879]: info: Aug 20 09:45:43 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:45:43 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:45:43 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:45:43 um700vol volumio[879]: info: Aug 20 09:45:43 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:45:43 um700vol volumio[879]: info: ControllerMpd::getState Aug 20 09:45:43 um700vol volumio[879]: verbose: ControllerMpd::sendMpdCommand status Aug 20 09:45:43 um700vol volumio[879]: info: Aug 20 09:45:43 um700vol volumio[879]: ---------------------------- MPD announces state update: player Aug 20 09:45:43 um700vol volumio[879]: info: ALBUMART /albumart?cacheid=512&web=Felix%20Mendelssohn/Violin%20Concertos/medium&path=%2Fmnt%2FNAS%2Fssd0-music%2FClassical%2FSibelius%2FInternet%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons.cue&metadata=false Aug 20 09:45:43 um700vol volumio[879]: info: URI /mnt/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons.cue Aug 20 09:45:43 um700vol volumio[879]: info: ALBUMART /albumart?cacheid=512&web=Felix%20Mendelssohn/Violin%20Concertos/medium&path=%2Fmnt%2FNAS%2Fssd0-music%2FClassical%2FSibelius%2FInternet%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons%2FSibelius%2C%20Mendelssohn%20-%20Violin%20Concertos%20-%20Sarah%20Chang%2C%20Berliner%20Philharmoniker%2C%20Mariss%20Jansons.cue&metadata=false Aug 20 09:45:43 um700vol volumio[879]: info: URI /mnt/NAS/ssd0-music/Classical/Sibelius/Internet/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons/Sibelius, Mendelssohn - Violin Concertos - Sarah Chang, Berliner Philharmoniker, Mariss Jansons.cue Aug 20 09:45:43 um700vol volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 09:45:43 um700vol volumio[879]: Error: Unable to resolve or reject the same promise twice Aug 20 09:45:43 um700vol volumio[879]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 20 09:45:43 um700vol volumio[879]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 20 09:45:43 um700vol volumio[879]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 20 09:45:43 um700vol volumio[879]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 20 09:45:43 um700vol volumio[879]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 20 09:45:43 um700vol volumio[879]: at Socket.emit (events.js:400:28) Aug 20 09:45:43 um700vol volumio[879]: at addChunk (internal/streams/readable.js:293:12) Aug 20 09:45:43 um700vol volumio[879]: at readableAddChunk (internal/streams/readable.js:263:11) Aug 20 09:45:43 um700vol volumio[879]: at Socket.Readable.push (internal/streams/readable.js:206:10) Aug 20 09:45:43 um700vol volumio[879]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 20 09:45:43 um700vol volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 09:45:43 um700vol sudo[12978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 08:44 Aug 20 09:45:43 um700vol sudo[12978]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 04:30:21 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2a4e15ea1ce484f8b0c1c7a110d46b73"