Oct 12 15:34:37 primo vtcs[4073]: [close:85] Entering Oct 12 15:34:37 primo volumio[3406]: info: Pushing metadata Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::servicePushState Oct 12 15:34:37 primo volumio[3406]: info: CoreStateMachine::pushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioPushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioGetState Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output update for this device Oct 12 15:34:37 primo vtcs[4073]: [feedThread:276] Exiting Oct 12 15:34:37 primo vtcs[4073]: [close:100] Exiting Oct 12 15:34:37 primo kernel: asoc-aml-card auge_sound: tdm playback stop Oct 12 15:34:37 primo kernel: spdif_a is set to disable Oct 12 15:34:37 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 12 15:34:37 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Oct 12 15:34:37 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 12 15:34:37 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.882+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" state=STATUS_PLAYING positionMs=223000 volume=100 Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.883+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" id= title="Puccini: La bohème, Act I: O soave fanciulla (Love Duet)" Oct 12 15:34:37 primo volumio[3406]: info: Signalling Playback active due to playback status change Oct 12 15:34:37 primo vtcs[4073]: [close:85] Entering Oct 12 15:34:37 primo vtcs[4073]: [close:100] Exiting Oct 12 15:34:37 primo vtcs[4073]: [open:50] Opening with sample rate: 192000, type: 3 Oct 12 15:34:37 primo vtcs[4073]: [start:106] Entering Oct 12 15:34:37 primo vtcs[4073]: [start:113] Reopening PCM device... Oct 12 15:34:37 primo volumio[3406]: info: Updating RAAT Signal Path Oct 12 15:34:37 primo kernel: aml_tdm_open Oct 12 15:34:37 primo kernel: Not init audio effects Oct 12 15:34:37 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 12 15:34:37 primo kernel: set mclk:49152000, mpll:98304000, get mclk:49151901, mpll:98303801 Oct 12 15:34:37 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2eee18, id(1), clksel(1) Oct 12 15:34:37 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Oct 12 15:34:37 primo kernel: dump_pcm_setting(ffffffc03d2eee18) Oct 12 15:34:37 primo kernel: pcm_mode(1) Oct 12 15:34:37 primo kernel: sysclk(49152000) Oct 12 15:34:37 primo kernel: sysclk_bclk_ratio(4) Oct 12 15:34:37 primo kernel: bclk(12288000) Oct 12 15:34:37 primo kernel: bclk_lrclk_ratio(64) Oct 12 15:34:37 primo kernel: lrclk(192000) Oct 12 15:34:37 primo kernel: tx_mask(0x3) Oct 12 15:34:37 primo kernel: rx_mask(0x3) Oct 12 15:34:37 primo kernel: slots(2) Oct 12 15:34:37 primo kernel: slot_width(32) Oct 12 15:34:37 primo kernel: lane_mask_in(0x2) Oct 12 15:34:37 primo kernel: lane_mask_out(0x1) Oct 12 15:34:37 primo kernel: lane_oe_mask_in(0x0) Oct 12 15:34:37 primo kernel: lane_oe_mask_out(0x0) Oct 12 15:34:37 primo kernel: lane_lb_mask_in(0x0) Oct 12 15:34:37 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Oct 12 15:34:37 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Oct 12 15:34:37 primo kernel: set mclk:49152000, mpll:98304000, get mclk:49151901, mpll:98303801 Oct 12 15:34:37 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Oct 12 15:34:37 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Oct 12 15:34:37 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Oct 12 15:34:37 primo kernel: aml_tdm_prepare(), reset fddr Oct 12 15:34:37 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Oct 12 15:34:37 primo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Oct 12 15:34:37 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 12 15:34:37 primo kernel: tdm playback mute: 0, lane_cnt = 8 Oct 12 15:34:37 primo kernel: aml_tdm_prepare(), reset fddr Oct 12 15:34:37 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Oct 12 15:34:37 primo kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Oct 12 15:34:37 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 12 15:34:37 primo kernel: tdm playback mute: 0, lane_cnt = 8 Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::servicePushState Oct 12 15:34:37 primo volumio[3406]: info: CoreStateMachine::pushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioPushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioGetState Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output update for this device Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output Oct 12 15:34:37 primo vtcs[4073]: [start:115] PCM opened successfully. Oct 12 15:34:37 primo vtcs[4073]: [start:120] Exiting Oct 12 15:34:37 primo vtcs[4073]: [feedThread:223] Entering Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.905+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" state=STATUS_PLAYING positionMs=223000 volume=100 Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.906+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" id= title="Puccini: La bohème, Act I: O soave fanciulla (Love Duet)" Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::servicePushState Oct 12 15:34:37 primo volumio[3406]: info: CoreStateMachine::pushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioPushState Oct 12 15:34:37 primo volumio[3406]: info: CoreCommandRouter::volumioGetState Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output update for this device Oct 12 15:34:37 primo volumio[3406]: info: MRS: Pushing multiroomSync output Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.917+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" state=STATUS_PLAYING positionMs=0 volume=100 Oct 12 15:34:37 primo volumio5-onboarding[4308]: time=2026-10-12T15:34:37.918+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.87:54002 @ 0x2870990" id= title="Puccini: La bohème, Act I: O soave fanciulla (Love Duet)" Oct 12 15:34:37 primo kernel: asoc-aml-card auge_sound: tdm playback enable Oct 12 15:34:37 primo kernel: spdif_a is set to enable Oct 12 15:34:37 primo volumio[3406]: info: Signalling Playback active due to playback status change Oct 12 15:34:37 primo volumio[3406]: info: Signalling Playback active due to playback status change Oct 12 15:34:37 primo volumio[3406]: info: Updating RAAT Signal Path Oct 12 15:34:37 primo volumio[3406]: info: Updating RAAT Signal Path Oct 12 15:34:51 primo volumio[3406]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 15:34:51 primo volumio[3406]: info: CoreStateMachine::removeQueueItem Oct 12 15:34:51 primo volumio[3406]: info: CoreStateMachine::stop Oct 12 15:34:51 primo volumio[3406]: info: CoreStateMachine::serviceStop Oct 12 15:34:51 primo volumio[3406]: info: CoreCommandRouter::serviceStop Oct 12 15:34:51 primo volumio[3406]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:34:51 primo volumio[3406]: TypeError: Cannot read properties of undefined (reading 'then') Oct 12 15:34:51 primo volumio[3406]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Oct 12 15:34:51 primo volumio[3406]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Oct 12 15:34:51 primo volumio[3406]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Oct 12 15:34:51 primo volumio[3406]: at Socket.emit (node:events:514:28) Oct 12 15:34:51 primo volumio[3406]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 12 15:34:51 primo volumio[3406]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 12 15:34:51 primo volumio[3406]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 15:34:51 primo vtcs[4073]: [close:85] Entering Oct 12 15:34:51 primo kernel: asoc-aml-card auge_sound: tdm playback stop Oct 12 15:34:51 primo kernel: spdif_a is set to disable Oct 12 15:34:51 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 12 15:34:51 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Oct 12 15:34:51 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 12 15:34:51 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 12 15:34:51 primo vtcs[4073]: [feedThread:276] Exiting Oct 12 15:34:51 primo vtcs[4073]: [close:100] Exiting Oct 12 15:34:52 primo vtcs[4073]: [2026-10-12 15:34:52.148] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:0 Oct 12 15:34:52 primo sudo[32751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 15:33' Oct 12 15:34:52 primo sudo[32751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun May 17 17:32:08 UTC 2026" VOLUMIO_VERSION="4.158" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="43d420a3aa41c50690ebfe378df38e2b"