Exception auf Raspberry, CMCommCycleTask Tainted

dav3
2023-02-17
1 day ago
  • dav3 - 2023-02-17

    Hallo, ich habe seit ein paar Wochen das Problem das die Codesys-Runtime mir den Raspberry abschießt.
    Im Kernel-log sehe ich Meldungen wie:
    rcu: INFO: rcu_sched self-detected stall on CPU
    CPU: 1 PID: 14903 Comm: CMCommCycleTask Tainted: G C 5.15.84-v7l+ #1613

    Die Runtime läuft mit Multicore Lizenz und die ersten Vorfälle waren immer auf CPU 3. Der Task der auf CPU 3 läuft hat REAL_TO_INT Umwandlungen, die ich als Ursache vermutete und hier anfangen wollte das anders anzugehen.
    Nun beim letzten mal war es aber eben Core 1, hier läuft die Visu und der Alarmmanager. Im Codesys Log selbst, sehe ich keine Alarme/Fehler und weiß nun nicht so recht wo ich angreifen soll.
    Was mir noch etwas sorgen macht, der OPC UA Server startet in der Runtime. Den hatte ich mal zu Testzwecken, habe das aber abgebrochen weil er unzuverlässig (erzeugte auch regelmäßig Abstürze) ist und zu viel Ressourcen braucht.
    Weiß jemand wie ich den OPC wieder komplett deaktivieren kann? Oder was sonst mein Problem verursachen könnte?

    Feb 12 16:12:32 SoftSPS1 kernel: [86243.056753] device eth1 left promiscuous mode
    Feb 12 16:12:32 SoftSPS1 kernel: [86243.191647] device eth2 left promiscuous mode
    Feb 12 16:12:42 SoftSPS1 kernel: [86253.451550] device eth1 entered promiscuous mode
    Feb 12 16:12:42 SoftSPS1 kernel: [86253.451816] r8152 2-1.3:1.0 eth1: Promiscuous mode enabled
    Feb 12 16:12:42 SoftSPS1 kernel: [86253.511783] device eth2 entered promiscuous mode
    Feb 12 16:12:42 SoftSPS1 kernel: [86253.512036] r8152 2-1.4:1.0 eth2: Promiscuous mode enabled
    Feb 12 16:14:39 SoftSPS1 kernel: [86370.461474] device eth1 left promiscuous mode
    Feb 12 16:14:39 SoftSPS1 kernel: [86370.563233] device eth2 left promiscuous mode
    Feb 12 16:14:48 SoftSPS1 kernel: [86378.653364] device eth1 entered promiscuous mode
    Feb 12 16:14:48 SoftSPS1 kernel: [86378.653649] r8152 2-1.3:1.0 eth1: Promiscuous mode enabled
    Feb 12 16:14:48 SoftSPS1 kernel: [86378.743372] device eth2 entered promiscuous mode
    Feb 12 16:14:48 SoftSPS1 kernel: [86378.743580] r8152 2-1.4:1.0 eth2: Promiscuous mode enabled
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602173] rcu: INFO: rcu_sched self-detected stall on CPU
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602184] rcu:   1-....: (2099 ticks this GP) idle=e3f/1/0x40000002 softirq=14115226/14115226 fqs=1049 
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602195]    (t=2100 jiffies g=37036717 q=3850)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602203] NMI backtrace for cpu 1
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602208] CPU: 1 PID: 14903 Comm: CMCommCycleTask Tainted: G         C        5.15.84-v7l+ #1613
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602215] Hardware name: BCM2711
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602219] Backtrace: 
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602225] [<c0bd8b34>] (dump_backtrace) from [<c0bd8d80>] (show_stack+0x20/0x24)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602244]  r7:00000080 r6:c0e3fc14 r5:00000080 r4:60000193
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602248] [<c0bd8d60>] (show_stack) from [<c0bdd490>] (dump_stack_lvl+0x70/0x94)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602259] [<c0bdd420>] (dump_stack_lvl) from [<c0bdd4cc>] (dump_stack+0x18/0x1c)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602270]  r7:00000080 r6:00000000 r5:00000001 r4:20000193
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602273] [<c0bdd4b4>] (dump_stack) from [<c07a5eec>] (nmi_cpu_backtrace+0x118/0x16c)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602285] [<c07a5dd4>] (nmi_cpu_backtrace) from [<c07a605c>] (nmi_trigger_cpumask_backtrace+0x11c/0x134)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602297]  r7:c0c020dc r6:00000001 r5:c1205b1c r4:c020eff0
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602300] [<c07a5f40>] (nmi_trigger_cpumask_backtrace) from [<c020fed8>] (arch_trigger_cpumask_backtrace+0x20/0x24)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602313]  r7:c1342116 r6:00000001 r5:c120514c r4:c1276f80
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602316] [<c020feb8>] (arch_trigger_cpumask_backtrace) from [<c0bdaa74>] (rcu_dump_cpu_stacks+0x144/0x174)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602326] [<c0bda930>] (rcu_dump_cpu_stacks) from [<c02a47a0>] (rcu_sched_clock_irq+0x6a0/0xa78)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602337]  r10:c1203d00 r9:2ee72000 r8:00000000 r7:c1098800 r6:c1098800 r5:eff0a800
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602341]  r4:c1276f80
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602344] [<c02a4100>] (rcu_sched_clock_irq) from [<c02b2de0>] (update_process_times+0xb0/0xe0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602354]  r10:c02c6c9c r9:eff03640 r8:ffffe000 r7:0001979a r6:00000000 r5:c1203d00
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602358]  r4:eff02540
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602361] [<c02b2d30>] (update_process_times) from [<c02c6924>] (tick_sched_handle+0x64/0x70)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602372]  r7:0001979a r6:f2bdebba r5:c4fb9e20 r4:eff03990
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602375] [<c02c68c0>] (tick_sched_handle) from [<c02c6cf8>] (tick_sched_timer+0x5c/0xbc)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602386] [<c02c6c9c>] (tick_sched_timer) from [<c02b38ac>] (__hrtimer_run_queues+0xe8/0x328)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602397]  r9:eff03640 r8:ffffe000 r7:eff03680 r6:c120509c r5:eff03640 r4:eff03990
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602400] [<c02b37c4>] (__hrtimer_run_queues) from [<c02b49ec>] (hrtimer_interrupt+0x130/0x2bc)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602411]  r10:eff03790 r9:eff037c0 r8:ffffffff r7:7fffffff r6:00000003 r5:20000193
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602414]  r4:eff03640
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602417] [<c02b48bc>] (hrtimer_interrupt) from [<c09f94ac>] (arch_timer_handler_phys+0x38/0x40)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602431]  r10:ffffe000 r9:c4fb8000 r8:c1097158 r7:0000001b r6:c14ed540 r5:c1205b6c
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602434]  r4:c14ec400
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602437] [<c09f9474>] (arch_timer_handler_phys) from [<c0291fe0>] (handle_percpu_devid_irq+0x8c/0x1d0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602450] [<c0291f54>] (handle_percpu_devid_irq) from [<c028b7b4>] (handle_domain_irq+0x6c/0x88)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602460]  r7:0000001e r6:00000000 r5:00000000 r4:c109714c
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602464] [<c028b748>] (handle_domain_irq) from [<c02012dc>] (gic_handle_irq+0x8c/0xa0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602474]  r7:c4fb9e20 r6:f081400c r5:f0814000 r4:c1205b6c
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602477] [<c0201250>] (gic_handle_irq) from [<c0200aec>] (__irq_svc+0x4c/0x64)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602484] Exception stack(0xc4fb9e20 to 0xc4fb9e68)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602491] 9e20: c2221940 c221fc80 00000040 80001000 c0871a90 c2221940 c13c30c4 000000dd
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602497] 9e40: 00000023 c12f4830 ffffe000 c4fb9e7c 00000000 c4fb9e70 c0871398 c0871ad8
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602501] 9e60: 60000013 ffffffff
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602506]  r9:c4fb8000 r8:00000023 r7:c4fb9e54 r6:ffffffff r5:60000013 r4:c0871ad8
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602510] [<c0871a90>] (bcm2711_rng200_read) from [<c0871398>] (rng_dev_read+0x1a8/0x2c0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602523] [<c08711f0>] (rng_dev_read) from [<c0459288>] (vfs_read+0xb4/0x340)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602536]  r10:c08711f0 r9:c4fb9f58 r8:b54dd7a8 r7:00000000 r6:c1205048 r5:00000100
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602539]  r4:c53a5240
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602542] [<c04591d4>] (vfs_read) from [<c04599e4>] (ksys_read+0x68/0xe0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602553]  r10:00000100 r9:b54dd7a8 r8:c53a5240 r7:00000000 r6:00000000 r5:c53a5241
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602557]  r4:c1205048
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602560] [<c045997c>] (ksys_read) from [<c0459a74>] (sys_read+0x18/0x1c)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602571]  r10:00000003 r9:c4fb8000 r8:c0200244 r7:00000003 r6:00000100 r5:b54de900
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602574]  r4:0000001e
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602577] [<c0459a5c>] (sys_read) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602585] Exception stack(0xc4fb9fa8 to 0xc4fb9ff0)
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602591] 9fa0:                   0000001e b54de900 0000001e b54dd7a8 00000100 00000002
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602597] 9fc0: 0000001e b54de900 00000100 00000003 00000000 087bc608 087bc0f8 b54ddf7c
    Feb 16 20:44:37 SoftSPS1 kernel: [448173.602601] 9fe0: 0861f024 b54dd788 b6de0368 b6e39ce8
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633136] rcu: INFO: rcu_sched self-detected stall on CPU
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633145] rcu:   1-....: (8402 ticks this GP) idle=e3f/1/0x40000002 softirq=14115226/14115226 fqs=4200 
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633156]    (t=8403 jiffies g=37036717 q=15214)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633162] NMI backtrace for cpu 1
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633168] CPU: 1 PID: 14903 Comm: CMCommCycleTask Tainted: G         C        5.15.84-v7l+ #1613
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633174] Hardware name: BCM2711
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633178] Backtrace: 
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633184] [<c0bd8b34>] (dump_backtrace) from [<c0bd8d80>] (show_stack+0x20/0x24)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633199]  r7:00000080 r6:c0e3fc14 r5:00000080 r4:60000193
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633202] [<c0bd8d60>] (show_stack) from [<c0bdd490>] (dump_stack_lvl+0x70/0x94)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633211] [<c0bdd420>] (dump_stack_lvl) from [<c0bdd4cc>] (dump_stack+0x18/0x1c)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633222]  r7:00000080 r6:00000000 r5:00000001 r4:20000193
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633225] [<c0bdd4b4>] (dump_stack) from [<c07a5eec>] (nmi_cpu_backtrace+0x118/0x16c)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633236] [<c07a5dd4>] (nmi_cpu_backtrace) from [<c07a605c>] (nmi_trigger_cpumask_backtrace+0x11c/0x134)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633248]  r7:c0c020dc r6:00000001 r5:c1205b1c r4:c020eff0
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633251] [<c07a5f40>] (nmi_trigger_cpumask_backtrace) from [<c020fed8>] (arch_trigger_cpumask_backtrace+0x20/0x24)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633263]  r7:c1342116 r6:00000001 r5:c120514c r4:c1276f80
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633266] [<c020feb8>] (arch_trigger_cpumask_backtrace) from [<c0bdaa74>] (rcu_dump_cpu_stacks+0x144/0x174)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633276] [<c0bda930>] (rcu_dump_cpu_stacks) from [<c02a47a0>] (rcu_sched_clock_irq+0x6a0/0xa78)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633287]  r10:c1203d00 r9:2ee72000 r8:00000000 r7:c1098800 r6:c1098800 r5:eff0a800
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633290]  r4:c1276f80
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633293] [<c02a4100>] (rcu_sched_clock_irq) from [<c02b2de0>] (update_process_times+0xb0/0xe0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633303]  r10:c02c6c9c r9:eff03640 r8:ffffe000 r7:000197a9 r6:00000000 r5:c1203d00
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633307]  r4:eff02540
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633310] [<c02b2d30>] (update_process_times) from [<c02c6924>] (tick_sched_handle+0x64/0x70)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633321]  r7:000197a9 r6:9f9f6560 r5:c4fb9e20 r4:eff03990
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633324] [<c02c68c0>] (tick_sched_handle) from [<c02c6cf8>] (tick_sched_timer+0x5c/0xbc)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633334] [<c02c6c9c>] (tick_sched_timer) from [<c02b38ac>] (__hrtimer_run_queues+0xe8/0x328)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633345]  r9:eff03640 r8:ffffe000 r7:eff03680 r6:c120509c r5:eff03640 r4:eff03990
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633349] [<c02b37c4>] (__hrtimer_run_queues) from [<c02b49ec>] (hrtimer_interrupt+0x130/0x2bc)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633359]  r10:eff03790 r9:eff037c0 r8:ffffffff r7:7fffffff r6:00000003 r5:20000193
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633362]  r4:eff03640
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633365] [<c02b48bc>] (hrtimer_interrupt) from [<c09f94ac>] (arch_timer_handler_phys+0x38/0x40)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633379]  r10:ffffe000 r9:c4fb8000 r8:c1097158 r7:0000001b r6:c14ed540 r5:c1205b6c
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633382]  r4:c14ec400
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633385] [<c09f9474>] (arch_timer_handler_phys) from [<c0291fe0>] (handle_percpu_devid_irq+0x8c/0x1d0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633397] [<c0291f54>] (handle_percpu_devid_irq) from [<c028b7b4>] (handle_domain_irq+0x6c/0x88)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633407]  r7:0000001e r6:00000000 r5:00000000 r4:c109714c
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633410] [<c028b748>] (handle_domain_irq) from [<c02012dc>] (gic_handle_irq+0x8c/0xa0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633419]  r7:c4fb9e20 r6:f081400c r5:f0814000 r4:c1205b6c
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633423] [<c0201250>] (gic_handle_irq) from [<c0200aec>] (__irq_svc+0x4c/0x64)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633431] Exception stack(0xc4fb9e20 to 0xc4fb9e68)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633437] 9e20: c2221940 c221fc80 00000040 80001000 c0871a90 c2221940 c13c30c4 000000dd
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633442] 9e40: 00000023 c12f4830 ffffe000 c4fb9e7c 00000000 c4fb9e70 c0871398 c0871ad8
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633446] 9e60: 60000013 ffffffff
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633452]  r9:c4fb8000 r8:00000023 r7:c4fb9e54 r6:ffffffff r5:60000013 r4:c0871ad8
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633455] [<c0871a90>] (bcm2711_rng200_read) from [<c0871398>] (rng_dev_read+0x1a8/0x2c0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633467] [<c08711f0>] (rng_dev_read) from [<c0459288>] (vfs_read+0xb4/0x340)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633479]  r10:c08711f0 r9:c4fb9f58 r8:b54dd7a8 r7:00000000 r6:c1205048 r5:00000100
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633483]  r4:c53a5240
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633486] [<c04591d4>] (vfs_read) from [<c04599e4>] (ksys_read+0x68/0xe0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633497]  r10:00000100 r9:b54dd7a8 r8:c53a5240 r7:00000000 r6:00000000 r5:c53a5241
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633501]  r4:c1205048
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633504] [<c045997c>] (ksys_read) from [<c0459a74>] (sys_read+0x18/0x1c)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633514]  r10:00000003 r9:c4fb8000 r8:c0200244 r7:00000003 r6:00000100 r5:b54de900
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633518]  r4:0000001e
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633521] [<c0459a5c>] (sys_read) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633529] Exception stack(0xc4fb9fa8 to 0xc4fb9ff0)
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633534] 9fa0:                   0000001e b54de900 0000001e b54dd7a8 00000100 00000002
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633540] 9fc0: 0000001e b54de900 00000100 00000003 00000000 087bc608 087bc0f8 b54ddf7c
    Feb 16 20:45:40 SoftSPS1 kernel: [448236.633545] 9fe0: 0861f024 b54dd788 b6de0368 b6e39ce8
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664100] rcu: INFO: rcu_sched self-detected stall on CPU
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664110] rcu:   1-....: (14705 ticks this GP) idle=e3f/1/0x40000002 softirq=14115226/14115226 fqs=7352 
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664120]    (t=14706 jiffies g=37036717 q=26623)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664126] NMI backtrace for cpu 1
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664131] CPU: 1 PID: 14903 Comm: CMCommCycleTask Tainted: G         C        5.15.84-v7l+ #1613
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664138] Hardware name: BCM2711
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664142] Backtrace: 
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664148] [<c0bd8b34>] (dump_backtrace) from [<c0bd8d80>] (show_stack+0x20/0x24)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664162]  r7:00000080 r6:c0e3fc14 r5:00000080 r4:60000193
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664165] [<c0bd8d60>] (show_stack) from [<c0bdd490>] (dump_stack_lvl+0x70/0x94)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664175] [<c0bdd420>] (dump_stack_lvl) from [<c0bdd4cc>] (dump_stack+0x18/0x1c)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664185]  r7:00000080 r6:00000000 r5:00000001 r4:20000193
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664188] [<c0bdd4b4>] (dump_stack) from [<c07a5eec>] (nmi_cpu_backtrace+0x118/0x16c)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664200] [<c07a5dd4>] (nmi_cpu_backtrace) from [<c07a605c>] (nmi_trigger_cpumask_backtrace+0x11c/0x134)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664211]  r7:c0c020dc r6:00000001 r5:c1205b1c r4:c020eff0
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664214] [<c07a5f40>] (nmi_trigger_cpumask_backtrace) from [<c020fed8>] (arch_trigger_cpumask_backtrace+0x20/0x24)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664227]  r7:c1342116 r6:00000001 r5:c120514c r4:c1276f80
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664230] [<c020feb8>] (arch_trigger_cpumask_backtrace) from [<c0bdaa74>] (rcu_dump_cpu_stacks+0x144/0x174)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664239] [<c0bda930>] (rcu_dump_cpu_stacks) from [<c02a47a0>] (rcu_sched_clock_irq+0x6a0/0xa78)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664250]  r10:c1203d00 r9:2ee72000 r8:00000000 r7:c1098800 r6:c1098800 r5:eff0a800
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664253]  r4:c1276f80
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664257] [<c02a4100>] (rcu_sched_clock_irq) from [<c02b2de0>] (update_process_times+0xb0/0xe0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664266]  r10:c02c6c9c r9:eff03640 r8:ffffe000 r7:000197b8 r6:00000000 r5:c1203d00
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664270]  r4:eff02540
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664273] [<c02b2d30>] (update_process_times) from [<c02c6924>] (tick_sched_handle+0x64/0x70)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664284]  r7:000197b8 r6:4c80dec4 r5:c4fb9e20 r4:eff03990
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664287] [<c02c68c0>] (tick_sched_handle) from [<c02c6cf8>] (tick_sched_timer+0x5c/0xbc)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664297] [<c02c6c9c>] (tick_sched_timer) from [<c02b38ac>] (__hrtimer_run_queues+0xe8/0x328)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664308]  r9:eff03640 r8:ffffe000 r7:eff03680 r6:c120509c r5:eff03640 r4:eff03990
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664312] [<c02b37c4>] (__hrtimer_run_queues) from [<c02b49ec>] (hrtimer_interrupt+0x130/0x2bc)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664322]  r10:eff03790 r9:eff037c0 r8:ffffffff r7:7fffffff r6:00000003 r5:20000193
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664325]  r4:eff03640
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664328] [<c02b48bc>] (hrtimer_interrupt) from [<c09f94ac>] (arch_timer_handler_phys+0x38/0x40)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664342]  r10:ffffe000 r9:c4fb8000 r8:c1097158 r7:0000001b r6:c14ed540 r5:c1205b6c
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664346]  r4:c14ec400
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664349] [<c09f9474>] (arch_timer_handler_phys) from [<c0291fe0>] (handle_percpu_devid_irq+0x8c/0x1d0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664361] [<c0291f54>] (handle_percpu_devid_irq) from [<c028b7b4>] (handle_domain_irq+0x6c/0x88)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664371]  r7:0000001e r6:00000000 r5:00000000 r4:c109714c
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664374] [<c028b748>] (handle_domain_irq) from [<c02012dc>] (gic_handle_irq+0x8c/0xa0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664383]  r7:c4fb9e20 r6:f081400c r5:f0814000 r4:c1205b6c
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664386] [<c0201250>] (gic_handle_irq) from [<c0200aec>] (__irq_svc+0x4c/0x64)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664394] Exception stack(0xc4fb9e20 to 0xc4fb9e68)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664400] 9e20: c2221940 c221fc80 00000040 80001000 c0871a90 c2221940 c13c30c4 000000dd
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664406] 9e40: 00000023 c12f4830 ffffe000 c4fb9e7c 00000000 c4fb9e70 c0871398 c0871ad8
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664410] 9e60: 60000013 ffffffff
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664415]  r9:c4fb8000 r8:00000023 r7:c4fb9e54 r6:ffffffff r5:60000013 r4:c0871ad8
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664418] [<c0871a90>] (bcm2711_rng200_read) from [<c0871398>] (rng_dev_read+0x1a8/0x2c0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664431] [<c08711f0>] (rng_dev_read) from [<c0459288>] (vfs_read+0xb4/0x340)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664443]  r10:c08711f0 r9:c4fb9f58 r8:b54dd7a8 r7:00000000 r6:c1205048 r5:00000100
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664447]  r4:c53a5240
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664450] [<c04591d4>] (vfs_read) from [<c04599e4>] (ksys_read+0x68/0xe0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664461]  r10:00000100 r9:b54dd7a8 r8:c53a5240 r7:00000000 r6:00000000 r5:c53a5241
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664464]  r4:c1205048
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664467] [<c045997c>] (ksys_read) from [<c0459a74>] (sys_read+0x18/0x1c)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664478]  r10:00000003 r9:c4fb8000 r8:c0200244 r7:00000003 r6:00000100 r5:b54de900
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664482]  r4:0000001e
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664485] [<c0459a5c>] (sys_read) from [<c0200040>] (ret_fast_syscall+0x0/0x1c)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664493] Exception stack(0xc4fb9fa8 to 0xc4fb9ff0)
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664498] 9fa0:                   0000001e b54de900 0000001e b54dd7a8 00000100 00000002
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664504] 9fc0: 0000001e b54de900 00000100 00000003 00000000 087bc608 087bc0f8 b54ddf7c
    Feb 16 20:46:43 SoftSPS1 kernel: [448299.664508] 9fe0: 0861f024 b54dd788 b6de0368 b6e39ce8
    Feb 16 20:47:05 SoftSPS1 kernel: [448322.000861] device eth1 left promiscuous mode
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Booting Linux on physical CPU 0x0
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Linux version 5.15.84-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1613 SMP Thu Jan 5 12:01:26 GMT 2023
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] CPU: div instructions available: patching division code
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] random: crng init done
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Memory policy: Data cache writealloc
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x000000001ac00000, size 320 MiB
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Zone ranges:
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000002fffffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]   Normal   empty
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]   HighMem  [mem 0x0000000030000000-0x000000007fffffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Movable zone start for each node
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Early memory node ranges
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003e5fffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] On node 0, zone HighMem: 512 pages in unavailable ranges
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] percpu: Embedded 16 pages/cpu s35788 r8192 d21556 u65536
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] pcpu-alloc: s35788 r8192 d21556 u65536 alloc=16*4096
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 515904
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1  smsc95xx.macaddr=DC:A6:32:1F:BE:CA vc_mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000  console=ttyS0,115200 console=tty1 root=PARTUUID=ab83ed81-02 rootfstype=ext4 fsck.repair=yes rootwait
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] software IO TLB: mapped [mem 0x0000000015a00000-0x0000000019a00000] (64MB)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] Memory: 1638296K/2070528K available (10240K kernel code, 1386K rwdata, 3308K rodata, 2048K init, 591K bss, 104552K reserved, 327680K cma-reserved, 1284096K highmem)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] ftrace: allocating 35518 entries in 105 pages
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] ftrace: allocated 105 pages with 4 groups
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] trace event string verifier disabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] rcu: Hierarchical RCU implementation.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]     Rude variant of Tasks RCU enabled.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000]     Tracing variant of Tasks RCU enabled.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] GIC: Using split EOI/Deactivate mode
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 25)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000022] Switching to timer-based delay loop, resolution 18ns
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.000377] Console: colour dummy device 80x30
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001070] printk: console [tty1] enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001146] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001198] pid_max: default: 32768 minimum: 301
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001350] LSM: Security Framework initializing
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001561] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.001609] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.003157] cgroup: Disabling memory control group subsystem
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.003408] CPU: Testing write buffer coherency: ok
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.003478] CPU0: Spectre BHB: enabling loop workaround for all CPUs
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.003929] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.005426] Setting up static identity map for 0x200000 - 0x20003c
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.005640] rcu: Hierarchical SRCU implementation.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.006646] smp: Bringing up secondary CPUs ...
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.007806] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.009104] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.010424] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.010585] smp: Brought up 1 node, 4 CPUs
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.010664] SMP: Total of 4 processors activated (432.00 BogoMIPS).
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.010696] CPU: All CPU(s) started in HYP mode.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.010721] CPU: Virtualization extensions available.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.011483] devtmpfs: initialized
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.026525] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.026769] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.026825] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.036768] pinctrl core: initialized pinctrl subsystem
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.038013] NET: Registered PF_NETLINK/PF_ROUTE protocol family
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.042410] DMA: preallocated 1024 KiB pool for atomic coherent allocations
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.043238] audit: initializing netlink subsys (disabled)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.043502] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.044129] thermal_sys: Registered thermal governor 'step_wise'
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.044830] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.044888] hw-breakpoint: maximum watchpoint size is 8 bytes.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.045301] Serial: AMBA PL011 UART driver
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.059350] bcm2835-mbox fe00b880.mailbox: mailbox enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.100053] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-01-05T10:47:11, variant start_cd
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.110070] raspberrypi-firmware soc:firmware: Firmware hash is 8ba17717fbcedd4c3b6d4bce7e50c7af4155cba9
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.169067] Kprobes globally optimized
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.175079] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.179635] vgaarb: loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.180098] SCSI subsystem initialized
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.180424] usbcore: registered new interface driver usbfs
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.180501] usbcore: registered new interface driver hub
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.180589] usbcore: registered new device driver usb
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.181005] usb_phy_generic phy: supply vcc not found, using dummy regulator
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.181228] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.181574] pps_core: LinuxPPS API ver. 1 registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.181604] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.181653] PTP clock support registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.183224] clocksource: Switched to clocksource arch_sys_counter
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.263948] VFS: Disk quotas dquot_6.6.0
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.264077] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.264277] FS-Cache: Loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.264490] CacheFiles: Loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.274985] NET: Registered PF_INET protocol family
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.275243] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.276832] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.276888] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.276934] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.277017] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.277102] TCP: Hash tables configured (established 8192 bind 8192)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.277266] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.277322] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.277593] NET: Registered PF_UNIX/PF_LOCAL protocol family
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.284135] RPC: Registered named UNIX socket transport module.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.284169] RPC: Registered udp transport module.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.284196] RPC: Registered tcp transport module.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.284223] RPC: Registered tcp NFSv4.1 backchannel transport module.
    Feb 16 20:17:52 SoftSPS1 kernel: [    0.284259] PCI: CLS 0 bytes, default 64
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.337355] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.339996] Initialise system trusted keyrings
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.340330] workingset: timestamp_bits=14 max_order=19 bucket_order=5
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.349397] zbud: loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.351352] FS-Cache: Netfs 'nfs' registered for caching
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.352194] NFS: Registering the id_resolver key type
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.352263] Key type id_resolver registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.352291] Key type id_legacy registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.352433] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.352465] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.353627] Key type asymmetric registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.353660] Asymmetric key parser 'x509' registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.353902] bounce: pool size: 64 pages
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.353991] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.354235] io scheduler mq-deadline registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.354267] io scheduler kyber registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.363667] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.363731] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.363847] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.363961] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0400000000
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.425329] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.425708] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.425745] pci_bus 0000:00: root bus resource [bus 00-ff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.425780] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.425893] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.426154] pci 0000:00:00.0: PME# supported from D0 D3hot
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.429859] PCI: bus0: Fast back to back transfers disabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.429899] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.430306] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.430452] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.430900] pci 0000:01:00.0: PME# supported from D0 D3cold
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434587] PCI: bus1: Fast back to back transfers disabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434623] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434682] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434723] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434827] pci 0000:00:00.0: PCI bridge to [bus 01]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.434866] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.435293] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.435553] pcieport 0000:00:00.0: PME: Signaling with IRQ 77
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.449171] iproc-rng200 fe104000.rng: hwrng registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.449561] vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.451096] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.463711] brd: module loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.473843] loop: module loaded
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.474621] Loading iSCSI transport class v2.0-870.
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.479867] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.573384] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.574574] usbcore: registered new interface driver r8152
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.574679] usbcore: registered new interface driver lan78xx
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.574757] usbcore: registered new interface driver smsc95xx
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.576375] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.576566] xhci_hcd 0000:01:00.0: xHCI Host Controller
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.576633] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.582302] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0001e40000000890
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.583418] xhci_hcd 0000:01:00.0: xHCI Host Controller
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.583462] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.583509] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.583921] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.583966] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.584003] usb usb1: Product: xHCI Host Controller
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.584034] usb usb1: Manufacturer: Linux 5.15.84-v7l+ xhci-hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.584064] usb usb1: SerialNumber: 0000:01:00.0
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.584813] hub 1-0:1.0: USB hub found
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.584897] hub 1-0:1.0: 1 port detected
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.585961] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586006] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586043] usb usb2: Product: xHCI Host Controller
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586072] usb usb2: Manufacturer: Linux 5.15.84-v7l+ xhci-hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586102] usb usb2: SerialNumber: 0000:01:00.0
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586799] hub 2-0:1.0: USB hub found
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.586904] hub 2-0:1.0: 4 ports detected
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.588706] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589093] dwc_otg: FIQ enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589108] dwc_otg: NAK holdoff enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589121] dwc_otg: FIQ split-transaction FSM enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589138] Module dwc_common_port init
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589620] usbcore: registered new interface driver uas
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589745] usbcore: registered new interface driver usb-storage
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.589947] mousedev: PS/2 mouse device common for all mice
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.595150] sdhci: Secure Digital Host Controller Interface driver
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.595186] sdhci: Copyright(c) Pierre Ossman
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.595936] sdhci-pltfm: SDHCI platform and OF driver helper
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.599734] ledtrig-cpu: registered to indicate activity on CPUs
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.600107] hid: raw HID events driver (C) Jiri Kosina
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.600343] usbcore: registered new interface driver usbhid
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.600374] usbhid: USB HID core driver
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.606022] Initializing XFRM netlink socket
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.606085] NET: Registered PF_PACKET protocol family
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.606214] Key type dns_resolver registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.606604] Registering SWP/SWPB emulation handler
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.607445] registered taskstats version 1
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.607488] Loading compiled-in X.509 certificates
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.608309] Key type .fscrypt registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.608339] Key type fscrypt-provisioning registered
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.620591] uart-pl011 fe201000.serial: there is not valid maps for state default
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.620957] uart-pl011 fe201000.serial: cts_event_workaround enabled
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.621100] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.630464] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.631007] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.632329] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.632367] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.668311] mmc1: Controller never released inhibit bit(s).
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.671343] of_cfs_init
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.671587] of_cfs_init: OK
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.709066] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.709528] Waiting for root device PARTUUID=ab83ed81-02...
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.765584] mmc1: new high speed SDIO card at address 0001
    Feb 16 20:17:52 SoftSPS1 kernel: [    1.873259] usb 1-1: new high-speed USB device number 2 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.056349] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.056398] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.056433] usb 1-1: Product: USB2.0 Hub
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.058284] hub 1-1:1.0: USB hub found
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.058600] hub 1-1:1.0: 4 ports detected
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.203782] usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.238027] usb 2-1: New USB device found, idVendor=05e3, idProduct=0616, bcdDevice= 4.01
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.238072] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.238108] usb 2-1: Product: USB3.0 Hub
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.238137] usb 2-1: Manufacturer: GenesysLogic
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.240646] hub 2-1:1.0: USB hub found
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.241185] hub 2-1:1.0: 4 ports detected
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.393735] usb 2-2: new SuperSpeed USB device number 3 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.425575] usb 2-2: New USB device found, idVendor=174c, idProduct=0825, bcdDevice= 0.20
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.425621] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.425656] usb 2-2: Product: X825
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.425684] usb 2-2: Manufacturer: SupTronics
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.425713] usb 2-2: SerialNumber: 20220000000B
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.464245] scsi host0: uas
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.465621] scsi 0:0:0:0: Direct-Access     ASMT     2115             0    PQ: 0 ANSI: 6
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.467793] sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.468004] sd 0:0:0:0: [sda] Write Protect is off
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.468041] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.468435] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.469246] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.523271] usb 1-1.1: new high-speed USB device number 3 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.615543]  sda: sda1 sda2
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.617790] sd 0:0:0:0: [sda] Attached SCSI disk
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.637828] EXT4-fs (sda2): INFO: recovery required on readonly filesystem
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.637870] EXT4-fs (sda2): write access will be enabled during recovery
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.664931] usb 1-1.1: New USB device found, idVendor=05e3, idProduct=0610, bcdDevice= 4.01
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.665006] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.665062] usb 1-1.1: Product: USB2.0 Hub
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.665093] usb 1-1.1: Manufacturer: GenesysLogic
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.667933] hub 1-1.1:1.0: USB hub found
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.669130] hub 1-1.1:1.0: 4 ports detected
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.753495] usb 2-1.3: new SuperSpeed USB device number 4 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.786145] usb 2-1.3: New USB device found, idVendor=0bda, idProduct=8153, bcdDevice=30.00
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.786199] usb 2-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=6
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.786238] usb 2-1.3: Product: USB 10/100/1000 LAN
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.786268] usb 2-1.3: Manufacturer: Realtek
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.786297] usb 2-1.3: SerialNumber: 000233
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.893271] usb 1-1.4: new full-speed USB device number 4 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    2.997425] EXT4-fs (sda2): recovery complete
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.001508] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.001651] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.002209] devtmpfs: mounted
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013297] Freeing unused kernel image (initmem) memory: 2048K
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013639] Run /sbin/init as init process
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013667]   with arguments:
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013680]     /sbin/init
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013693]   with environment:
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013705]     HOME=/
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.013718]     TERM=linux
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.030733] usb 1-1.4: New USB device found, idVendor=064f, idProduct=2af9, bcdDevice= 1.00
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.030788] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.030827] usb 1-1.4: Product: CodeMeter-Stick
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.030857] usb 1-1.4: Manufacturer: WIBU-SYSTEMS AG
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.030886] usb 1-1.4: SerialNumber: 000003750368
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.043496] hid-generic 0003:064F:2AF9.0001: hiddev96,hidraw0: USB HID v1.11 Device [WIBU-SYSTEMS AG CodeMeter-Stick] on usb-0000:01:00.0-1.4/input0
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.123744] usb 2-1.4: new SuperSpeed USB device number 5 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.155360] usb 2-1.4: New USB device found, idVendor=0bda, idProduct=8153, bcdDevice=30.00
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.155416] usb 2-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=6
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.155454] usb 2-1.4: Product: USB 10/100/1000 LAN
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.155485] usb 2-1.4: Manufacturer: Realtek
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.155514] usb 2-1.4: SerialNumber: 000124
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.254048] usb 2-1.3: reset SuperSpeed USB device number 4 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.327702] NET: Registered PF_INET6 protocol family
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.329424] Segment Routing with IPv6
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.329503] In-situ OAM (IOAM) with IPv6
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.341367] r8152 2-1.3:1.0: load rtl8153a-4 v2 02/07/20 successfully
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.394669] r8152 2-1.3:1.0 eth1: v1.12.13
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.395004] usb 2-1.4: reset SuperSpeed USB device number 5 using xhci_hcd
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.465095] r8152 2-1.4:1.0: load rtl8153a-4 v2 02/07/20 successfully
    Feb 16 20:17:52 SoftSPS1 kernel: [    3.505515] r8152 2-1.4:1.0 eth2: v1.12.13
    Feb 16 20:17:52 SoftSPS1 kernel: [    4.314435] fuse: init (API version 7.34)
    Feb 16 20:17:52 SoftSPS1 kernel: [    4.845763] EXT4-fs (sda2): re-mounted. Opts: (null). Quota mode: none.
    Feb 16 20:17:52 SoftSPS1 kernel: [    5.898051] mc: Linux media interface: v0.10
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.017325] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.019544] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.019576] [vc_sm_connected_init]: start
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.046046] vc_sm_cma_vchi_init: failed to open VCHI service (-1)
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.046071] [vc_sm_connected_init]: failed to initialize shared memory service
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.134751] videodev: Linux video capture interface: v2.00
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.322698] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.341278] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.362267] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.365803] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.384422] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.386587] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413335] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413552] : Unregister from media controller
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413570] (efault): Unregistering node (null)[0] device node /dev/video0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413589] (efault): Unregistering node (null)[0] device node /dev/video0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413606] (efault): Unregistering node (null)[0] device node /dev/video0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.413622] (efault): Unregistering node (null)[0] device node /dev/video0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.416863] rpivid feb10000.codec: Device registered as /dev/video19
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.420166] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.458504] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.528920] brcmstb-i2c fef04500.i2c:  @97500hz registered in polling mode
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.529547] brcmstb-i2c fef09500.i2c:  @97500hz registered in polling mode
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.633349] sd 0:0:0:0: Attached scsi generic sg0 type 0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.834418] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.980856] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    6.989947] cfg80211: Loading compiled-in X.509 certificates for regulatory database
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.051115] Registered IR keymap rc-cec
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.051329] rc rc0: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.051562] input: vc4 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input0
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.059017] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.059247] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.067606] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.100268] Registered IR keymap rc-cec
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.100457] rc rc1: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.100702] input: vc4 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input1
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.116614] vc4-drm gpu: bound fef05700.hdmi (ops vc4_hdmi_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.117194] vc4-drm gpu: bound fe004000.txp (ops vc4_txp_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.117708] vc4-drm gpu: bound fe206000.pixelvalve (ops vc4_crtc_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.118202] vc4-drm gpu: bound fe207000.pixelvalve (ops vc4_crtc_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.118638] vc4-drm gpu: bound fe20a000.pixelvalve (ops vc4_crtc_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.119101] vc4-drm gpu: bound fe216000.pixelvalve (ops vc4_crtc_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.119529] vc4-drm gpu: bound fec12000.pixelvalve (ops vc4_crtc_ops [vc4])
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.168740] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.169103] vc4-drm gpu: [drm] Cannot find any crtc or sizes
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.251235] brcmfmac: F1 signature read @0x18000000=0x15264345
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.262302] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.264987] usbcore: registered new interface driver brcmfmac
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.545770] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
    Feb 16 20:17:52 SoftSPS1 kernel: [    7.551067] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
    Feb 16 20:17:52 SoftSPS1 kernel: [    8.351423] 8021q: 802.1Q VLAN Support v1.8
    Feb 16 20:17:52 SoftSPS1 kernel: [    8.397229] uart-pl011 fe201000.serial: no DMA platform data
    Feb 16 20:17:52 SoftSPS1 kernel: [    8.643307] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k FS
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.174949] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.176265] bcmgenet fd580000.ethernet eth0: Link is Down
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.219833] IPv6: ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.221353] r8152 2-1.4:1.0 eth2: carrier on
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.223883] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
    Feb 16 20:17:53 SoftSPS1 kernel: [    9.224799] r8152 2-1.3:1.0 eth1: carrier on
    Feb 16 20:17:57 SoftSPS1 kernel: [   13.353541] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
    Feb 16 20:17:57 SoftSPS1 kernel: [   13.353604] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.079983] Bluetooth: Core ver 2.22
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.080074] NET: Registered PF_BLUETOOTH protocol family
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.080085] Bluetooth: HCI device and connection manager initialized
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.080108] Bluetooth: HCI socket layer initialized
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.080122] Bluetooth: L2CAP socket layer initialized
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.080145] Bluetooth: SCO socket layer initialized
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.093192] Bluetooth: HCI UART driver ver 2.3
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.093248] Bluetooth: HCI UART protocol H4 registered
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.093324] Bluetooth: HCI UART protocol Three-wire (H5) registered
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.093575] Bluetooth: HCI UART protocol Broadcom registered
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.268652] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.268665] Bluetooth: BNEP filters: protocol multicast
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.268679] Bluetooth: BNEP socket layer initialized
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.282354] NET: Registered PF_ALG protocol family
    Feb 16 20:18:00 SoftSPS1 kernel: [   16.301839] cryptd: max_cpu_qlen set to 1000
    Feb 16 20:18:02 SoftSPS1 kernel: [   17.763579] vc4-drm gpu: [drm] Cannot find any crtc or sizes
    Feb 16 20:18:02 SoftSPS1 kernel: [   18.137081] process 'opt/codesys/bin/codesyscontrol_armv7l_raspberry.bin' started with executable stack
    Feb 16 20:18:03 SoftSPS1 kernel: [   19.154385] can: controller area network core
    Feb 16 20:18:03 SoftSPS1 kernel: [   19.154527] NET: Registered PF_CAN protocol family
    Feb 16 20:18:03 SoftSPS1 kernel: [   19.167156] can: raw protocol
    Feb 16 20:18:06 SoftSPS1 kernel: [   22.060896] tun: Universal TUN/TAP device driver, 1.6
    Feb 16 20:18:06 SoftSPS1 kernel: [   22.103555] device eth1 entered promiscuous mode
    Feb 16 20:18:06 SoftSPS1 kernel: [   22.103798] r8152 2-1.3:1.0 eth1: Promiscuous mode enabled
    Feb 16 20:18:06 SoftSPS1 kernel: [   22.153785] device eth2 entered promiscuous mode
    Feb 16 20:18:06 SoftSPS1 kernel: [   22.155353] r8152 2-1.4:1.0 eth2: Promiscuous mode enabled
    Feb 16 20:18:16 SoftSPS1 kernel: [   31.833242] cam-dummy-reg: disabling
    
     
  • christopher - 2023-03-10

    Hallo dav3,

    hast du hier schon eine Lösung gefunden? Aktuell habe ich die gleichen Probleme. Mein Pi stürzt in unregelmäßigen abständen ab. (Meistens 1-3 Tage). Ich kann mich dann auch nicht mehr über SSH verbinden.

    Das Pi wurde erst vor ca. 2 Wochen in Betrieb genommen mit der Runtime 4.6.0.0.

    Ich nutze keine OPC funktionen und habe an sich auch nur "normale" Logigbefehle im Programm.

    Zusätzlich nutze ich ein Wago IO Modul über Profinet und eine Ansteuerung zu einem Modbus Client.

    Gruß Christopher

     
  • dav3 - 2023-03-10

    Hallo Christopher,

    ich kann zu dem Problem noch nichts neues sagen, erstaunlicherweise läuft der Pi seit meinem letzten Post durch. Auf dem Pi läuft bei mir Version 4.7.0.0, war auch schon bei dem Ausfall so.

    Da ich meine IO's mit Ethercat angebunden habe, liegt es wohl nicht am Bus?! Obwohl die ersten Meldungen im log mich da stutzig machen (das mit dem "promiscuous mode"). Eth1 und eth2 sind bei mir die Schnittstellen an denen die Ethercat-Koppler hängen, im Ring. Vielleicht kannst du da mal in dein Kernel-Log schauen ob du diese Meldungen auch hast. Dieser "promiscuous mode" ist wohl ein Modus, in dem die Ethernet Schnittstelle alle Pakete empfängt, nicht nur die an den Port adressierten. So könnte das ein Bus-Neustart gewesen sein (nur eine Vermutung).

    Falls du mit Modbus Client eine Modbus-TCP Verbindung meinst, hätten wir hier eine Gemeinsamkeit. Allerdings habe ich dazu keine weiteren Hinweise. Paketverluste, Verbindungsabbruch oder ähnliche TCP Probleme im Netzwerk wären mir aufgefallen (Das hängt bei mir an einem Managed Switch mit Controller der das geloggt hätte).

    CMCommCycleTask scheint ja für Kommunikation zuständig, vielleicht gibt uns @eschwellinger ja mal einen Hinweis dazu ob und wie man da weitere Diagnose betreiben kann :)

    Bei Vorfällen oder Fortschritten werde ich hier aktualisieren.

    Ich wünsche ein schönes Wochenende, ohne Ausfälle ;)

    David

     
  • eschwellinger

    eschwellinger - 2023-03-10

    Hallo David,
    ich würde so vorgehen:
    Nachdem nachgewiesen wurde, dass es sich nicht um ein Checkbounds-Problem handelt (in IEC oben),
    https://faq.codesys.com/pages/viewpage.action?pageId=3538982
    würde ich empfehlen, in solchen Fällen auf der SPS-Seite wie folgt vorzugehen:

    Anmeldung per ssh-Client (oder lokal auf der PI)

    Runtime stoppen

    1.sudo /etc/init.d/codesyscontrol stoppen

    1. cd /var/opt/codesys

    sudo /opt/codesys/bin/codesyscontrol.bin -d /etc/CODESYSControl.cfg

    Öffnen Sie eine zweite ssh-Verbindung zum Pi und sehen Sie sich das Protokoll an

    tail -f /tmp/codesyscontrol.log

    Starten Sie die Laufzeitumgebung manuell per Befehl (um die Debug-Ausgabe zu erhalten)

    für die MC-Laufzeit

    cd /var/opt/codesys

    sudo /opt/codesys/bin/codesyscontrol_armv7l_raspberry.bin -d /etc/CODESYSControl.cfg

    Für SL-Laufzeit:

    cd /var/opt/codesys

    sudo /opt/codesys/bin/codesyscontrol_armv6l_raspberry.bin -d /etc/CODESYSControl.cfg

    wenn es nun passiert den Grund des Absturzes sehen (zusätzlich haben Sie im zweiten ssh-Fenster - die Ausgabe des Loggers auf der Laufzeitseite

    Natürlich sollten beide ssh-Verbindungen offen und verbunden bleiben, bis der Fehler auftritt!

    Wenn man das nicht kann (also +ber Tage hinweg mit ssh eingeloggt sein) wäre eine zweite Option:

    nano /etc/CODESYSControl.cfg
    
    [CmpLog]
    Logger.0.Name=/tmp/codesyscontrol.log
    Logger.0.Filter=0xFFFFFFFF
    Logger.0.Enable=1
    Logger.0.MaxEntries=100000
    Logger.0.MaxFileSize=1000000
    Logger.0.MaxFiles=1
    Logger.0.Backend.0.ClassId=0x00000104 ;writes logger messages in a file
    Logger.0.Backend.1.ClassId=0x0000010B ;writes logger messages to STDOUT
    Logger.0.Type=0x514 ;Set the timestamp to RTC/Dump Always
    Zusammen mit
        nano /etc/default/codesyscontrol
    
    
    #
    # DEBUGOUTPUT:
    # set to nonzero value to activate the debug output
    #
    # e.g.:
    DEBUGOUTPUT=1
    # DEBUGLOGFILE:
    # Debug output log file
    # only valid, ifDEBUGOUTPUT is active!
    # set to valid filepath
    # e.g.:
    DEBUGLOGFILE=/tmp/codesyscontrol_debug.log
    
     

    Last edit: eschwellinger 2023-03-10
  • dav3 - 2023-03-17

    Hallo,
    ich möchte einen Statusbericht abgeben.
    Bisher gab es keinen erneuten Ausfall. Da es nur sporadisch passiert, ist das Beobachten per SSH schwierig. Ich habe mir das mit dem checkbounds mal angesehen und habe dabei keine Probleme gefunden. Alle meine Arrays haben statische Grenzen und werden auch mit festen FOR-Schleifen bearbeitet.
    Ich wollte mir dann auch mal die RangeChecks ansehen, gibt es aber nicht für INT-Variablen.
    Ich vermute dort aber immer noch das Problem. Ich nutze die PID Regler aus der Util Library und wandle den Ausgang mit REAL_TO_INT (für 0-10V Ausgänge einer Beckhoff EL4008). Unter Umständen heißt das dann das die MIN/MAX Limits des Reglers nicht sauber funktionieren?!?
    Sollte es an diesen Umwandlungen liegen, wird die Gefahr eines erneuten Ausfalls auch erst wieder im nächsten Winter bestehen, die Regler steuern Stellantriebe an meinen Heizkörpern. ;)

    @eschwellinger eine Frage hätte ich noch:
    -Ich habe zur CODESYSControl.cfg ein PDF gefunden, hier ist bei CmpLog ein Eintrag

    ;Logger.0.Backend.2.ClassId=0x00000135 ;sends logger messages as UDP syslog

    angegeben. Es wäre toll wenn ich die Laufzeit an meinem Syslog-Server hängen könnte.
    Wie kann ich denn die IP und den Port des Servers festlegen? Oder gibt es irgendwo eine genauere Doku zur CODESYSControl.cfg?

     
    • eschwellinger

      eschwellinger - 2023-03-20

      Beispiel:
      [SysSocket]
      sysLog.IPAddress=192.168.100.0
      sysLog.Port=514

       
      • dav3 - 2023-03-20

        Super. Vielen Dank :)

         
  • alex - 2023-03-17

    Hab das selbe Problem, dass die Steuerung stehen bleibt und ein Verbinden mit ssh nicht mehr funktioniert.
    Dies ist aktuell nur lösbar durch Spannung schalten.

    Runtime Raspberry Pi MC 4.6.0.0

    Das einzige was mir nicht erklärlich ist, dass im Log die Einträge den Anhanges sind.

    Komischerweise wurde die Steuerung laut Log neu gestartet, reagierte nicht mehr und anschließend musste eben der Spannungsreset gemacht werden.
    Der erneute Neustart ist nicht im Log vermerkt !!! (2023-03-17T12:45:20Z)

    Wir hatten allerdings keinen Stromausfall noch war zu diesem Zeitpunkt jemand daheim.

     
  • eschwellinger

    eschwellinger - 2023-03-26

    Hi,
    ich würde vorsichtig behaupten das es kein CODESYS Problem ist,
    die Log sagen nichts von einem Absturz oder sonstigen Problemen.
    Wenn du per ssh nicht mehr drauf kommst, würde ich meinen ist das was eher was PI OS bedingtes.
    Also paar Fragen:
    - Netzteil stark genug?
    - Was läuft sonst noch auf dem PI?

     
    • alex - 2023-03-27

      Hi,
      Hab schon mehr getestet.

      1. "normaler" Aufbau mit SD Karte und PI OS Lite --> CPU auf performance gestellt
      2. "normaler" Aufbau mit SD Karte und PI OS Lite
      3. Image auf SSD und PI OS Lite

      Beides keine Verbesserung.
      Keine Veränderungen am OS.

      Netzteil ist original PI Netzteil.
      Hab Probleme mit StromPi und original Netzteil.

      Am WE hab ich jetzt auf die neueste Entwicklungsumgebung 3.5.19.0 und die dazu neuen Bibliotheken gewechselt.
      Mal sehen ob dies besser läuft.

      Mich machen im Log die Einträge mehrere Stunden bevor der Absturz kommt stutzig.
      Dazu kommt in den Logs kein Eintrag, wenn System daraufhin neu gestartet wird.

       
  • eschwellinger

    eschwellinger - 2023-03-27

    wenn du dich nicht mehr ssh einloggen kannst um zu untersuche wo das Problem liegt,
    musst du denke ich leider einen Monitor (über HDMI) und Tastatur anschließen und lokal schauen ob und was das Problem ist..!
    Wie gesagt ich kann es fast nicht glauben (den Logs nach zu urteilen) das es CODESYS sein soll..
    Welche Einträge meinst du mit "Mich machen im Log die Einträge mehrere Stunden bevor der Absturz kommt stutzig"
    Die Modbus Einträge?

     
  • dav3 - 2023-04-28

    Heute Nacht hatte ich wieder einen Ausfall, dummerweise hat er im debug-log nicht mitgeschrieben.
    Da habe ich wohl irgendwas verkonfiguriert... checke ich nochmal.
    Aber im normalem log habe ich ein paar Meldungen die ich vorher nicht hatte:

    2023-04-27T23:04:29Z, 0x00000001, 1, 0, 35, CODESYS Control shutdown...
    2023-04-27T23:04:30Z, 0x00000114, 2, 1, 0, !!!! Warning: SysTaskJoin [CMCommCycleTask]: error Connection timed out
    2023-04-27T23:04:56Z, 0x00000124, 1, 0, 0, Provider 'AlarmManager' (version 0x305121e) unregisterd at the OPC UA server.
    2023-04-27T23:04:56Z, 0x00000071, 1, 0, 13, Webserver stopped
    2023-04-27T23:04:56Z, 0x00000114, 2, 1, 0, !!!! Warning: SysTaskExit [MainTask]: lostcycles: [1]
    2023-04-27T23:05:06Z, 0x00000114, 2, 1, 0, !!!! Warning: SysTaskJoin [Modbus]: error Connection timed out
    2023-04-27T23:05:16Z, 0x00000114, 2, 1, 0, !!!! Warning: SysTaskJoin [Network]: error Connection timed out
    2023-04-27T23:05:16Z, 0x0000011c, 16, 0, 0, closeethernet(1): closing adapter
    

    Vor "CODESYS Control shutdown..." sind nur ältere Meldungen über geschlossene Webvisu Verbindungen, Stunden zuvor. Heißt das die Modbus TCP Verbindung verursacht das Problem?
    Vom Netzwerk (Switch und Gateway) habe ich keine Alarme zu diesem Zeitpunkt auf meinem Syslog-Server. Der Modbus TCP Slave ist ein Lüftungsgerät, davon bekomme ich keine logs.

    edit: die /etc/default/codesyscontrol war wieder auf ursprünglichem Zustand, weil ich die Runtime auf 4.8.0.0 und zurück auf 4.7.0.0 geladen habe... Mein Fehler...
    Mit der 4.8.0.0 konnte ich plötzlich keine Dateien mehr lesen/schreiben

     

    Related

    Talk.ru: 1


    Last edit: dav3 2023-04-28
  • eschwellinger

    eschwellinger - 2023-04-28

    Mit der 4.8.0.0 konnte ich plötzlich keine Dateien mehr lesen/schreiben
    wo schreibst du hin oder wo ließt du?

     
    • dav3 - 2023-04-28

      im user Ordner /home/pi habe ich einen Unterordner "runtimedata", indem ich Einstellungen und so was wegspeichere um sie bei einem Neustart laden zu können, finde ich für solche Sachen die sich nicht ständig ändern besser und zuverlässiger als Persistant (egal ob per GVL oder Application Composer). Ich nutze dazu die SysFile Funktionen, habe bei der Raspberry 4.8.0.0 aber invalid file handles 16#FFFFFFFF.

       
  • eschwellinger

    eschwellinger - 2023-04-29

    ja schreib zur Sps gehörende Files nach /var/opt/codesys/PlcLogic/Application/ dann gehts...

    Alternativ deinen Pfad im CODESYSControl_Usr.cfg hinzufügen
    Beispiel:

    [SysFile]
    PlaceholderFilePath.1=/home/pi, $DATA$
    PlaceholderFilePath.1.Volatile=1

     

    Last edit: eschwellinger 2023-04-29
  • richard-wang - 2024-02-16

    Hell Everyone

    Thanks for sharing these information, I currently have the same issue, not knowing how to resolve this. Does anyone have any update and or solution?

    Thanks much

     
  • richard-wang - 2024-02-16

    Hell Everyone

    Thanks for sharing these information, I currently have the same issue, not knowing how to resolve this. Does anyone have any update and or solution?

    Thanks much

     
  • dav3 - 2024-02-16

    Hello,

    I don't really know what my problem was, but it's working for me at the moment. All i did was to change my code because of some REAL_TO_INT conversions, like i wrote above. And later a complete new Pi setup (because of 64x support with 4.10.0.0 now).

     

Log in to post a comment.