-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Sun 2024-10-27 11:51:20 +07. -- Oct 27 11:50:00 volumio systemd[1]: Started Music Player Daemon. Oct 27 11:50:00 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Oct 27 11:50:00 volumio volumio[901]: error: updateQueue error: null Oct 27 11:50:00 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Oct 27 11:50:00 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:00 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:00 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:00 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:00 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:00 volumio volumio[901]: info: ControllerMpd::resume Oct 27 11:50:00 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:00 volumio volumio[901]: info: sendMpdCommand play took 1 milliseconds Oct 27 11:50:02 volumio volumio[901]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 27 11:50:02 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume Oct 27 11:50:02 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 11:50:02 volumio volumio[901]: info: VolumeController:: Volume=86 Mute =false Oct 27 11:50:02 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:02 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:02 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:02 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:03 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Oct 27 11:50:03 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Oct 27 11:50:03 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 11:50:03 volumio volumio[901]: info: VolumeController:: Volume=86 Mute =false Oct 27 11:50:03 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:03 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:03 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:03 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:04 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:04 volumio volumio[901]: info: ControllerMpd::resume Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand play took 1 milliseconds Oct 27 11:50:04 volumio volumio[901]: info: CoreCommandRouter::volumioNext Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::next Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::stop Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:04 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:04 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::serviceStop Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:04 volumio volumio[901]: info: CoreCommandRouter::serviceStop Oct 27 11:50:04 volumio volumio[901]: info: ControllerMpd::stop Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand stop took 4 milliseconds Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::clearAddPlayTracks USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 11:50:04 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Oct 27 11:50:04 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand stop took 1 milliseconds Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 11:50:04 volumio volumio[901]: info: Oct 27 11:50:04 volumio volumio[901]: ---------------------------- MPD announces system playlist update Oct 27 11:50:04 volumio volumio[901]: info: Ignoring MPD Status Update Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand clear took 1 milliseconds Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac" Oct 27 11:50:04 volumio volumio[901]: error: updateQueue error: null Oct 27 11:50:04 volumio volumio[901]: info: Oct 27 11:50:04 volumio volumio[901]: ---------------------------- MPD announces system playlist update Oct 27 11:50:04 volumio volumio[901]: info: Ignoring MPD Status Update Oct 27 11:50:04 volumio volumio[901]: info: ------------------------------ 2ms Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand add "USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac" took 1 milliseconds Oct 27 11:50:04 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:04 volumio volumio[901]: info: ------------------------------ 2ms Oct 27 11:50:04 volumio volumio[901]: info: sendMpdCommand play took 2 milliseconds Oct 27 11:50:05 volumio volumio[901]: info: Oct 27 11:50:05 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:05 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:05 volumio volumio[901]: info: Oct 27 11:50:05 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:05 volumio volumio[901]: info: sendMpdCommand status took 11 milliseconds Oct 27 11:50:05 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:05 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:05 volumio volumio[901]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 27 11:50:05 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:05 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:05 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:05 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:05 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:05 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Oct 27 11:50:05 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:05 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:05 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:05 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:05 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:05 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:05 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:05 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:05 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:05 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:05 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:05 volumio volumio[901]: info: ------------------------------ 26ms Oct 27 11:50:05 volumio volumio[901]: info: ------------------------------ 24ms Oct 27 11:50:05 volumio volumio[901]: Error saving last played file: Error: EROFS: read-only file system, open '/data/laststates.json' Oct 27 11:50:06 volumio nmbd[2381]: [2024/10/27 11:50:06.817923, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Oct 27 11:50:06 volumio nmbd[2381]: wins_write_database: Can't open /var/lib/samba/wins.dat.2381: Read-only file system Oct 27 11:50:07 volumio volumio[901]: info: CoreCommandRouter::volumioPrevious Oct 27 11:50:07 volumio volumio[901]: info: CoreStateMachine::previous Oct 27 11:50:07 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:07 volumio volumio[901]: info: ControllerMpd::seek Oct 27 11:50:07 volumio volumio[901]: error: null Oct 27 11:50:07 volumio volumio[901]: info: Oct 27 11:50:07 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:07 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:07 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:07 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:07 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:07 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:07 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:07 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:07 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:07 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2995 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:07 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:07 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:07 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:07 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Oct 27 11:50:07 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:07 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:07 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:07 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:07 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:07 volumio volumio[901]: info: ------------------------------ 21ms Oct 27 11:50:08 volumio volumio[901]: info: Oct 27 11:50:08 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:08 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:08 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:08 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 11:50:08 volumio volumio[901]: info: sendMpdCommand status took 6 milliseconds Oct 27 11:50:08 volumio kernel: usb 1-1.4: timeout: still 1 active urbs on EP #2 Oct 27 11:50:08 volumio kernel: ------------[ cut here ]------------ Oct 27 11:50:08 volumio kernel: WARNING: CPU: 1 PID: 2373 at drivers/usb/core/urb.c:379 usb_submit_urb+0x4fc/0x578 Oct 27 11:50:08 volumio kernel: URB 5ab96a39 submitted while active Oct 27 11:50:08 volumio kernel: Modules linked in: cmac bnep nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables nfnetlink 8021q garp stp llc snd_usb_audio snd_hwdep snd_usbmidi_lib snd_seq_midi snd_seq_midi_event snd_rawmidi hci_uart btbcm snd_bcm2835(C) brcmfmac snd_pcm bluetooth rpivid_hevc(C) bcm2835_codec(C) snd_seq bcm2835_isp(C) sg v4l2_mem2mem brcmutil snd_seq_device cfg80211 bcm2835_v4l2(C) snd_timer bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 ecdh_generic ecc videobuf2_common snd i2c_bcm2835 videodev raspberrypi_hwmon rfkill raspberrypi_gpiomem vc_sm_cma(C) mc uio_pdrv_genirq nvmem_rmem uio i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm drm_panel_orientation_quirks backlight Oct 27 11:50:08 volumio kernel: CPU: 1 PID: 2373 Comm: rtio Tainted: G C 6.1.69-v7l+ #1710 Oct 27 11:50:08 volumio kernel: Hardware name: BCM2711 Oct 27 11:50:08 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Oct 27 11:50:08 volumio kernel: show_stack from dump_stack_lvl+0x68/0x8c Oct 27 11:50:08 volumio kernel: dump_stack_lvl from __warn+0x84/0x124 Oct 27 11:50:08 volumio kernel: __warn from warn_slowpath_fmt+0x11c/0x184 Oct 27 11:50:08 volumio kernel: warn_slowpath_fmt from usb_submit_urb+0x4fc/0x578 Oct 27 11:50:08 volumio kernel: usb_submit_urb from snd_usb_endpoint_start+0x138/0x338 [snd_usb_audio] Oct 27 11:50:08 volumio kernel: snd_usb_endpoint_start [snd_usb_audio] from start_endpoints+0x68/0xb4 [snd_usb_audio] Oct 27 11:50:08 volumio kernel: start_endpoints [snd_usb_audio] from snd_usb_substream_playback_trigger+0x100/0x170 [snd_usb_audio] Oct 27 11:50:08 volumio kernel: snd_usb_substream_playback_trigger [snd_usb_audio] from snd_pcm_action_single+0x40/0x80 [snd_pcm] Oct 27 11:50:08 volumio kernel: snd_pcm_action_single [snd_pcm] from snd_pcm_action_lock_irq+0x30/0x44 [snd_pcm] Oct 27 11:50:08 volumio kernel: snd_pcm_action_lock_irq [snd_pcm] from snd_pcm_ioctl+0x958/0x1024 [snd_pcm] Oct 27 11:50:08 volumio kernel: snd_pcm_ioctl [snd_pcm] from sys_ioctl+0x120/0x984 Oct 27 11:50:08 volumio kernel: sys_ioctl from __sys_trace_return+0x0/0x10 Oct 27 11:50:08 volumio kernel: Exception stack(0xf09f5fa8 to 0xf09f5ff0) Oct 27 11:50:08 volumio kernel: 5fa0: a8c0fae0 b4e3b40c 00000011 00004142 a8c0fc48 a8c0fc48 Oct 27 11:50:08 volumio kernel: 5fc0: a8c0fae0 b4e3b40c ffffd120 00000036 00002ee0 00000000 00000000 aaa0a2f0 Oct 27 11:50:08 volumio kernel: 5fe0: b4e3b818 aaa0a29c b4db2cd8 b2b5ee3c Oct 27 11:50:08 volumio kernel: ---[ end trace 0000000000000000 ]--- Oct 27 11:50:08 volumio kernel: usb 1-1.4: cannot submit urb 0, error -16: unknown error Oct 27 11:50:08 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:08 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:08 volumio volumio[901]: info: sendMpdCommand clearerror took 2 milliseconds Oct 27 11:50:08 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:08 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:08 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:08 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:08 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:08 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1202,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2941 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:08 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:08 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 27 11:50:08 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:08 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:08 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:08 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:08 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:08 volumio volumio[901]: info: ------------------------------ 17ms Oct 27 11:50:09 volumio kernel: usb 1-1.4: timeout: still 1 active urbs on EP #2 Oct 27 11:50:11 volumio volumio[901]: info: CoreCommandRouter::volumioPause Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::pause Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::servicePause Oct 27 11:50:11 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:11 volumio volumio[901]: info: CoreCommandRouter::servicePause Oct 27 11:50:11 volumio volumio[901]: info: ControllerMpd::pause Oct 27 11:50:11 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 11:50:11 volumio volumio[901]: info: Oct 27 11:50:11 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:11 volumio volumio[901]: info: sendMpdCommand pause took 7 milliseconds Oct 27 11:50:11 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:11 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:11 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:11 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:11 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:11 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:11 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:11 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:11 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:11 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:11 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2205,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:11 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:11 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:11 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:11 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:11 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:11 volumio volumio[901]: info: ------------------------------ 14ms Oct 27 11:50:17 volumio volumio[901]: info: VolumeController::SetAlsaVolume89 Oct 27 11:50:17 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:17 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:17 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:17 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::volumioPause Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::pause Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::servicePause Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::servicePause Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::pause Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 11:50:19 volumio volumio[901]: info: Oct 27 11:50:19 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand pause took 2 milliseconds Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9828,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2859 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:19 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:19 volumio volumio[901]: info: ------------------------------ 13ms Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::resume Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:19 volumio volumio[901]: info: Oct 27 11:50:19 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand play took 6 milliseconds Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand status took 0 milliseconds Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:19 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:19 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:19 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10825,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2503 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:19 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:19 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:19 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:19 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:19 volumio volumio[901]: info: ------------------------------ 13ms Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 11:50:26 volumio volumio[901]: info: Discovery: Getting this device information Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:50:26 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::volumioPause Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::pause Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::servicePause Oct 27 11:50:26 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::servicePause Oct 27 11:50:26 volumio volumio[901]: info: ControllerMpd::pause Oct 27 11:50:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 11:50:26 volumio volumio[901]: info: Oct 27 11:50:26 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:26 volumio volumio[901]: info: sendMpdCommand pause took 1 milliseconds Oct 27 11:50:26 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:26 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:26 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:26 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:26 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:26 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:26 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:26 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:26 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17697,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2967 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:26 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:26 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:26 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:26 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:26 volumio volumio[901]: info: ------------------------------ 13ms Oct 27 11:50:26 volumio nmbd[2383]: [2024/10/27 11:50:26.845312, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Oct 27 11:50:26 volumio nmbd[2383]: wins_write_database: Can't open /var/lib/samba/wins.dat.2383: Read-only file system Oct 27 11:50:28 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:28 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:28 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:28 volumio volumio[901]: info: ControllerMpd::resume Oct 27 11:50:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:28 volumio volumio[901]: info: Oct 27 11:50:28 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:28 volumio volumio[901]: info: sendMpdCommand play took 6 milliseconds Oct 27 11:50:28 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:28 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:28 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:28 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:28 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:28 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:28 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:28 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:28 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:28 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18695,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2962 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:28 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:28 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:28 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:28 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:28 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:28 volumio volumio[901]: info: ------------------------------ 15ms Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::volumioSeek Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::seek Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: info: TRACKBLOCK {"uri":"mnt/USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","service":"mpd","name":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=7&web=Jacob%20Collier%20feat.%20Lindsey%20Lomis/Djesse%2C%20Vol.%204/extralarge&path=%2FUSB%2F01DB2827E61B0E50%2FMusic%2Falbums%2FJacob%20Collier%2F2024%20-%20Djesse%20Vol.%204&metadata=false","duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: info: ControllerMpd::seek Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:34 volumio volumio[901]: error: null Oct 27 11:50:34 volumio volumio[901]: info: Oct 27 11:50:34 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:34 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:34 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:34 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:34 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:34 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:34 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:34 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:34 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80000,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2953 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:34 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:34 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:34 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:34 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:34 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:34 volumio volumio[901]: info: ------------------------------ 46ms Oct 27 11:50:36 volumio volumio[901]: info: CoreCommandRouter::volumioPrevious Oct 27 11:50:36 volumio volumio[901]: info: CoreStateMachine::previous Oct 27 11:50:36 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:36 volumio volumio[901]: info: ControllerMpd::seek Oct 27 11:50:36 volumio volumio[901]: error: null Oct 27 11:50:36 volumio volumio[901]: info: Oct 27 11:50:36 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:36 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:36 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:36 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 27 11:50:36 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:36 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:36 volumio volumio[901]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 27 11:50:36 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:36 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:36 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:36 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:36 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3173 Kbps","isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:36 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:36 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:36 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:36 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Oct 27 11:50:36 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:36 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:36 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:36 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:36 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:36 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:36 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:36 volumio volumio[901]: info: ------------------------------ 25ms Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::volumioPrevious Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::previous Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::stop Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::serviceStop Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::serviceStop Oct 27 11:50:38 volumio volumio[901]: info: ControllerMpd::stop Oct 27 11:50:38 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 11:50:38 volumio volumio[901]: info: sendMpdCommand stop took 17 milliseconds Oct 27 11:50:38 volumio volumio[901]: info: Oct 27 11:50:38 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:38 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:38 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:38 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:38 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:38 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:38 volumio volumio[901]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 27 11:50:38 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:38 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cinnamon Crush","artist":"Jacob Collier feat. Lindsey Lomis","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.05. Cinnamon Crush.flac","trackType":"flac"} Oct 27 11:50:38 volumio volumio[901]: verbose: CURRENT POSITION 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::syncState stateService stop Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:38 volumio volumio[901]: info: No code Oct 27 11:50:38 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:38 volumio volumio[901]: info: CorePlayQueue::getTrack 4 Oct 27 11:50:38 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:38 volumio volumio[901]: info: ------------------------------ 23ms Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::clearAddPlayTracks USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand stop took 0 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 11:50:39 volumio volumio[901]: info: Oct 27 11:50:39 volumio volumio[901]: ---------------------------- MPD announces system playlist update Oct 27 11:50:39 volumio volumio[901]: info: Ignoring MPD Status Update Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand clear took 1 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand add "USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac" Oct 27 11:50:39 volumio volumio[901]: error: updateQueue error: null Oct 27 11:50:39 volumio volumio[901]: info: Oct 27 11:50:39 volumio volumio[901]: ---------------------------- MPD announces system playlist update Oct 27 11:50:39 volumio volumio[901]: info: Ignoring MPD Status Update Oct 27 11:50:39 volumio volumio[901]: info: ------------------------------ 4ms Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand add "USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac" took 2 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:50:39 volumio volumio[901]: info: ------------------------------ 3ms Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand play took 3 milliseconds Oct 27 11:50:39 volumio volumio[901]: info: Oct 27 11:50:39 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:39 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:39 volumio volumio[901]: info: Oct 27 11:50:39 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:39 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand status took 2 milliseconds Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:39 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WELLLL","artist":"Jacob Collier","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac","trackType":"flac"} Oct 27 11:50:39 volumio volumio[901]: verbose: CURRENT POSITION 3 Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus stop Oct 27 11:50:39 volumio volumio[901]: info: ------------------------------ 11ms Oct 27 11:50:39 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 27 11:50:39 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:39 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:39 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WELLLL","artist":"Jacob Collier","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac","trackType":"flac"} Oct 27 11:50:39 volumio volumio[901]: verbose: CURRENT POSITION 3 Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Oct 27 11:50:39 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:39 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:39 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:39 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:39 volumio volumio[901]: info: ------------------------------ 30ms Oct 27 11:50:39 volumio volumio[901]: Error saving last played file: Error: EROFS: read-only file system, open '/data/laststates.json' Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 27 11:50:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 11:50:46 volumio nmbd[2426]: [2024/10/27 11:50:46.873076, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Oct 27 11:50:46 volumio nmbd[2426]: wins_write_database: Can't open /var/lib/samba/wins.dat.2426: Read-only file system Oct 27 11:50:50 volumio volumio[901]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 27 11:50:50 volumio volumio[901]: info: Preparing to save Alsa Options, stopping services first Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:50:50 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::volumioPause Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::pause Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::servicePause Oct 27 11:50:50 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::servicePause Oct 27 11:50:50 volumio volumio[901]: info: ControllerMpd::pause Oct 27 11:50:50 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 11:50:50 volumio volumio[901]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB-C adapter"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 11:50:50 volumio volumio[901]: info: Setting mixer PCM for card USB-C adapter Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 27 11:50:50 volumio volumio[901]: info: Updating Volume Controller Parameters: Device: 5 Name: USB-C adapter Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 27 11:50:50 volumio volumio[901]: info: Disabling external Volume Control Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 11:50:50 volumio volumio[901]: info: Preparing to generate the ALSA configuration file Oct 27 11:50:50 volumio volumio[901]: info: Oct 27 11:50:50 volumio volumio[901]: ---------------------------- MPD announces state update: player Oct 27 11:50:50 volumio volumio[901]: info: sendMpdCommand pause took 188 milliseconds Oct 27 11:50:50 volumio volumio[901]: info: ControllerMpd::getState Oct 27 11:50:50 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Oct 27 11:50:50 volumio volumio[901]: info: sendMpdCommand status took 1 milliseconds Oct 27 11:50:50 volumio volumio[901]: verbose: ControllerMpd::parseState Oct 27 11:50:50 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 11:50:50 volumio volumio[901]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 11:50:50 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Oct 27 11:50:50 volumio volumio[901]: info: ControllerMpd::pushState Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::servicePushState Oct 27 11:50:50 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:50 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":11991,"duration":158,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3044 Kbps","isStreaming":false,"title":"WELLLL","artist":"Jacob Collier","album":"Djesse, Vol. 4","uri":"USB/01DB2827E61B0E50/Music/albums/Jacob Collier/2024 - Djesse Vol. 4/01.04. WELLLL.flac","trackType":"flac"} Oct 27 11:50:50 volumio volumio[901]: verbose: CURRENT POSITION 3 Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:50 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Oct 27 11:50:50 volumio volumio[901]: info: ------------------------------ 13ms Oct 27 11:50:50 volumio volumio[901]: info: Asound.conf file unchanged, so no further update is needed Oct 27 11:50:50 volumio volumio[901]: info: Output device has changed, restarting MPD Oct 27 11:50:50 volumio sudo[2437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 27 11:50:50 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:50:50 volumio volumio[901]: info: Output device has changed, restarting Shairport Sync Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 11:50:50 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Oct 27 11:50:50 volumio sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 27 11:50:50 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: Stopping Music Player Daemon... Oct 27 11:50:50 volumio volumio[901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 27 11:50:50 volumio systemd[1]: mpd.service: Succeeded. Oct 27 11:50:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 11:50:50 volumio systemd[1]: Starting Music Player Daemon... Oct 27 11:50:50 volumio volumio[901]: info: Error setting mpd conf file perms: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/mpd.conf Oct 27 11:50:50 volumio volumio[901]: /bin/chmod: changing permissions of '/etc/mpd.conf': Read-only file system Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 11:50:50 volumio volumio[901]: info: Could not write mpd.conf:Error: EROFS: read-only file system, open '/etc/mpd.conf' Oct 27 11:50:50 volumio volumio[901]: info: VolumeController:: Volume=56 Mute =false Oct 27 11:50:50 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:50 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 11:50:50 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:50 volumio volumio[901]: info: Starting Shairport Sync Oct 27 11:50:50 volumio sudo[2447]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 27 11:50:50 volumio sudo[2447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:50:50 volumio sudo[2447]: pam_unix(sudo:session): session closed for user root Oct 27 11:50:50 volumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 27 11:50:50 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: dev-sdc1.device: Cannot add dependency job, ignoring: Unit dev-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host2-target2:0:0-2:0:0:0-block-sdc-sdc1.device not found. Oct 27 11:50:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 27 11:50:50 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 27 11:50:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 27 11:50:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 27 11:50:50 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root Oct 27 11:50:50 volumio volumio[901]: info: Shairport-Sync Started Oct 27 11:50:51 volumio volumio[901]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/audio_interface/alsa_controller/config.json' Oct 27 11:50:51 volumio mpd[2460]: Oct 27 11:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 27 11:50:51 volumio systemd[1]: Started Music Player Daemon. Oct 27 11:50:51 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Oct 27 11:50:51 volumio volumio[901]: error: updateQueue error: null Oct 27 11:50:54 volumio volumio[901]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 27 11:50:54 volumio volumio[901]: info: CoreStateMachine::getcurrentVolume Oct 27 11:50:54 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 11:50:54 volumio volumio[901]: info: VolumeController:: Volume=56 Mute =false Oct 27 11:50:54 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:54 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:54 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:54 volumio volumio[901]: info: CoreStateMachine::updateTrackBlock Oct 27 11:50:54 volumio volumio[901]: info: CorePlayQueue::getTrackBlock Oct 27 11:50:54 volumio volumio[901]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 11:50:54 volumio volumio[901]: info: VolumeController:: Volume=56 Mute =false Oct 27 11:50:54 volumio volumio[901]: info: CoreStateMachine::pushState Oct 27 11:50:54 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:54 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Oct 27 11:50:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 11:50:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 11:50:56 volumio volumio[901]: info: Discovery: Getting this device information Oct 27 11:50:56 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:50:56 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:50:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 11:51:06 volumio nmbd[2471]: [2024/10/27 11:51:06.901702, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Oct 27 11:51:06 volumio nmbd[2471]: wins_write_database: Can't open /var/lib/samba/wins.dat.2471: Read-only file system Oct 27 11:51:07 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Oct 27 11:51:07 volumio volumio[901]: info: CoreStateMachine::play index undefined Oct 27 11:51:07 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 11:51:07 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:07 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Oct 27 11:51:07 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:07 volumio volumio[901]: info: ControllerMpd::resume Oct 27 11:51:07 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Oct 27 11:51:07 volumio volumio[901]: info: sendMpdCommand play took 1 milliseconds Oct 27 11:51:14 volumio ntpd[795]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 11:51:15 volumio volumio[901]: info: Discovery: Getting this device information Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:15 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 11:51:15 volumio volumio[901]: verbose: New Socket.io Connection to 192.168.1.77:3000 from 192.168.1.63 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:15 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 27 11:51:15 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 27 11:51:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 11:51:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 11:51:16 volumio volumio[901]: info: Discovery: Getting this device information Oct 27 11:51:16 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:16 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:16 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 11:51:19 volumio sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 11:51:19 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:51:19 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Oct 27 11:51:19 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 11:51:19 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:51:19 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Oct 27 11:51:19 volumio volumio[901]: verbose: New Socket.io Connection to 192.168.1.77 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 11; BE2029 Build/RKQ1.201217.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 27 11:51:19 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 11:51:19 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 11:51:19 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:51:19 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Oct 27 11:51:19 volumio sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 11:51:19 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 11:51:19 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root Oct 27 11:51:20 volumio volumio[901]: verbose: New Socket.io Connection to 192.168.1.77 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 11; BE2029 Build/RKQ1.201217.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:20 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 11:51:20 volumio volumio[901]: info: Received Get System Info Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 11:51:20 volumio volumio[901]: info: Discovery: Getting this device information Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:20 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 11:51:20 volumio volumio[901]: info: CoreCommandRouter::volumioGetState Oct 27 11:51:20 volumio volumio[901]: info: CorePlayQueue::getTrack 3 Oct 27 11:51:20 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 11:51:20 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #1083745: lblock 0: comm node: error -5 reading directory block Oct 27 11:51:20 volumio volumio[901]: Error: EIO: i/o error, scandir '/data/plugins/' Oct 27 11:51:20 volumio volumio[901]: at Object.readdirSync (fs.js:1021:3) Oct 27 11:51:20 volumio volumio[901]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Oct 27 11:51:20 volumio volumio[901]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Oct 27 11:51:20 volumio volumio[901]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1328:45) Oct 27 11:51:20 volumio volumio[901]: at Socket.emit (events.js:315:20) Oct 27 11:51:20 volumio volumio[901]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 27 11:51:20 volumio volumio[901]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 27 11:51:20 volumio volumio[901]: errno: -5, Oct 27 11:51:20 volumio volumio[901]: syscall: 'scandir', Oct 27 11:51:20 volumio volumio[901]: code: 'EIO', Oct 27 11:51:20 volumio volumio[901]: path: '/data/plugins/' Oct 27 11:51:20 volumio volumio[901]: } Oct 27 11:51:20 volumio volumio[901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 11:51:20 volumio sudo[2493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 11:50 Oct 27 11:51:20 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"