pivccu3 startet und stoppt mit Fehlern

Virtualisierte CCU für Raspberry Pi und Clones

Moderator: Co-Administratoren

Antworten
wok1415
Beiträge: 118
Registriert: 16.07.2020, 15:52
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 1 Mal
Danksagung erhalten: 3 Mal

pivccu3 startet und stoppt mit Fehlern

Beitrag von wok1415 » 19.06.2021, 18:11

Auf meinem Armbian System (Armbian 21.05.4 Buster with Linux 5.10.43-rockchip64) läuft die aktuelle pivccu Version. Mir ist aufgefallen, dass beim Starten und Stoppen der pivccu Fehler auftreten:

Code: Alles auswählen

 monitor_hb_rf_connection.sh[2482]: lxc-attach: lxc: lsm/lsm.c: lsm_process_label_set_at: 174 Operation not permitted - Failed to set AppArmor label "unconfined"
Alles scheint trotzdem wie gewohnt zu funktionieren. Kann mir jemand vielleicht einen Hinweis geben, was da möglicherweise falsch läuft ?

Viele Grüße !

Code: Alles auswählen

# pivccu-info
piVCCU version: 3.57.5-57
Kernel modules: Available
Raw UART dev:   Available
HMRF Hardware:  RPI-RF-MOD
 Connected via: HB-RF-ETH@192.xxx.xxx.xxx (/dev/raw-uart)
 Board serial:  xxxxxxxxxx
 Radio MAC:     0xxxxxxx
HMIP Hardware:  RPI-RF-MOD
 SGTIN:         xxxxxxxxxxxxxxxxxxxxxxxxx
 Radio MAC:     0xxxxxxx
State:          RUNNING
PID:            6256
IP:             192.xxx.xxx.xxx
IP:             2a02:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx
CPU use:        50.13 seconds
BlkIO use:      592.00 KiB
Memory use:     197.91 MiB
KMem use:       6.74 MiB
Link:           vethpivccu
 TX bytes:      319.77 KiB
 RX bytes:      2.65 MiB
 Total bytes:   2.96 MiB
# systemctl start pivccu

Code: Alles auswählen

# cat /var/log/syslog 
Jun 19 17:46:07 helios64 kernel: [  665.723538] IPv6: ADDRCONF(NETDEV_CHANGE): vethpivccu: link becomes ready
Jun 19 17:46:07 helios64 kernel: [  665.723630] br0: port 2(vethpivccu) entered blocking state
Jun 19 17:46:07 helios64 kernel: [  665.723636] br0: port 2(vethpivccu) entered forwarding state
Jun 19 17:46:07 helios64 systemd-networkd[486]: vethpivccu: Gained carrier
Jun 19 17:46:07 helios64 systemd[1]: Started piVCCU.
Jun 19 17:46:07 helios64 systemd[1]: Started Monitor HB-RF-ETH.
Jun 19 17:46:09 helios64 avahi-daemon[826]: Joining mDNS multicast group on interface vethpivccu.IPv6 with address fe80::xxxx:xxxx:xxxx:xxxx.
Jun 19 17:46:09 helios64 systemd-networkd[486]: vethpivccu: Gained IPv6LL
Jun 19 17:46:09 helios64 avahi-daemon[826]: New relevant interface vethpivccu.IPv6 for mDNS.
Jun 19 17:46:09 helios64 avahi-daemon[826]: Registering new address record for fe80::xxxx:xxxx:xxxx:xxxx on vethpivccu.*.
Jun 19 17:46:26 helios64 kernel: [  684.535320] eq3loop: eq3loop_open_slave() mmd_bidcos
Jun 19 17:46:38 helios64 kernel: [  696.866466] eq3loop: eq3loop_open_slave() mmd_hmip
Jun 19 17:46:38 helios64 kernel: [  696.866580] eq3loop: eq3loop_close_slave() mmd_hmip
Jun 19 17:46:38 helios64 kernel: [  696.868233] eq3loop: eq3loop_close_slave() mmd_hmip
Jun 19 17:46:52 helios64 monitor_hb_rf_connection.sh[6281]: Waiting for CCU startup ............................................. Done.
Jun 19 17:46:52 helios64 monitor_hb_rf_connection.sh[6281]: lxc-attach: lxc: lsm/lsm.c: lsm_process_label_set_at: 174 Operation not permitted - Failed to set AppArmor label "unconfined"
Jun 19 17:46:52 helios64 monitor_hb_rf_connection.sh[6281]: HB-RF-ETH is (re-)connected
Jun 19 17:46:53 helios64 monitor_hb_rf_connection.sh[6281]: lxc-attach: lxc: lsm/lsm.c: lsm_process_label_set_at: 174 Operation not permitted - Failed to set AppArmor label "unconfined"
# systemctl stop pivccu

Code: Alles auswählen

Jun 19 18:00:33 grid systemd[1]: Stopping Monitor HB-RF-ETH...
Jun 19 18:00:33 grid systemd[1]: monitor-hb-rf-eth.service: Main process exited, code=killed, status=15/TERM
Jun 19 18:00:33 grid systemd[1]: monitor-hb-rf-eth.service: Succeeded.
Jun 19 18:00:33 grid systemd[1]: Stopped Monitor HB-RF-ETH.
Jun 19 18:00:33 grid systemd[1]: Stopping piVCCU...
Jun 19 18:00:33 grid stop_container.sh[7978]: lxc-attach: lxc: lsm/lsm.c: lsm_process_label_set_at: 174 Operation not permitted - Failed to set AppArmor label "unconfined"
Jun 19 18:00:33 grid stop_container.sh[7978]: lxc-attach: lxc: lsm/lsm.c: lsm_process_label_set_at: 174 Operation not permitted - Failed to set AppArmor label "unconfined"
Jun 19 18:02:03 grid systemd[1]: pivccu.service: Stopping timed out. Terminating.
Jun 19 18:02:03 grid systemd[1]: pivccu.service: Control process exited, code=killed, status=15/TERM
Jun 19 18:02:12 grid kernel: [ 1630.249354] eq3loop: eq3loop_close_master() mmd_bidcos
Jun 19 18:02:12 grid kernel: [ 1630.249378] eq3loop: eq3loop_close_master() mmd_bidcos destroy device
Jun 19 18:02:12 grid kernel: [ 1630.249781] eq3loop: eq3loop_close_master() mmd_hmip
Jun 19 18:02:12 grid kernel: [ 1630.550853] eq3loop: eq3loop_close_slave() mmd_hmip
Jun 19 18:02:12 grid kernel: [ 1630.550862] eq3loop: eq3loop_close_slave() mmd_hmip destroy device
Jun 19 18:02:14 grid kernel: [ 1632.666374] br0: port 2(vethpivccu) entered disabled state
Jun 19 18:02:14 grid kernel: [ 1632.669127] device vethpivccu left promiscuous mode
Jun 19 18:02:14 grid kernel: [ 1632.669260] br0: port 2(vethpivccu) entered disabled state
Jun 19 18:02:14 grid avahi-daemon[826]: Interface vethpivccu.IPv6 no longer relevant for mDNS.
Jun 19 18:02:14 grid avahi-daemon[826]: Leaving mDNS multicast group on interface vethpivccu.IPv6 with address fe80::xxxx:xxxx:xxxx:xxxx.
Jun 19 18:02:14 grid systemd-networkd[486]: vethpivccu: Lost carrier
Jun 19 18:02:14 grid avahi-daemon[826]: Withdrawing address record for fe80::xxxx:xxxx:xxxx:xxxx on vethpivccu.
Jun 19 18:02:14 grid kernel: [ 1632.739659] br0: port 2(vethpivccu) entered blocking state
Jun 19 18:02:14 grid kernel: [ 1632.739749] br0: port 2(vethpivccu) entered disabled state
Jun 19 18:02:14 grid kernel: [ 1632.740422] device vethpivccu entered promiscuous mode
Jun 19 18:02:14 grid systemd-udevd[8216]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 19 18:02:14 grid kernel: [ 1632.742569] br0: port 2(vethpivccu) entered blocking state
Jun 19 18:02:14 grid kernel: [ 1632.742601] br0: port 2(vethpivccu) entered forwarding state
Jun 19 18:02:14 grid systemd-udevd[8086]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 19 18:02:14 grid systemd-udevd[8086]: Using default interface naming scheme 'v240'.
Jun 19 18:02:14 grid systemd-udevd[8216]: Using default interface naming scheme 'v240'.
Jun 19 18:02:14 grid systemd-udevd[8216]: Could not generate persistent MAC address for vethE0SQDL: No such file or directory
Jun 19 18:02:14 grid networkd-dispatcher[824]: WARNING:Unknown index 12 seen, reloading interface list
Jun 19 18:02:14 grid kernel: [ 1632.823944] eth0: renamed from vethE0SQDL
Jun 19 18:02:14 grid kernel: [ 1632.844769] IPv6: ADDRCONF(NETDEV_CHANGE): vethpivccu: link becomes ready
Jun 19 18:02:14 grid systemd-networkd[486]: vethpivccu: Gained carrier
Jun 19 18:02:14 grid networkd-dispatcher[824]: WARNING:Unknown index 11 seen, reloading interface list
Jun 19 18:02:14 grid networkd-dispatcher[824]: ERROR:Unknown interface index 11 seen even after reload
Jun 19 18:02:15 grid avahi-daemon[826]: Joining mDNS multicast group on interface vethpivccu.IPv6 with address fe80::xxxx:xxxx:xxxx:xxxx.
Jun 19 18:02:15 grid avahi-daemon[826]: New relevant interface vethpivccu.IPv6 for mDNS.
Jun 19 18:02:15 grid systemd-networkd[486]: vethpivccu: Gained IPv6LL
Jun 19 18:02:15 grid avahi-daemon[826]: Registering new address record for fe80::xxxx:xxxx:xxxx:xxxx on vethpivccu.*.
Jun 19 18:02:33 grid kernel: [ 1651.669247] eq3loop: eq3loop_open_slave() mmd_bidcos
Jun 19 18:02:46 grid kernel: [ 1664.263888] eq3loop: eq3loop_open_slave() mmd_hmip
Jun 19 18:03:33 grid systemd[1]: pivccu.service: State 'stop-sigterm' timed out. Killing.
Jun 19 18:03:33 grid systemd[1]: pivccu.service: Killing process 6242 (lxc-start) with signal SIGKILL.
Jun 19 18:03:33 grid systemd[1]: pivccu.service: Main process exited, code=killed, status=9/KILL
Jun 19 18:03:33 grid systemd[1]: pivccu.service: Failed with result 'timeout'.
Jun 19 18:03:33 grid systemd[1]: Stopped piVCCU.
Jun 19 18:03:33 grid kernel: [ 1711.720127] eq3loop: eq3loop_close_master() mmd_bidcos
Jun 19 18:03:33 grid kernel: [ 1711.720160] eq3loop: eq3loop_close_master() mmd_hmip
Jun 19 18:03:33 grid kernel: [ 1711.755154] eq3loop: eq3loop_close_slave() mmd_bidcos
Jun 19 18:03:33 grid kernel: [ 1711.755185] eq3loop: eq3loop_close_slave() mmd_bidcos destroy device
Jun 19 18:03:33 grid kernel: [ 1711.865721] eq3loop: eq3loop_close_slave() mmd_hmip
Jun 19 18:03:33 grid avahi-daemon[826]: Interface vethpivccu.IPv6 no longer relevant for mDNS.
Jun 19 18:03:33 grid avahi-daemon[826]: Leaving mDNS multicast group on interface vethpivccu.IPv6 with address fe80::xxxx:xxxx:xxxx:xxxx.
Jun 19 18:03:33 grid systemd-networkd[486]: vethpivccu: Lost carrier
Jun 19 18:03:33 grid kernel: [ 1711.960629] br0: port 2(vethpivccu) entered disabled state
Jun 19 18:03:33 grid kernel: [ 1711.962294] device vethpivccu left promiscuous mode
Jun 19 18:03:33 grid kernel: [ 1711.962320] br0: port 2(vethpivccu) entered disabled state
Jun 19 18:03:33 grid networkd-dispatcher[824]: Failed to request link: No such device
Jun 19 18:03:33 grid avahi-daemon[826]: Withdrawing address record for fe80::xxxx:xxxx:xxxx:xxxx on vethpivccu.
Jun 19 18:03:33 grid networkd-dispatcher[824]: ERROR:Failed to get interface "vethpivccu" status: Command '['/usr/bin/networkctl', 'status', '--no-pager', '--no-legend', '--', 'vethpivccu']' returned non-zero exit status 1.

wok1415
Beiträge: 118
Registriert: 16.07.2020, 15:52
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 1 Mal
Danksagung erhalten: 3 Mal

Re: pivccu3 startet und stoppt mit Fehlern

Beitrag von wok1415 » 27.08.2021, 16:37

Das Problem hat sich von selbst gelöst: Nach dem Upgrade von Armbian Buster zu Bullseye hat sich das erledigt.
Die Ursache war ein lxc-attach bug, der mit der aktualisierten Version offensichtlich behoben wurde.

Viele Grüße !

Antworten

Zurück zu „piVCCU“