-- Logs begin at Wed 2024-09-18 00:06:54 JST, end at Thu 2024-10-10 20:45:39 JST. -- Oct 10 20:45:00 volumio5 volumio[1086]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 10 20:45:00 volumio5 volumio[1086]: info: Preparing to save Alsa Options, stopping services first Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 10 20:45:00 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::volumioPause Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreStateMachine::pause Oct 10 20:45:00 volumio5 volumio[1086]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"picade-hat","label":"Picade HAT"}} Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:00 volumio5 volumio[1086]: info: Enabling I2S DAC: Picade HAT Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:00 volumio5 sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 10 20:45:00 volumio5 sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:00 volumio5 sudo[2162]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:00 volumio5 sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r allo-boss-dac-pcm512x-audio Oct 10 20:45:00 volumio5 sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:00 volumio5 kernel: ------------[ cut here ]------------ Oct 10 20:45:00 volumio5 kernel: clk-hifiberry-dacpro already disabled Oct 10 20:45:00 volumio5 kernel: WARNING: CPU: 1 PID: 2166 at drivers/clk/clk.c:1038 clk_core_disable+0x23c/0x278 Oct 10 20:45:00 volumio5 kernel: Modules linked in: snd_soc_pcm512x_i2c snd_soc_pcm512x regmap_i2c designware_i2s clk_hifiberry_dacpro 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 vc4 spidev hci_uart snd_soc_hdmi_codec btbcm bluetooth snd_soc_core brcmfmac brcmutil snd_compress snd_pcm_dmaengine snd_pcm cfg80211 snd_seq rpivid_hevc(C) ecdh_generic pisp_be aes_ce_blk ecc snd_seq_device aes_ce_cipher v4l2_mem2mem snd_timer videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 ghash_ce rfkill gf128mul libaes videobuf2_common videodev snd sha2_ce sha256_arm64 sha1_ce sg gpio_keys spi_bcm2835 mc raspberrypi_hwmon i2c_brcmstb pwm_fan i2c_designware_platform i2c_designware_core rp1_adc raspberrypi_gpiomem uio_pdrv_genirq uio nvmem_rmem i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched Oct 10 20:45:00 volumio5 kernel: drm_shmem_helper drm drm_panel_orientation_quirks backlight Oct 10 20:45:00 volumio5 kernel: CPU: 1 PID: 2166 Comm: dtoverlay Tainted: G C 6.1.69-v8+ #1710 Oct 10 20:45:00 volumio5 kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Oct 10 20:45:00 volumio5 kernel: pstate: 404000c9 (nZcv daIF +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Oct 10 20:45:00 volumio5 kernel: pc : clk_core_disable+0x23c/0x278 Oct 10 20:45:00 volumio5 kernel: lr : clk_core_disable+0x23c/0x278 Oct 10 20:45:00 volumio5 kernel: sp : ffffffc008b438e0 Oct 10 20:45:00 volumio5 kernel: x29: ffffffc008b438e0 x28: ffffff812187cb00 x27: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x26: ffffff812187cb48 x25: ffffffd00d7380b0 x24: ffffff81056371b0 Oct 10 20:45:00 volumio5 kernel: x23: 0000000000000000 x22: ffffffd00cf84e38 x21: ffffffcfd23d1038 Oct 10 20:45:00 volumio5 kernel: x20: ffffff8121954100 x19: ffffff8121954100 x18: ffffff8100178710 Oct 10 20:45:00 volumio5 kernel: x17: 0000000000000000 x16: ffffffd00c707488 x15: ffffff813bd95628 Oct 10 20:45:00 volumio5 kernel: x14: 0000000000000000 x13: 64656c6261736964 x12: ffffffd00d4fc7f8 Oct 10 20:45:00 volumio5 kernel: x11: 0000000000000003 x10: 0000000000000001 x9 : ffffffd00c0f5684 Oct 10 20:45:00 volumio5 kernel: x8 : 0000000000017fe8 x7 : c0000000ffffefff x6 : ffffffd00d4e47b8 Oct 10 20:45:00 volumio5 kernel: x5 : ffffff81fef38a10 x4 : 0000000000000000 x3 : ffffffb1f1dff000 Oct 10 20:45:00 volumio5 kernel: x2 : ffffff8135f1dd00 x1 : 59920fcd95cc9300 x0 : 0000000000000000 Oct 10 20:45:00 volumio5 kernel: Call trace: Oct 10 20:45:00 volumio5 kernel: clk_core_disable+0x23c/0x278 Oct 10 20:45:00 volumio5 kernel: clk_core_disable_lock+0x2c/0x48 Oct 10 20:45:00 volumio5 kernel: clk_disable+0x28/0x38 Oct 10 20:45:00 volumio5 kernel: pcm512x_remove+0x54/0x78 [snd_soc_pcm512x] Oct 10 20:45:00 volumio5 kernel: pcm512x_i2c_remove+0x1c/0x28 [snd_soc_pcm512x_i2c] Oct 10 20:45:00 volumio5 kernel: i2c_device_remove+0x34/0xa8 Oct 10 20:45:00 volumio5 kernel: device_remove+0x54/0x88 Oct 10 20:45:00 volumio5 kernel: device_release_driver_internal+0xe8/0x190 Oct 10 20:45:00 volumio5 kernel: device_release_driver+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: bus_remove_device+0x130/0x140 Oct 10 20:45:00 volumio5 kernel: device_del+0x184/0x3c0 Oct 10 20:45:00 volumio5 kernel: device_unregister+0x20/0x40 Oct 10 20:45:00 volumio5 kernel: i2c_unregister_device.part.30+0x50/0x80 Oct 10 20:45:00 volumio5 kernel: i2c_unregister_device+0x24/0x38 Oct 10 20:45:00 volumio5 kernel: of_i2c_notify+0xe8/0x170 Oct 10 20:45:00 volumio5 kernel: blocking_notifier_call_chain+0x74/0xa8 Oct 10 20:45:00 volumio5 kernel: __of_changeset_entry_notify+0x6c/0x110 Oct 10 20:45:00 volumio5 kernel: __of_changeset_revert_notify+0x4c/0x80 Oct 10 20:45:00 volumio5 kernel: of_overlay_remove+0x1c8/0x2d8 Oct 10 20:45:00 volumio5 kernel: cfs_overlay_release+0x34/0x60 Oct 10 20:45:00 volumio5 kernel: config_item_release+0x64/0x150 Oct 10 20:45:00 volumio5 kernel: config_item_put+0x74/0x98 Oct 10 20:45:00 volumio5 kernel: configfs_rmdir+0x1e8/0x2e0 Oct 10 20:45:00 volumio5 kernel: vfs_rmdir+0x100/0x210 Oct 10 20:45:00 volumio5 kernel: do_rmdir+0x10c/0x1e0 Oct 10 20:45:00 volumio5 kernel: __arm64_sys_rmdir+0x30/0x40 Oct 10 20:45:00 volumio5 kernel: invoke_syscall+0x4c/0x110 Oct 10 20:45:00 volumio5 kernel: el0_svc_common.constprop.3+0xfc/0x120 Oct 10 20:45:00 volumio5 kernel: do_el0_svc_compat+0x24/0x48 Oct 10 20:45:00 volumio5 kernel: el0_svc_compat+0x30/0x88 Oct 10 20:45:00 volumio5 kernel: el0t_32_sync_handler+0xe4/0x100 Oct 10 20:45:00 volumio5 kernel: el0t_32_sync+0x190/0x194 Oct 10 20:45:00 volumio5 kernel: ---[ end trace 0000000000000000 ]--- Oct 10 20:45:00 volumio5 kernel: ------------[ cut here ]------------ Oct 10 20:45:00 volumio5 kernel: clk-hifiberry-dacpro already unprepared Oct 10 20:45:00 volumio5 kernel: WARNING: CPU: 1 PID: 2166 at drivers/clk/clk.c:898 clk_core_unprepare+0x21c/0x240 Oct 10 20:45:00 volumio5 kernel: Modules linked in: snd_soc_pcm512x_i2c snd_soc_pcm512x regmap_i2c designware_i2s clk_hifiberry_dacpro 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 vc4 spidev hci_uart snd_soc_hdmi_codec btbcm bluetooth snd_soc_core brcmfmac brcmutil snd_compress snd_pcm_dmaengine snd_pcm cfg80211 snd_seq rpivid_hevc(C) ecdh_generic pisp_be aes_ce_blk ecc snd_seq_device aes_ce_cipher v4l2_mem2mem snd_timer videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 ghash_ce rfkill gf128mul libaes videobuf2_common videodev snd sha2_ce sha256_arm64 sha1_ce sg gpio_keys spi_bcm2835 mc raspberrypi_hwmon i2c_brcmstb pwm_fan i2c_designware_platform i2c_designware_core rp1_adc raspberrypi_gpiomem uio_pdrv_genirq uio nvmem_rmem i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched Oct 10 20:45:00 volumio5 kernel: drm_shmem_helper drm drm_panel_orientation_quirks backlight Oct 10 20:45:00 volumio5 kernel: CPU: 1 PID: 2166 Comm: dtoverlay Tainted: G WC 6.1.69-v8+ #1710 Oct 10 20:45:00 volumio5 kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Oct 10 20:45:00 volumio5 kernel: pstate: 40400009 (nZcv daif +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Oct 10 20:45:00 volumio5 kernel: pc : clk_core_unprepare+0x21c/0x240 Oct 10 20:45:00 volumio5 kernel: lr : clk_core_unprepare+0x21c/0x240 Oct 10 20:45:00 volumio5 kernel: sp : ffffffc008b43900 Oct 10 20:45:00 volumio5 kernel: x29: ffffffc008b43900 x28: ffffff812187cb00 x27: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x26: ffffff812187cb48 x25: ffffffd00d7380b0 x24: ffffff81056371b0 Oct 10 20:45:00 volumio5 kernel: x23: 0000000000000000 x22: ffffffd00cf84e38 x21: ffffffcfd23d1038 Oct 10 20:45:00 volumio5 kernel: x20: ffffff8135f92780 x19: ffffff8121954100 x18: ffffff8100178710 Oct 10 20:45:00 volumio5 kernel: x17: 0000000000000000 x16: ffffffd00c707178 x15: ffffff813bd95628 Oct 10 20:45:00 volumio5 kernel: x14: 0000000000000000 x13: 6465726170657270 x12: ffffffd00d4fc7f8 Oct 10 20:45:00 volumio5 kernel: x11: 0000000000000003 x10: 0000000000000001 x9 : ffffffd00c0f5684 Oct 10 20:45:00 volumio5 kernel: x8 : 0000000000017fe8 x7 : c0000000ffffefff x6 : ffffffd00d4e47b8 Oct 10 20:45:00 volumio5 kernel: x5 : ffffff81fef38a10 x4 : 0000000000000000 x3 : ffffffb1f1dff000 Oct 10 20:45:00 volumio5 kernel: x2 : ffffff8135f1dd00 x1 : 59920fcd95cc9300 x0 : 0000000000000000 Oct 10 20:45:00 volumio5 kernel: Call trace: Oct 10 20:45:00 volumio5 kernel: clk_core_unprepare+0x21c/0x240 Oct 10 20:45:00 volumio5 kernel: clk_unprepare+0x34/0x50 Oct 10 20:45:00 volumio5 kernel: pcm512x_remove+0x5c/0x78 [snd_soc_pcm512x] Oct 10 20:45:00 volumio5 kernel: pcm512x_i2c_remove+0x1c/0x28 [snd_soc_pcm512x_i2c] Oct 10 20:45:00 volumio5 kernel: i2c_device_remove+0x34/0xa8 Oct 10 20:45:00 volumio5 kernel: device_remove+0x54/0x88 Oct 10 20:45:00 volumio5 kernel: device_release_driver_internal+0xe8/0x190 Oct 10 20:45:00 volumio5 kernel: device_release_driver+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: bus_remove_device+0x130/0x140 Oct 10 20:45:00 volumio5 kernel: device_del+0x184/0x3c0 Oct 10 20:45:00 volumio5 kernel: device_unregister+0x20/0x40 Oct 10 20:45:00 volumio5 kernel: i2c_unregister_device.part.30+0x50/0x80 Oct 10 20:45:00 volumio5 kernel: i2c_unregister_device+0x24/0x38 Oct 10 20:45:00 volumio5 kernel: of_i2c_notify+0xe8/0x170 Oct 10 20:45:00 volumio5 kernel: blocking_notifier_call_chain+0x74/0xa8 Oct 10 20:45:00 volumio5 kernel: __of_changeset_entry_notify+0x6c/0x110 Oct 10 20:45:00 volumio5 kernel: __of_changeset_revert_notify+0x4c/0x80 Oct 10 20:45:00 volumio5 kernel: of_overlay_remove+0x1c8/0x2d8 Oct 10 20:45:00 volumio5 kernel: cfs_overlay_release+0x34/0x60 Oct 10 20:45:00 volumio5 kernel: config_item_release+0x64/0x150 Oct 10 20:45:00 volumio5 kernel: config_item_put+0x74/0x98 Oct 10 20:45:00 volumio5 kernel: configfs_rmdir+0x1e8/0x2e0 Oct 10 20:45:00 volumio5 kernel: vfs_rmdir+0x100/0x210 Oct 10 20:45:00 volumio5 kernel: do_rmdir+0x10c/0x1e0 Oct 10 20:45:00 volumio5 kernel: __arm64_sys_rmdir+0x30/0x40 Oct 10 20:45:00 volumio5 kernel: invoke_syscall+0x4c/0x110 Oct 10 20:45:00 volumio5 kernel: el0_svc_common.constprop.3+0xfc/0x120 Oct 10 20:45:00 volumio5 kernel: do_el0_svc_compat+0x24/0x48 Oct 10 20:45:00 volumio5 kernel: el0_svc_compat+0x30/0x88 Oct 10 20:45:00 volumio5 kernel: el0t_32_sync_handler+0xe4/0x100 Oct 10 20:45:00 volumio5 kernel: el0t_32_sync+0x190/0x194 Oct 10 20:45:00 volumio5 kernel: ---[ end trace 0000000000000000 ]--- Oct 10 20:45:00 volumio5 kernel: regulator-dummy: Underflow of regulator enable count Oct 10 20:45:00 volumio5 kernel: Failed to disable CPVDD: -EINVAL Oct 10 20:45:00 volumio5 kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /boss_osc Oct 10 20:45:00 volumio5 sudo[2165]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:00 volumio5 kernel: ------------[ cut here ]------------ Oct 10 20:45:00 volumio5 kernel: refcount_t: addition on 0; use-after-free. Oct 10 20:45:00 volumio5 kernel: WARNING: CPU: 1 PID: 847 at lib/refcount.c:25 refcount_warn_saturate+0xa4/0x148 Oct 10 20:45:00 volumio5 kernel: Modules linked in: snd_soc_pcm512x_i2c snd_soc_pcm512x regmap_i2c designware_i2s clk_hifiberry_dacpro 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 vc4 spidev hci_uart snd_soc_hdmi_codec btbcm bluetooth snd_soc_core brcmfmac brcmutil snd_compress snd_pcm_dmaengine snd_pcm cfg80211 snd_seq rpivid_hevc(C) ecdh_generic pisp_be aes_ce_blk ecc snd_seq_device aes_ce_cipher v4l2_mem2mem snd_timer videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 ghash_ce rfkill gf128mul libaes videobuf2_common videodev snd sha2_ce sha256_arm64 sha1_ce sg gpio_keys spi_bcm2835 mc raspberrypi_hwmon i2c_brcmstb pwm_fan i2c_designware_platform i2c_designware_core rp1_adc raspberrypi_gpiomem uio_pdrv_genirq uio nvmem_rmem i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched Oct 10 20:45:00 volumio5 kernel: drm_shmem_helper drm drm_panel_orientation_quirks backlight Oct 10 20:45:00 volumio5 kernel: CPU: 1 PID: 847 Comm: kworker/1:5 Tainted: G WC 6.1.69-v8+ #1710 Oct 10 20:45:00 volumio5 kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Oct 10 20:45:00 volumio5 kernel: Workqueue: events_long device_link_release_fn Oct 10 20:45:00 volumio5 kernel: pstate: 404000c9 (nZcv daIF +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Oct 10 20:45:00 volumio5 kernel: pc : refcount_warn_saturate+0xa4/0x148 Oct 10 20:45:00 volumio5 kernel: lr : refcount_warn_saturate+0xa4/0x148 Oct 10 20:45:00 volumio5 kernel: sp : ffffffc009b4b7b0 Oct 10 20:45:00 volumio5 kernel: x29: ffffffc009b4b7b0 x28: ffffffc009b4bb59 x27: ffffffd00cf847f0 Oct 10 20:45:00 volumio5 kernel: x26: ffffff8105f17000 x25: ffffff8105f17018 x24: ffffffd00ccf0bc8 Oct 10 20:45:00 volumio5 kernel: x23: ffffffc009b4bb28 x22: ffffffc009b4bb59 x21: ffffff81fefa29e0 Oct 10 20:45:00 volumio5 kernel: x20: 0000000000000000 x19: ffffff8105f17080 x18: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x17: 0000000000000000 x16: 0000000000000000 x15: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x14: 0000000000000000 x13: 2e656572662d7265 x12: ffffffd00d4fc7f8 Oct 10 20:45:00 volumio5 kernel: x11: 0000000000000003 x10: 0000000000000001 x9 : ffffffd00c0f5684 Oct 10 20:45:00 volumio5 kernel: x8 : 0000000000017fe8 x7 : c0000000ffffefff x6 : ffffffd00d4e47b8 Oct 10 20:45:00 volumio5 kernel: x5 : ffffff81fef38a10 x4 : 0000000000000000 x3 : ffffffb1f1dff000 Oct 10 20:45:00 volumio5 kernel: x2 : ffffff8100f95d00 x1 : 59920fcd95cc9300 x0 : 0000000000000000 Oct 10 20:45:00 volumio5 kernel: Call trace: Oct 10 20:45:00 volumio5 kernel: refcount_warn_saturate+0xa4/0x148 Oct 10 20:45:00 volumio5 kernel: kobject_get+0xbc/0xc0 Oct 10 20:45:00 volumio5 kernel: of_node_get+0x28/0x40 Oct 10 20:45:00 volumio5 kernel: of_fwnode_get+0x3c/0x60 Oct 10 20:45:00 volumio5 kernel: fwnode_handle_get+0x38/0x58 Oct 10 20:45:00 volumio5 kernel: fwnode_get_nth_parent+0x80/0x90 Oct 10 20:45:00 volumio5 kernel: fwnode_full_name_string+0x4c/0xc8 Oct 10 20:45:00 volumio5 kernel: device_node_string+0x20c/0x3e0 Oct 10 20:45:00 volumio5 kernel: pointer+0x1a8/0x360 Oct 10 20:45:00 volumio5 kernel: vsnprintf+0x378/0x6e0 Oct 10 20:45:00 volumio5 kernel: vprintk_store+0xfc/0x4c8 Oct 10 20:45:00 volumio5 kernel: vprintk_emit+0x118/0x320 Oct 10 20:45:00 volumio5 kernel: vprintk_default+0x40/0x50 Oct 10 20:45:00 volumio5 kernel: vprintk+0x98/0x100 Oct 10 20:45:00 volumio5 kernel: _printk+0x6c/0x90 Oct 10 20:45:00 volumio5 kernel: of_node_release+0xe8/0xf0 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: of_node_put+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: platform_device_release+0x24/0x60 Oct 10 20:45:00 volumio5 kernel: device_release+0x3c/0x98 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: device_link_release_fn+0x68/0xe8 Oct 10 20:45:00 volumio5 kernel: process_one_work+0x208/0x480 Oct 10 20:45:00 volumio5 kernel: worker_thread+0x50/0x428 Oct 10 20:45:00 volumio5 kernel: kthread+0x114/0x120 Oct 10 20:45:00 volumio5 kernel: ret_from_fork+0x10/0x20 Oct 10 20:45:00 volumio5 kernel: ---[ end trace 0000000000000000 ]--- Oct 10 20:45:00 volumio5 kernel: ------------[ cut here ]------------ Oct 10 20:45:00 volumio5 kernel: refcount_t: underflow; use-after-free. Oct 10 20:45:00 volumio5 kernel: WARNING: CPU: 1 PID: 847 at lib/refcount.c:28 refcount_warn_saturate+0xf8/0x148 Oct 10 20:45:00 volumio5 kernel: Modules linked in: snd_soc_pcm512x_i2c snd_soc_pcm512x regmap_i2c designware_i2s clk_hifiberry_dacpro 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 vc4 spidev hci_uart snd_soc_hdmi_codec btbcm bluetooth snd_soc_core brcmfmac brcmutil snd_compress snd_pcm_dmaengine snd_pcm cfg80211 snd_seq rpivid_hevc(C) ecdh_generic pisp_be aes_ce_blk ecc snd_seq_device aes_ce_cipher v4l2_mem2mem snd_timer videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 ghash_ce rfkill gf128mul libaes videobuf2_common videodev snd sha2_ce sha256_arm64 sha1_ce sg gpio_keys spi_bcm2835 mc raspberrypi_hwmon i2c_brcmstb pwm_fan i2c_designware_platform i2c_designware_core rp1_adc raspberrypi_gpiomem uio_pdrv_genirq uio nvmem_rmem i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched Oct 10 20:45:00 volumio5 kernel: drm_shmem_helper drm drm_panel_orientation_quirks backlight Oct 10 20:45:00 volumio5 kernel: CPU: 1 PID: 847 Comm: kworker/1:5 Tainted: G WC 6.1.69-v8+ #1710 Oct 10 20:45:00 volumio5 kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Oct 10 20:45:00 volumio5 kernel: Workqueue: events_long device_link_release_fn Oct 10 20:45:00 volumio5 kernel: pstate: 404000c9 (nZcv daIF +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Oct 10 20:45:00 volumio5 kernel: pc : refcount_warn_saturate+0xf8/0x148 Oct 10 20:45:00 volumio5 kernel: lr : refcount_warn_saturate+0xf8/0x148 Oct 10 20:45:00 volumio5 kernel: sp : ffffffc009b4b7c0 Oct 10 20:45:00 volumio5 kernel: x29: ffffffc009b4b7c0 x28: ffffffc009b4bb59 x27: ffffffd00cf847f0 Oct 10 20:45:00 volumio5 kernel: x26: ffffff8105f17000 x25: ffffff8105f17018 x24: ffffffd00ccf0bc8 Oct 10 20:45:00 volumio5 kernel: x23: ffffffc009b4bb28 x22: ffffffc009b4bb62 x21: ffffff8105f17018 Oct 10 20:45:00 volumio5 kernel: x20: 00000000ffffffff x19: ffffff8105f17080 x18: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x17: 0000000000000000 x16: 0000000000000000 x15: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x14: 0000000000000000 x13: 2e656572662d7265 x12: ffffffd00d4fc7f8 Oct 10 20:45:00 volumio5 kernel: x11: 0000000000000003 x10: 0000000000000001 x9 : ffffffd00c0f5684 Oct 10 20:45:00 volumio5 kernel: x8 : 0000000000017fe8 x7 : c0000000ffffefff x6 : ffffffd00d4e47b8 Oct 10 20:45:00 volumio5 kernel: x5 : ffffff81fef38a10 x4 : 0000000000000000 x3 : ffffffb1f1dff000 Oct 10 20:45:00 volumio5 kernel: x2 : ffffff8100f95d00 x1 : 59920fcd95cc9300 x0 : 0000000000000000 Oct 10 20:45:00 volumio5 kernel: Call trace: Oct 10 20:45:00 volumio5 kernel: refcount_warn_saturate+0xf8/0x148 Oct 10 20:45:00 volumio5 kernel: kobject_put+0x110/0x120 Oct 10 20:45:00 volumio5 kernel: of_node_put+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: of_fwnode_put+0x3c/0x50 Oct 10 20:45:00 volumio5 kernel: fwnode_handle_put.part.1+0x28/0x38 Oct 10 20:45:00 volumio5 kernel: fwnode_handle_put+0x24/0x38 Oct 10 20:45:00 volumio5 kernel: fwnode_full_name_string+0x9c/0xc8 Oct 10 20:45:00 volumio5 kernel: device_node_string+0x20c/0x3e0 Oct 10 20:45:00 volumio5 kernel: pointer+0x1a8/0x360 Oct 10 20:45:00 volumio5 kernel: vsnprintf+0x378/0x6e0 Oct 10 20:45:00 volumio5 kernel: vprintk_store+0xfc/0x4c8 Oct 10 20:45:00 volumio5 kernel: vprintk_emit+0x118/0x320 Oct 10 20:45:00 volumio5 kernel: vprintk_default+0x40/0x50 Oct 10 20:45:00 volumio5 kernel: vprintk+0x98/0x100 Oct 10 20:45:00 volumio5 kernel: _printk+0x6c/0x90 Oct 10 20:45:00 volumio5 kernel: of_node_release+0xe8/0xf0 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: of_node_put+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: platform_device_release+0x24/0x60 Oct 10 20:45:00 volumio5 kernel: device_release+0x3c/0x98 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: device_link_release_fn+0x68/0xe8 Oct 10 20:45:00 volumio5 kernel: process_one_work+0x208/0x480 Oct 10 20:45:00 volumio5 kernel: worker_thread+0x50/0x428 Oct 10 20:45:00 volumio5 kernel: kthread+0x114/0x120 Oct 10 20:45:00 volumio5 kernel: ret_from_fork+0x10/0x20 Oct 10 20:45:00 volumio5 kernel: ---[ end trace 0000000000000000 ]--- Oct 10 20:45:00 volumio5 kernel: OF: ERROR: memory leak before free overlay changeset, /boss_osc Oct 10 20:45:00 volumio5 kernel: ------------[ cut here ]------------ Oct 10 20:45:00 volumio5 kernel: refcount_t: saturated; leaking memory. Oct 10 20:45:00 volumio5 kernel: WARNING: CPU: 1 PID: 847 at lib/refcount.c:22 refcount_warn_saturate+0x78/0x148 Oct 10 20:45:00 volumio5 kernel: Modules linked in: snd_soc_pcm512x_i2c snd_soc_pcm512x regmap_i2c designware_i2s clk_hifiberry_dacpro 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 vc4 spidev hci_uart snd_soc_hdmi_codec btbcm bluetooth snd_soc_core brcmfmac brcmutil snd_compress snd_pcm_dmaengine snd_pcm cfg80211 snd_seq rpivid_hevc(C) ecdh_generic pisp_be aes_ce_blk ecc snd_seq_device aes_ce_cipher v4l2_mem2mem snd_timer videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 ghash_ce rfkill gf128mul libaes videobuf2_common videodev snd sha2_ce sha256_arm64 sha1_ce sg gpio_keys spi_bcm2835 mc raspberrypi_hwmon i2c_brcmstb pwm_fan i2c_designware_platform i2c_designware_core rp1_adc raspberrypi_gpiomem uio_pdrv_genirq uio nvmem_rmem i2c_dev ip_tables x_tables ipv6 squashfs overlay nls_iso8859_1 fuse drm_display_helper cec drm_dma_helper drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops v3d gpu_sched Oct 10 20:45:00 volumio5 kernel: drm_shmem_helper drm drm_panel_orientation_quirks backlight Oct 10 20:45:00 volumio5 kernel: CPU: 1 PID: 847 Comm: kworker/1:5 Tainted: G WC 6.1.69-v8+ #1710 Oct 10 20:45:00 volumio5 kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Oct 10 20:45:00 volumio5 kernel: Workqueue: events_long device_link_release_fn Oct 10 20:45:00 volumio5 kernel: pstate: 404000c9 (nZcv daIF +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Oct 10 20:45:00 volumio5 kernel: pc : refcount_warn_saturate+0x78/0x148 Oct 10 20:45:00 volumio5 kernel: lr : refcount_warn_saturate+0x78/0x148 Oct 10 20:45:00 volumio5 kernel: sp : ffffffc009b4b710 Oct 10 20:45:00 volumio5 kernel: x29: ffffffc009b4b710 x28: ffffffd00d69bb31 x27: ffffffd00cf847f0 Oct 10 20:45:00 volumio5 kernel: x26: ffffff8105f17000 x25: ffffff8105f17018 x24: ffffffd00ccf0bc8 Oct 10 20:45:00 volumio5 kernel: x23: ffffffd00d69bb3c x22: ffffffd00d69bb31 x21: ffffff81fefa29e0 Oct 10 20:45:00 volumio5 kernel: x20: 0000000000000000 x19: ffffff8105f17080 x18: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x17: 0000000000000000 x16: 0000000000000000 x15: 0000000000000000 Oct 10 20:45:00 volumio5 kernel: x14: 0000000000000000 x13: 0a2e79726f6d656d x12: ffffffd00d4fc7f8 Oct 10 20:45:00 volumio5 kernel: x11: 0000000000000003 x10: 0000000000000001 x9 : ffffffd00c0f5684 Oct 10 20:45:00 volumio5 kernel: x8 : 0000000000017fe8 x7 : c0000000ffffefff x6 : ffffffd00d4e47b8 Oct 10 20:45:00 volumio5 kernel: x5 : ffffff81fef38a10 x4 : 0000000000000000 x3 : ffffffb1f1dff000 Oct 10 20:45:00 volumio5 kernel: x2 : ffffff8100f95d00 x1 : 59920fcd95cc9300 x0 : 0000000000000000 Oct 10 20:45:00 volumio5 kernel: Call trace: Oct 10 20:45:00 volumio5 kernel: refcount_warn_saturate+0x78/0x148 Oct 10 20:45:00 volumio5 kernel: kobject_get+0xa0/0xc0 Oct 10 20:45:00 volumio5 kernel: of_node_get+0x28/0x40 Oct 10 20:45:00 volumio5 kernel: of_fwnode_get+0x3c/0x60 Oct 10 20:45:00 volumio5 kernel: fwnode_handle_get+0x38/0x58 Oct 10 20:45:00 volumio5 kernel: fwnode_get_nth_parent+0x80/0x90 Oct 10 20:45:00 volumio5 kernel: fwnode_full_name_string+0x4c/0xc8 Oct 10 20:45:00 volumio5 kernel: device_node_string+0x20c/0x3e0 Oct 10 20:45:00 volumio5 kernel: pointer+0x1a8/0x360 Oct 10 20:45:00 volumio5 kernel: vsnprintf+0x378/0x6e0 Oct 10 20:45:00 volumio5 kernel: vscnprintf+0x34/0x58 Oct 10 20:45:00 volumio5 kernel: printk_sprint+0x48/0x1b8 Oct 10 20:45:00 volumio5 kernel: vprintk_store+0x414/0x4c8 Oct 10 20:45:00 volumio5 kernel: vprintk_emit+0x118/0x320 Oct 10 20:45:00 volumio5 kernel: vprintk_default+0x40/0x50 Oct 10 20:45:00 volumio5 kernel: vprintk+0x98/0x100 Oct 10 20:45:00 volumio5 kernel: _printk+0x6c/0x90 Oct 10 20:45:00 volumio5 kernel: of_node_release+0xe8/0xf0 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: of_node_put+0x20/0x30 Oct 10 20:45:00 volumio5 kernel: platform_device_release+0x24/0x60 Oct 10 20:45:00 volumio5 kernel: device_release+0x3c/0x98 Oct 10 20:45:00 volumio5 kernel: kobject_put+0xa4/0x120 Oct 10 20:45:00 volumio5 kernel: device_link_release_fn+0x68/0xe8 Oct 10 20:45:00 volumio5 kernel: process_one_work+0x208/0x480 Oct 10 20:45:00 volumio5 kernel: worker_thread+0x50/0x428 Oct 10 20:45:00 volumio5 kernel: kthread+0x114/0x120 Oct 10 20:45:00 volumio5 kernel: ret_from_fork+0x10/0x20 Oct 10 20:45:00 volumio5 kernel: ---[ end trace 0000000000000000 ]--- Oct 10 20:45:00 volumio5 volumio[1086]: info: Removed overlay allo-boss-dac-pcm512x-audio Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 10 20:45:00 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:00 volumio5 sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Oct 10 20:45:00 volumio5 sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Oct 10 20:45:01 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Oct 10 20:45:01 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Oct 10 20:45:01 volumio5 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Oct 10 20:45:01 volumio5 sudo[2183]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 volumio[1086]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: Invalid card number. Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 10 20:45:01 volumio5 volumio[1086]: info: Updating Volume Controller Parameters: Device: 2 Name: Picade HAT Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 10 20:45:01 volumio5 volumio[1086]: info: Disabling external Volume Control Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 10 20:45:01 volumio5 volumio[1086]: info: Preparing to generate the ALSA configuration file Oct 10 20:45:01 volumio5 volumio[1086]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 20:45:01 volumio5 volumio[1086]: info: I2S Param [object Object] successfully enabled Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 20:45:01 volumio5 volumio[1086]: info: Getting Alsa Cards List without I2S DAC Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: Invalid card number. Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 20:45:01 volumio5 volumio[1086]: info: VolumeController:: Volume=undefined Mute =false Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreStateMachine::pushState Oct 10 20:45:01 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 10 20:45:01 volumio5 volumio[1086]: info: Asound.conf file written Oct 10 20:45:01 volumio5 sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 10 20:45:01 volumio5 sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 sudo[2210]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 volumio[1086]: No state is present for card vc4hdmi0 Oct 10 20:45:01 volumio5 volumio[1086]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 10 20:45:01 volumio5 volumio[1086]: Hardware is initialized using a generic method Oct 10 20:45:01 volumio5 volumio[1086]: No state is present for card vc4hdmi0 Oct 10 20:45:01 volumio5 volumio[1086]: No state is present for card vc4hdmi1 Oct 10 20:45:01 volumio5 volumio[1086]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 10 20:45:01 volumio5 volumio[1086]: Hardware is initialized using a generic method Oct 10 20:45:01 volumio5 volumio[1086]: No state is present for card vc4hdmi1 Oct 10 20:45:01 volumio5 volumio[1086]: info: Output device has changed, restarting MPD Oct 10 20:45:01 volumio5 sudo[2215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 20:45:01 volumio5 volumio[1086]: info: Output device has changed, restarting Shairport Sync Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 sudo[2215]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 20:45:01 volumio5 sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 systemd[1]: Stopping Music Player Daemon... Oct 10 20:45:01 volumio5 volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 20:45:01 volumio5 volumio[1086]: info: Output device has changed, restarting MPD Oct 10 20:45:01 volumio5 systemd[1]: mpd.service: Succeeded. Oct 10 20:45:01 volumio5 systemd[1]: Stopped Music Player Daemon. Oct 10 20:45:01 volumio5 sudo[2225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 20:45:01 volumio5 sudo[2225]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 volumio[1086]: info: Output device has changed, restarting Shairport Sync Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 sudo[2225]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 systemd[1]: Starting Music Player Daemon... Oct 10 20:45:01 volumio5 sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 20:45:01 volumio5 sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 20:45:01 volumio5 systemd[1]: mpd.service: Succeeded. Oct 10 20:45:01 volumio5 systemd[1]: Stopped Music Player Daemon. Oct 10 20:45:01 volumio5 systemd[1]: Starting Music Player Daemon... Oct 10 20:45:01 volumio5 volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 20:45:01 volumio5 volumio[1086]: info: MPD Permissions set Oct 10 20:45:01 volumio5 volumio[1086]: info: MPD Permissions set Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 20:45:01 volumio5 volumio[1086]: info: Starting Shairport Sync Oct 10 20:45:01 volumio5 sudo[2233]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 20:45:01 volumio5 sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 sudo[2233]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 volumio[1086]: info: Starting Shairport Sync Oct 10 20:45:01 volumio5 sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 20:45:01 volumio5 sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 20:45:01 volumio5 sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:01 volumio5 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 20:45:01 volumio5 systemd[1]: shairport-sync.service: Succeeded. Oct 10 20:45:01 volumio5 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 20:45:01 volumio5 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 20:45:01 volumio5 sudo[2245]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 sudo[2248]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 volumio[1086]: info: Shairport-Sync Started Oct 10 20:45:01 volumio5 volumio[1086]: info: Shairport-Sync Started Oct 10 20:45:01 volumio5 mpd[2244]: Oct 10 20:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 20:45:01 volumio5 systemd[1]: Started Music Player Daemon. Oct 10 20:45:01 volumio5 sudo[2217]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 sudo[2227]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:01 volumio5 volumio[1086]: error: MPD error: The expression evaluated to a falsy value: Oct 10 20:45:01 volumio5 volumio[1086]: assert.ok(self.idling) Oct 10 20:45:01 volumio5 volumio[1086]: error: The expression evaluated to a falsy value: Oct 10 20:45:01 volumio5 volumio[1086]: assert.ok(self.idling) Oct 10 20:45:01 volumio5 volumio[1086]: error: updateQueue error: null Oct 10 20:45:03 volumio5 volumio[1086]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 10 20:45:03 volumio5 volumio[1086]: info: PLUGIN onReboot : networkfs Oct 10 20:45:25 volumio5 volumio-remote-updater[812]: [2024-10-10 11:45:25] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 10 20:45:30 volumio5 volumio-remote-updater[812]: [2024-10-10 11:45:30] [connect] Successful connection Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#21 uas_eh_abort_handler 0 uas-tag 14 inflight: CMD OUT Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#21 CDB: opcode=0x2a 2a 00 09 db 90 10 00 00 02 00 Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#20 uas_eh_abort_handler 0 uas-tag 13 inflight: CMD OUT Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x2a 2a 00 09 db 8f a8 00 00 02 00 Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#19 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD OUT Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x2a 2a 00 09 db 8f 68 00 00 02 00 Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#18 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD OUT Oct 10 20:45:34 volumio5 kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x2a 2a 00 09 db 70 76 00 00 04 00 Oct 10 20:45:39 volumio5 kernel: xhci-hcd xhci-hcd.0: xHCI host not responding to stop endpoint command Oct 10 20:45:39 volumio5 kernel: xhci-hcd xhci-hcd.0: xHCI host controller not responding, assume dead Oct 10 20:45:39 volumio5 kernel: xhci-hcd xhci-hcd.0: HC died; cleaning up Oct 10 20:45:39 volumio5 kernel: usb 2-1: cmd cmplt err -108 Oct 10 20:45:39 volumio5 kernel: usb 2-1: cmd cmplt err -108 Oct 10 20:45:39 volumio5 kernel: usb 2-1: cmd cmplt err -108 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#17 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x2a 2a 00 09 db 70 6c 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#16 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x2a 2a 00 09 db 70 64 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#15 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#15 CDB: opcode=0x2a 2a 00 09 db 70 5e 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#14 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#14 CDB: opcode=0x2a 2a 00 09 db 70 4c 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#13 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#13 CDB: opcode=0x2a 2a 00 09 db 70 42 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#12 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x2a 2a 00 09 db 70 22 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#11 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x2a 2a 00 09 db 70 04 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#10 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x2a 2a 00 00 53 74 c8 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#9 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#9 CDB: opcode=0x2a 2a 00 00 53 73 38 00 00 0e 00 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#8 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x2a 2a 00 00 53 70 02 00 00 02 00 Oct 10 20:45:39 volumio5 kernel: usb 2-1: USB disconnect, device number 2 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 5468162 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 1, lost async page write Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 5468984 op 0x1:(WRITE) flags 0x3800 phys_seg 7 prio class 2 Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 412, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 413, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 414, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 415, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 416, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 417, lost async page write Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 418, lost async page write Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 5469384 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 612, lost async page write Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376004 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: Buffer I/O error on dev sda3, logical block 79953922, lost async page write Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376034 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376066 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376076 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376094 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376100 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: device offline error, dev sda, sector 165376108 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 2 Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19441962: comm nmbd: reading directory lblock 0 Oct 10 20:45:39 volumio5 kernel: Aborting journal on device sda3-8. Oct 10 20:45:39 volumio5 nmbd[2258]: [2024/10/10 20:45:39.223534, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Oct 10 20:45:39 volumio5 kernel: JBD2: I/O error when updating journal superblock for sda3-8. Oct 10 20:45:39 volumio5 kernel: EXT4-fs (sda3): previous I/O error to superblock detected Oct 10 20:45:39 volumio5 kernel: EXT4-fs (sda3): I/O error while writing superblock Oct 10 20:45:39 volumio5 nmbd[2258]: wins_write_database: Can't open /var/lib/samba/wins.dat.2258: Input/output error Oct 10 20:45:39 volumio5 systemd[1]: Stopped target Local File Systems. Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19442163: comm node: reading directory lblock 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreStateMachine::getcurrentVolume Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 20:45:39 volumio5 volumio[1086]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-Realtek_RTL9210_NVME_012345679130-0:0-part1 /dev/disk/by-partuuid/11f12c05-01 /dev/disk/by-uuid/9C48-E5A3 /dev/disk/by-label/boot","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9C48-E5A3","ID_FS_UUID_ENC":"9C48-E5A3","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"RTL9210_NVME","ID_MODEL_ENC":"RTL9210\\x20NVME\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9210","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"11f12c05-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"11f12c05","ID_PATH":"platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Realtek_RTL9210_NVME_012345679130-0:0","ID_SERIAL_SHORT":"012345679130","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Realtek","ID_VENDOR_ENC":"Realtek\\x20","ID_VENDOR_ID":"0bda","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2952","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2148474"} Oct 10 20:45:39 volumio5 volumio[1086]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 20:45:39 volumio5 systemd[1]: Unmounting /boot... Oct 10 20:45:39 volumio5 sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Oct 10 20:45:39 volumio5 sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 20:45:39 volumio5 volumio[1086]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-Realtek_RTL9210_NVME_012345679130-0:0-part2 /dev/disk/by-partuuid/11f12c05-02 /dev/disk/by-label/volumio /dev/disk/by-uuid/8543fa12-4125-4451-a534-cec5480e0606","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"8543fa12-4125-4451-a534-cec5480e0606","ID_FS_UUID_ENC":"8543fa12-4125-4451-a534-cec5480e0606","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"RTL9210_NVME","ID_MODEL_ENC":"RTL9210\\x20NVME\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9210","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"11f12c05-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"11f12c05","ID_PATH":"platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Realtek_RTL9210_NVME_012345679130-0:0","ID_SERIAL_SHORT":"012345679130","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Realtek","ID_VENDOR_ENC":"Realtek\\x20","ID_VENDOR_ID":"0bda","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2953","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2147985"} Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19442116: comm systemd: reading directory lblock 0 Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19442163: comm node: reading directory lblock 0 Oct 10 20:45:39 volumio5 systemd-logind[810]: Unit reboot.target is not-found, refusing operation. Oct 10 20:45:39 volumio5 kernel: FAT-fs (sda1): unable to read boot sector to mark fs as dirty Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 10 20:45:39 volumio5 systemd[1]: boot.mount: Succeeded. Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 2 Oct 10 20:45:39 volumio5 systemd[1]: Unmounted /boot. Oct 10 20:45:39 volumio5 systemd[1]: systemd-fsck@dev-disk-by\x2duuid-9C48\x2dE5A3.service: Succeeded. Oct 10 20:45:39 volumio5 systemd[1]: Stopped File System Check on /dev/disk/by-uuid/9C48-E5A3. Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 3 Oct 10 20:45:39 volumio5 volumio[1086]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio_data /dev/disk/by-path/platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0-part3 /dev/disk/by-partuuid/11f12c05-03 /dev/disk/by-id/usb-Realtek_RTL9210_NVME_012345679130-0:0-part3 /dev/disk/by-uuid/f7cca1c6-391b-47a8-9c19-3b1948539def","DEVNAME":"/dev/sda3","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"f7cca1c6-391b-47a8-9c19-3b1948539def","ID_FS_UUID_ENC":"f7cca1c6-391b-47a8-9c19-3b1948539def","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"RTL9210_NVME","ID_MODEL_ENC":"RTL9210\\x20NVME\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9210","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"244601520","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"11f12c05-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"11f12c05","ID_PATH":"platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Realtek_RTL9210_NVME_012345679130-0:0","ID_SERIAL_SHORT":"012345679130","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Realtek","ID_VENDOR_ENC":"Realtek\\x20","ID_VENDOR_ID":"0bda","MAJOR":"8","MINOR":"3","PARTN":"3","SEQNUM":"2954","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2147849"} Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 4 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19442163: comm node: reading directory lblock 0 Oct 10 20:45:39 volumio5 kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #19442116: comm systemd: reading directory lblock 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: VolumeController:: Volume=undefined Mute =false Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreStateMachine::pushState Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreStateMachine::updateTrackBlock Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrackBlock Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 20:45:39 volumio5 sudo[2265]: pam_unix(sudo:session): session closed for user root Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 12 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 13 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 14 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 15 Oct 10 20:45:39 volumio5 volumio[1086]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 10 20:45:39 volumio5 volumio[1086]: info: Command failed: /usr/bin/sudo systemctl reboot Oct 10 20:45:39 volumio5 volumio[1086]: Failed to reboot system via logind: Access denied Oct 10 20:45:39 volumio5 volumio[1086]: Failed to start reboot.target: Unit reboot.target not found. Oct 10 20:45:39 volumio5 volumio[1086]: {"killed":false,"code":5,"signal":null,"cmd":"/usr/bin/sudo systemctl reboot"} Oct 10 20:45:39 volumio5 volumio[1086]: info: Rebooting Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 16 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 18 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 19 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 20 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 21 Oct 10 20:45:39 volumio5 volumio[1086]: info: VolumeController:: Volume=undefined Mute =false Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreStateMachine::pushState Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 22 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 23 Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 24 Oct 10 20:45:39 volumio5 kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Oct 10 20:45:39 volumio5 kernel: xhci-hcd xhci-hcd.0: WARN Can't disable streams for endpoint 0x81, streams are being disabled already Oct 10 20:45:39 volumio5 volumio[1086]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.96 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 25 Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: Received Get System Info Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 20:45:39 volumio5 volumio[1086]: info: Discovery: Getting this device information Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 20:45:39 volumio5 volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 10 20:45:39 volumio5 volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 10 20:45:39 volumio5 volumio[1086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 20:45:39 volumio5 kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #19442014: lblock 0: comm node: error -5 reading directory block Oct 10 20:45:39 volumio5 volumio[1086]: Error: EIO: i/o error, scandir '/data/plugins/' Oct 10 20:45:39 volumio5 volumio[1086]: at Object.readdirSync (fs.js:1021:3) Oct 10 20:45:39 volumio5 volumio[1086]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Oct 10 20:45:39 volumio5 volumio[1086]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Oct 10 20:45:39 volumio5 volumio[1086]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1328:45) Oct 10 20:45:39 volumio5 volumio[1086]: at Socket.emit (events.js:315:20) Oct 10 20:45:39 volumio5 volumio[1086]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 10 20:45:39 volumio5 volumio[1086]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 10 20:45:39 volumio5 volumio[1086]: errno: -5, Oct 10 20:45:39 volumio5 volumio[1086]: syscall: 'scandir', Oct 10 20:45:39 volumio5 volumio[1086]: code: 'EIO', Oct 10 20:45:39 volumio5 volumio[1086]: path: '/data/plugins/' Oct 10 20:45:39 volumio5 volumio[1086]: } Oct 10 20:45:39 volumio5 volumio[1086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 20:45:39 volumio5 sudo[2281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 20:44 Oct 10 20:45:39 volumio5 sudo[2281]: 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"