No Connection using RaspBee II: After Upgrade from Raspian OS 10 buster 32bit armhf to Raspberry PI OS 12 / arm64

deCONZ General Support
1

Hello,

Unfortunately I cannot get my deCONZ Setup working anymore. Previously I had Raspian OS 10 buster 32bit armhf installed, then during the last upgrade / reinstall last Monday to Raspberry Pi OS 12 it is not working anymore.

I just found out that there is also an issue in the official raspberrypi GitHub issue tracker that might relate to this issues…?

GCFFlasher V3_17 (c) dresden elektronik ingenieurtechnik gmbh
Path             | Vendor | Product | Serial     | Type
-----------------+--------+---------+------------+-------
/dev/ttyS0       | 0x0000 | 0x0000  |            | RaspBee 

GCFFlasher V3_17 (c) dresden elektronik ingenieurtechnik gmbh
Reboot device RaspBee (RaspBee)
deCONZ firmware version 26780700
Bootloader version 0x00030003, app crc: 0x8F62A185

When I open up deCONZ App I can see RaspBee in the DropDown, but I cannot connect (inactive). Furthermore the web app shows also “no connection” in the Firmware section.

Thank you,
Joachim

2 
12:53:39:511 dev /dev/ttyS0 (/dev/ttyS0)
12:53:39:956 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:53:40:456 wait reconnect 5 seconds
12:53:40:510 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:40:511 dev /dev/ttyS0 (/dev/ttyS0)
12:53:41:457 wait reconnect 4 seconds
12:53:42:456 wait reconnect 3 seconds
12:53:43:456 wait reconnect 2 seconds
12:53:43:956 failed to reconnect to network try=6
12:53:44:456 wait reconnect 1 seconds
12:53:44:571 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:44:572 dev /dev/ttyS0 (/dev/ttyS0)
12:53:44:572 failed open com status: (-3), path: /dev/ttyS0
12:53:45:456 wait reconnect 5 seconds
12:53:45:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:45:511 dev /dev/ttyS0 (/dev/ttyS0)
12:53:46:456 wait reconnect 4 seconds
12:53:47:010 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:47:011 dev /dev/ttyS0 (/dev/ttyS0)
12:53:47:011 GW firmware update select /dev/ttyS0 device
12:53:47:013 GW update firmware not found: 
12:53:47:456 wait reconnect 3 seconds
12:53:48:457 wait reconnect 2 seconds
12:53:48:957 failed to reconnect to network try=7
12:53:49:457 wait reconnect 1 seconds
12:53:49:512 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:49:513 dev /dev/ttyS0 (/dev/ttyS0)
12:53:49:513 failed open com status: (-3), path: /dev/ttyS0
12:53:49:957 DB save zll database items 0x00000087
12:53:49:958 DB sql exec REPLACE INTO auth (apikey, devicetype, createdate, lastusedate, useragent) VALUES ('07919579F9', 'homebridge-hue#JotRPI', '2021-08-05T14:04:48', '2023-10-26T10:53:47', '')
12:53:49:964 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885418 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01'
12:53:49:967 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'state/reachable', 'true', 'dev', 1698317573 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01'
12:53:49:971 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697627869 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01'
12:53:49:973 DB sql exec REPLACE INTO nodes (id, state, mac, name, groups, endpoint, modelid, manufacturername, swbuildid, ritems) VALUES ('4', 'normal', '58:8e:81:ff:fe:62:39:91-01', 'JoSaHome ZigBee Cover 2', '65520,11', '1', 'FYRTUR block-out roller blind', 'IKEA of Sweden', '2.3.088', '{"attr/id":"4","attr/lastannounced":null,"attr/lastseen":"2023-10-26T10:52Z","attr/manufacturername":"IKEA of Sweden","attr/modelid":"FYRTUR block-out roller blind","attr/name":"JoSaHome ZigBee Cover 2","attr/swversion":"2.3.088","attr/type":"Window covering device","attr/uniqueid":"58:8e:81:ff:fe:62:39:91-01","state/lift":0,"state/open":true,"state/reachable":true}')
12:53:49:977 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885421 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01'
12:53:49:980 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'state/reachable', 'true', 'dev', 1698317573 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01'
12:53:49:983 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697742756 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01'
12:53:49:985 DB sql exec REPLACE INTO nodes (id, state, mac, name, groups, endpoint, modelid, manufacturername, swbuildid, ritems) VALUES ('5', 'normal', '58:8e:81:ff:fe:b4:63:96-01', 'JoSaHome ZigBee Cover 1', '65520,11', '1', 'FYRTUR block-out roller blind', 'IKEA of Sweden', '2.3.088', '{"attr/id":"5","attr/lastannounced":null,"attr/lastseen":"2023-10-26T10:52Z","attr/manufacturername":"IKEA of Sweden","attr/modelid":"FYRTUR block-out roller blind","attr/name":"JoSaHome ZigBee Cover 1","attr/swversion":"2.3.088","attr/type":"Window covering device","attr/uniqueid":"58:8e:81:ff:fe:b4:63:96-01","state/lift":100,"state/open":false,"state/reachable":true}')
12:53:49:988 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0xFFF0', 'All', 'normal', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:49:990 DB sql exec DELETE FROM scenes WHERE gid='0x0001'
12:53:49:991 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0001', 'JoSaHome Room 1', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:49:992 DB sql exec DELETE FROM scenes WHERE gid='0x0002'
12:53:49:993 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0002', 'TRADFRI open/close remote', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '68:0a:e2:ff:fe:f9:a0:15')
12:53:49:994 DB sql exec DELETE FROM scenes WHERE gid='0x0003'
12:53:49:995 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0003', 'Test', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:49:996 DB sql exec DELETE FROM scenes WHERE gid='0x0004'
12:53:49:997 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0004', 'TRADFRI open/close remote', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '68:0a:e2:ff:fe:f9:a0:15')
12:53:49:998 DB sql exec DELETE FROM scenes WHERE gid='0x0005'
12:53:49:999 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0005', 'Dummy Group', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:50:000 DB sql exec DELETE FROM scenes WHERE gid='0x0006'
12:53:50:001 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0006', 'TRADFRI open/close remote', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '68:0a:e2:ff:fe:fa:22:14')
12:53:50:002 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0007', 'TRADFRI SHORTCUT Button', 'normal', '', '7', '', 'false', 'LightGroup', 'Other', 'b4:e3:f9:ff:fe:67:a0:70')
12:53:50:003 DB sql exec DELETE FROM scenes WHERE gid='0x0008'
12:53:50:004 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0008', 'TRADFRI open/close remote', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '68:0a:e2:ff:fe:fa:22:14')
12:53:50:005 DB sql exec DELETE FROM scenes WHERE gid='0x0009'
12:53:50:006 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x0009', 'lklk', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:50:007 DB sql exec DELETE FROM scenes WHERE gid='0x000A'
12:53:50:008 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x000A', 'Test', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:50:009 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x000B', 'TRADFRI open/close remote', 'normal', '', '14', '', 'false', 'LightGroup', 'Other', '68:0a:e2:ff:fe:fa:22:14')
12:53:50:011 DB sql exec DELETE FROM scenes WHERE gid='0x000C'
12:53:50:012 DB sql exec REPLACE INTO groups (gid, name, state, mids, devicemembership, lightsequence, hidden, type, class, uniqueid) VALUES ('0x000C', 'JoSaHome Roller Shutter Group', 'deleted', '', '', '', 'false', 'LightGroup', 'Other', '')
12:53:50:015 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697627869 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01-0001'
12:53:50:016 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885418 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01-0001'
12:53:50:018 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/on', 'true', 'dev', 1680343529 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01-0001'
12:53:50:020 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/reachable', 'true', 'dev', 1695297542 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:62:39:91-01-0001'
12:53:50:022 DB sql exec REPLACE INTO sensors (sid, name, type, modelid, manufacturername, uniqueid, swversion, state, config, fingerprint, deletedState, mode, lastseen, lastannounced) VALUES ('12', 'FYRTUR block-out roller blind', 'ZHABattery', 'FYRTUR block-out roller blind', 'IKEA of Sweden', '58:8e:81:ff:fe:62:39:91-01-0001', '2.3.088', '{"battery":87,"lastupdated":"2023-10-21T10:50:23.090"}', '{"on":true,"reachable":true}', '{"d":514,"ep":1,"in":[0,1,258],"out":[25],"p":260}', 'normal', '1', '2023-10-26T10:52Z', '')
12:53:50:027 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697345420 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:029 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885305 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:031 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/on', 'true', 'dev', 1679998740 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:032 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/reachable', 'false', 'dev', 1697885406 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:034 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/battery', '100', 'dev', 1652392284 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:036 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/offset', '0', 'dev', 1666374054 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0402'
12:53:50:038 DB sql exec REPLACE INTO sensors (sid, name, type, modelid, manufacturername, uniqueid, swversion, state, config, fingerprint, deletedState, mode, lastseen, lastannounced) VALUES ('9', 'JoSaHome ZigBee Sensor TempHum 1', 'ZHATemperature', 'lumi.weather', 'LUMI', '00:15:8d:00:06:f8:3e:d6-01-0402', '0.0.0_0005', '{"lastupdated":"2023-10-21T10:48:25.438","temperature":2240,"temperature_bis":null}', '{"battery":100,"offset":0,"on":true,"reachable":false}', '{"d":24321,"ep":1,"in":[0,1026],"p":260}', 'normal', '1', '2023-10-26T10:52Z', '')
12:53:50:042 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697345420 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:044 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885305 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:046 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/on', 'true', 'dev', 1679998741 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:047 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/reachable', 'false', 'dev', 1697885406 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:049 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/battery', '100', 'dev', 1652392284 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:051 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/offset', '0', 'dev', 1652392284 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0405'
12:53:50:052 DB sql exec REPLACE INTO sensors (sid, name, type, modelid, manufacturername, uniqueid, swversion, state, config, fingerprint, deletedState, mode, lastseen, lastannounced) VALUES ('10', 'JoSaHome ZigBee Sensor TempHum 1', 'ZHAHumidity', 'lumi.weather', 'LUMI', '00:15:8d:00:06:f8:3e:d6-01-0405', '0.0.0_0005', '{"humidity":7418,"humidity_bis":null,"lastupdated":"2023-10-21T10:48:25.439"}', '{"battery":100,"offset":0,"on":true,"reachable":false}', '{"d":24321,"ep":1,"in":[0,1029],"p":260}', 'normal', '1', '2023-10-26T10:52Z', '')
12:53:50:056 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697345420 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:059 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885305 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:060 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/on', 'true', 'dev', 1679998743 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:062 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/reachable', 'false', 'dev', 1697885406 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:064 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/battery', '100', 'dev', 1652392284 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:065 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/offset', '0', 'dev', 1652392284 FROM sub_devices WHERE uniqueid = '00:15:8d:00:06:f8:3e:d6-01-0403'
12:53:50:067 DB sql exec REPLACE INTO sensors (sid, name, type, modelid, manufacturername, uniqueid, swversion, state, config, fingerprint, deletedState, mode, lastseen, lastannounced) VALUES ('11', 'JoSaHome ZigBee Sensor TempHum 1', 'ZHAPressure', 'lumi.weather', 'LUMI', '00:15:8d:00:06:f8:3e:d6-01-0403', '0.0.0_0005', '{"lastupdated":"2023-10-21T10:48:25.450","pressure":993,"pressure_bis":null}', '{"battery":100,"offset":0,"on":true,"reachable":false}', '{"d":24321,"ep":1,"in":[0,1027],"p":260}', 'normal', '1', '2023-10-26T10:52Z', '')
12:53:50:071 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastannounced', '', 'dev', 1697742756 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01-0001'
12:53:50:073 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'attr/lastseen', '2023-10-26T10:52Z', 'dev', 1697885421 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01-0001'
12:53:50:075 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/on', 'true', 'dev', 1680343529 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01-0001'
12:53:50:076 INSERT INTO resource_items (sub_device_id,item,value,source,timestamp) SELECT id, 'config/reachable', 'true', 'dev', 1697028107 FROM sub_devices WHERE uniqueid = '58:8e:81:ff:fe:b4:63:96-01-0001'
12:53:50:079 DB sql exec REPLACE INTO sensors (sid, name, type, modelid, manufacturername, uniqueid, swversion, state, config, fingerprint, deletedState, mode, lastseen, lastannounced) VALUES ('5', 'FYRTUR block-out roller blind', 'ZHABattery', 'FYRTUR block-out roller blind', 'IKEA of Sweden', '58:8e:81:ff:fe:b4:63:96-01-0001', '2.3.088', '{"battery":55,"lastupdated":"2023-10-21T10:50:24.593"}', '{"on":true,"reachable":true}', '{"d":514,"ep":1,"in":[0,1,258],"out":[25],"p":260}', 'normal', '1', '2023-10-26T10:52Z', '')
12:53:50:103 DB saved in 146 ms
12:53:50:104 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:53:50:457 wait reconnect 5 seconds
12:53:50:512 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:50:513 dev /dev/ttyS0 (/dev/ttyS0)
12:53:51:457 wait reconnect 4 seconds
12:53:52:457 wait reconnect 3 seconds
12:53:53:456 wait reconnect 2 seconds
12:53:53:957 failed to reconnect to network try=8
12:53:54:456 wait reconnect 1 seconds
12:53:54:510 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:54:510 dev /dev/ttyS0 (/dev/ttyS0)
12:53:54:511 failed open com status: (-3), path: /dev/ttyS0
12:53:55:456 wait reconnect 5 seconds
12:53:55:549 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:55:550 dev /dev/ttyS0 (/dev/ttyS0)
12:53:56:457 wait reconnect 4 seconds
12:53:57:010 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:57:011 dev /dev/ttyS0 (/dev/ttyS0)
12:53:57:011 GW firmware update select /dev/ttyS0 device
12:53:57:014 GW update firmware not found: 
12:53:57:456 wait reconnect 3 seconds
12:53:58:456 wait reconnect 2 seconds
12:53:58:957 failed to reconnect to network try=9
12:53:59:457 wait reconnect 1 seconds
12:53:59:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:53:59:511 dev /dev/ttyS0 (/dev/ttyS0)
12:53:59:512 failed open com status: (-3), path: /dev/ttyS0
12:53:59:957 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:54:00:456 wait reconnect 5 seconds
12:54:00:512 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:00:513 dev /dev/ttyS0 (/dev/ttyS0)
12:54:01:456 wait reconnect 4 seconds
12:54:01:593 New websocket 192.168.1.15:62326 (state: 3) 
12:54:02:460 wait reconnect 3 seconds
12:54:03:456 wait reconnect 2 seconds
12:54:03:957 failed to reconnect to network try=10
12:54:04:457 wait reconnect 1 seconds
12:54:04:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:04:512 dev /dev/ttyS0 (/dev/ttyS0)
12:54:04:513 failed open com status: (-3), path: /dev/ttyS0
12:54:05:457 wait reconnect 5 seconds
12:54:05:513 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:05:513 dev /dev/ttyS0 (/dev/ttyS0)
12:54:06:456 wait reconnect 4 seconds
12:54:07:062 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:07:062 dev /dev/ttyS0 (/dev/ttyS0)
12:54:07:063 GW firmware update select /dev/ttyS0 device
12:54:07:066 GW update firmware not found: 
12:54:07:456 wait reconnect 3 seconds
12:54:08:456 wait reconnect 2 seconds
12:54:08:957 reconnect network failed, try later
12:54:09:457 networkState: CC_ReconnectNetwork
12:54:09:457 start reconnect to network
12:54:09:457 wait reconnect 1 seconds
12:54:09:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:09:512 dev /dev/ttyS0 (/dev/ttyS0)
12:54:09:512 failed open com status: (-3), path: /dev/ttyS0
12:54:09:957 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:54:10:457 wait reconnect 5 seconds
12:54:10:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:10:512 dev /dev/ttyS0 (/dev/ttyS0)
12:54:11:456 wait reconnect 4 seconds
12:54:12:456 wait reconnect 3 seconds
12:54:13:456 wait reconnect 2 seconds
12:54:14:456 wait reconnect 1 seconds
12:54:14:510 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:14:511 dev /dev/ttyS0 (/dev/ttyS0)
12:54:14:512 failed open com status: (-3), path: /dev/ttyS0
12:54:14:707 failed to reconnect to network try=1
12:54:15:456 wait reconnect 5 seconds
12:54:15:510 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:15:511 dev /dev/ttyS0 (/dev/ttyS0)
12:54:16:456 wait reconnect 4 seconds
12:54:17:010 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:17:011 dev /dev/ttyS0 (/dev/ttyS0)
12:54:17:012 GW firmware update select /dev/ttyS0 device
12:54:17:015 GW update firmware not found: 
12:54:17:465 wait reconnect 3 seconds
12:54:18:465 wait reconnect 2 seconds
12:54:19:465 wait reconnect 1 seconds
12:54:19:519 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:19:519 dev /dev/ttyS0 (/dev/ttyS0)
12:54:19:520 failed open com status: (-3), path: /dev/ttyS0
12:54:19:973 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:54:19:974 failed to reconnect to network try=2
12:54:20:483 wait reconnect 5 seconds
12:54:20:537 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:20:538 dev /dev/ttyS0 (/dev/ttyS0)
12:54:21:467 wait reconnect 4 seconds
12:54:22:466 wait reconnect 3 seconds
12:54:23:456 wait reconnect 2 seconds
12:54:24:457 wait reconnect 1 seconds
12:54:24:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:24:511 dev /dev/ttyS0 (/dev/ttyS0)
12:54:24:512 failed open com status: (-3), path: /dev/ttyS0
12:54:24:956 failed to reconnect to network try=3
12:54:25:456 wait reconnect 5 seconds
12:54:25:511 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:25:511 dev /dev/ttyS0 (/dev/ttyS0)
12:54:26:456 wait reconnect 4 seconds
12:54:27:010 COM: /dev/ttyS0 :  (0x0000/0x0000)
12:54:27:011 dev /dev/ttyS0 (/dev/ttyS0)
12:54:27:011 GW firmware update select /dev/ttyS0 device
12:54:27:013 GW update firmware not found:
3

dmesg

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 6.1.0-rpi4-rpi-v8 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT Debian 1:6.1.54-1+rpt2 (2023-10-05)
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.1
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000000dc00000, size 512 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[    0.000000] On node 0, zone DMA32: 19456 pages in unavailable ranges
[    0.000000] On node 0, zone DMA32: 16384 pages in unavailable ranges
[    0.000000] percpu: Embedded 29 pages/cpu s79144 r8192 d31448 u118784
[    0.000000] pcpu-alloc: s79144 r8192 d31448 u118784 alloc=29*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v3a
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM erratum 1742098
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0  smsc95xx.macaddr=DC:A6:32:4A:36:B7 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=tty1 root=PARTUUID=29ae416d-02 rootfstype=ext4 fsck.repair=yes rootwait cfg80211.ieee80211_regdom=AT
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 4.
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 3339012K/4050944K available (12352K kernel code, 2172K rwdata, 4164K rodata, 4224K init, 1082K bss, 187644K reserved, 524288K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 40047 entries in 157 pages
[    0.000000] ftrace: allocated 157 pages with 5 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU event tracing is enabled.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] 	Trampoline variant of Tasks RCU enabled.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000001] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000313] Console: colour dummy device 80x25
[    0.000894] printk: console [tty1] enabled
[    0.000962] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.001003] pid_max: default: 32768 minimum: 301
[    0.001129] LSM: Security Framework initializing
[    0.001330] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001396] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.002682] cgroup: Disabling memory control group subsystem
[    0.004830] cblist_init_generic: Setting adjustable number of callback queues.
[    0.004863] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.005050] cblist_init_generic: Setting adjustable number of callback queues.
[    0.005078] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.005259] cblist_init_generic: Setting adjustable number of callback queues.
[    0.005286] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.005743] rcu: Hierarchical SRCU implementation.
[    0.005768] rcu: 	Max phase no-delay instances is 1000.
[    0.007944] EFI services will not be available.
[    0.008465] smp: Bringing up secondary CPUs ...
[    0.009516] Detected PIPT I-cache on CPU1
[    0.009661] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.010777] Detected PIPT I-cache on CPU2
[    0.010892] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.012021] Detected PIPT I-cache on CPU3
[    0.012140] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.012288] smp: Brought up 1 node, 4 CPUs
[    0.012377] SMP: Total of 4 processors activated.
[    0.012398] CPU features: detected: 32-bit EL0 Support
[    0.012416] CPU features: detected: 32-bit EL1 Support
[    0.012438] CPU features: detected: CRC32 instructions
[    0.012582] CPU: All CPU(s) started at EL2
[    0.012614] alternatives: applying system-wide alternatives
[    0.014417] devtmpfs: initialized
[    0.026253] Enabled cp15_barrier support
[    0.026313] Enabled setend support
[    0.026546] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.026598] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.039117] pinctrl core: initialized pinctrl subsystem
[    0.039986] DMI not present or invalid.
[    0.040675] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.044173] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.044493] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.045490] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.045597] audit: initializing netlink subsys (disabled)
[    0.045873] audit: type=2000 audit(0.044:1): state=initialized audit_enabled=0 res=1
[    0.046497] thermal_sys: Registered thermal governor 'step_wise'
[    0.046585] cpuidle: using governor menu
[    0.046860] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.047076] ASID allocator initialised with 32768 entries
[    0.047991] Serial: AMBA PL011 UART driver
[    0.063064] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.084296] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-08-10T15:33:38, variant start
[    0.088309] raspberrypi-firmware soc:firmware: Firmware hash is 03dc77429335caee083e22ddc8eec09c07f12a7a
[    0.104654] KASLR enabled
[    0.140555] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.146177] iommu: Default domain type: Translated 
[    0.146208] iommu: DMA domain TLB invalidation policy: strict mode 
[    0.146648] SCSI subsystem initialized
[    0.146906] usbcore: registered new interface driver usbfs
[    0.146975] usbcore: registered new interface driver hub
[    0.147054] usbcore: registered new device driver usb
[    0.147486] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.148041] pps_core: LinuxPPS API ver. 1 registered
[    0.148066] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.148106] PTP clock support registered
[    0.149188] vgaarb: loaded
[    0.149751] clocksource: Switched to clocksource arch_sys_counter
[    0.150480] VFS: Disk quotas dquot_6.6.0
[    0.150570] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.150780] FS-Cache: Loaded
[    0.150969] CacheFiles: Loaded
[    0.159876] NET: Registered PF_INET protocol family
[    0.160316] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.165022] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.165104] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.165234] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.165448] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[    0.166462] TCP: Hash tables configured (established 32768 bind 32768)
[    0.166906] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear)
[    0.167105] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.167175] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.167458] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.168213] RPC: Registered named UNIX socket transport module.
[    0.168241] RPC: Registered udp transport module.
[    0.168260] RPC: Registered tcp transport module.
[    0.168279] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.168313] PCI: CLS 0 bytes, default 64
[    0.168910] Trying to unpack rootfs image as initramfs...
[    0.179853] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.180298] kvm [1]: IPA Size Limit: 44 bits
[    0.181693] kvm [1]: vgic interrupt IRQ9
[    0.181985] kvm [1]: Hyp mode initialized successfully
[    1.014155] Freeing initrd memory: 16716K
[    1.244165] Initialise system trusted keyrings
[    1.244702] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.252377] zbud: loaded
[    1.255321] NFS: Registering the id_resolver key type
[    1.255375] Key type id_resolver registered
[    1.255397] Key type id_legacy registered
[    1.255519] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.255548] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.256912] Key type asymmetric registered
[    1.256943] Asymmetric key parser 'x509' registered
[    1.257043] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.257319] io scheduler mq-deadline registered
[    1.257348] io scheduler kyber registered
[    1.260405] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7ef00100, parent irq: 27)
[    1.271970] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.272029] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.272142] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.272255] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.272823] brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE
[    1.273311] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.273343] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.273371] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.273464] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.273781] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.277576] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    1.277884] pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator
[    1.278101] pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator
[    1.278221] pci_bus 0000:01: supply vpcie12v not found, using dummy regulator
[    1.387864] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.388045] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.388186] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.388683] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.397864] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    1.397922] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.397958] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.398050] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.398083] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.398557] pcieport 0000:00:00.0: enabling device (0000 -> 0002)
[    1.398806] pcieport 0000:00:00.0: PME: Signaling with IRQ 31
[    1.399322] pcieport 0000:00:00.0: AER: enabled with IRQ 31
[    1.410554] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.414407] iproc-rng200 fe104000.rng: hwrng registered
[    1.415029] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.429045] brd: module loaded
[    1.437536] loop: module loaded
[    1.438492] Loading iSCSI transport class v2.0-870.
[    1.444540] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.553918] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.555064] usbcore: registered new interface driver r8152
[    1.555148] usbcore: registered new interface driver lan78xx
[    1.555235] usbcore: registered new interface driver smsc95xx
[    1.556882] xhci_hcd 0000:01:00.0: enabling device (0000 -> 0002)
[    1.557003] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.557044] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.557842] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890
[    1.558529] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.558564] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.558600] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.558965] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[    1.559001] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.559029] usb usb1: Product: xHCI Host Controller
[    1.559053] usb usb1: Manufacturer: Linux 6.1.0-rpi4-rpi-v8 xhci-hcd
[    1.559076] usb usb1: SerialNumber: 0000:01:00.0
[    1.559795] hub 1-0:1.0: USB hub found
[    1.559903] hub 1-0:1.0: 1 port detected
[    1.560896] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01
[    1.560934] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.560962] usb usb2: Product: xHCI Host Controller
[    1.560985] usb usb2: Manufacturer: Linux 6.1.0-rpi4-rpi-v8 xhci-hcd
[    1.561009] usb usb2: SerialNumber: 0000:01:00.0
[    1.561690] hub 2-0:1.0: USB hub found
[    1.561824] hub 2-0:1.0: 4 ports detected
[    1.563614] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.563996] dwc_otg: FIQ enabled
[    1.564008] dwc_otg: NAK holdoff enabled
[    1.564018] dwc_otg: FIQ split-transaction FSM enabled
[    1.564034] Module dwc_common_port init
[    1.565405] usbcore: registered new interface driver uas
[    1.565513] usbcore: registered new interface driver usb-storage
[    1.566556] mousedev: PS/2 mouse device common for all mice
[    1.574105] sdhci: Secure Digital Host Controller Interface driver
[    1.574140] sdhci: Copyright(c) Pierre Ossman
[    1.574914] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.578969] ledtrig-cpu: registered to indicate activity on CPUs
[    1.579338] hid: raw HID events driver (C) Jiri Kosina
[    1.579576] usbcore: registered new interface driver usbhid
[    1.579602] usbhid: USB HID core driver
[    1.589474] NET: Registered PF_PACKET protocol family
[    1.589613] Key type dns_resolver registered
[    1.590993] registered taskstats version 1
4

dmesg

[    1.591095] Loading compiled-in X.509 certificates
[    1.618025] Loaded X.509 cert 'Debian Secure Boot CA: 6ccece7e4c6c0d1f6149f3dd27dfcc5cbb419ea1'
[    1.618171] Loaded X.509 cert 'Debian Secure Boot Signer 2022 - linux: 14011249c2675ea8e5148542202005810584b25f'
[    1.619017] Key type .fscrypt registered
[    1.619044] Key type fscrypt-provisioning registered
[    1.634608] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.635512] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.635671] fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[    1.635948] serial serial0: tty port ttyAMA1 registered
[    1.644201] bcm2835-aux-uart fe215040.serial: there is not valid maps for state default
[    1.645239] fe215040.serial: ttyS0 at MMIO 0xfe215040 (irq = 37, base_baud = 62500000) is a 16550
[    1.647107] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.647609] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.649191] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.649224] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.679585] of_cfs_init
[    1.679734] of_cfs_init: OK
[    1.714114] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.722423] Freeing unused kernel memory: 4224K
[    1.722647] Run /init as init process
[    1.722667]   with arguments:
[    1.722677]     /init
[    1.722687]   with environment:
[    1.722696]     HOME=/
[    1.722706]     TERM=linux
[    1.762135] mmc1: new high speed SDIO card at address 0001
[    1.813863] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.814336] mmc0: new ultra high speed DDR50 SDXC card at address 5048
[    1.815414] mmcblk0: mmc0:5048 SD128 116 GiB 
[    1.818902]  mmcblk0: p1 p2
[    1.819500] mmcblk0: mmc0:5048 SD128 116 GiB
[    1.968577] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.968633] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.968661] usb 1-1: Product: USB2.0 Hub
[    1.970900] hub 1-1:1.0: USB hub found
[    1.971179] hub 1-1:1.0: 4 ports detected
[    2.098235] usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd
[    2.119686] usb 2-2: New USB device found, idVendor=0bc2, idProduct=2322, bcdDevice= 0.00
[    2.119746] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    2.119776] usb 2-2: Product: Expansion
[    2.119799] usb 2-2: Manufacturer: Seagate
[    2.119822] usb 2-2: SerialNumber: NA818EET
[    2.143292] scsi host0: uas
[    2.154204] scsi 0:0:0:0: Direct-Access     Seagate  Expansion        9300 PQ: 0 ANSI: 6
[    2.157033] sd 0:0:0:0: [sda] 1953525167 512-byte logical blocks: (1.00 TB/932 GiB)
[    2.269806] usb 1-1.4: new full-speed USB device number 3 using xhci_hcd
[    2.370384] sd 0:0:0:0: [sda] Write Protect is off
[    2.370435] sd 0:0:0:0: [sda] Mode Sense: 4f 00 00 00
[    2.370830] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.371413] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
[    2.371445] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[    2.381128] usb 1-1.4: New USB device found, idVendor=04b3, idProduct=301a, bcdDevice= 2.00
[    2.381182] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.381212] usb 1-1.4: Product: USB 1.1 2port downstream low power hub
[    2.381238] usb 1-1.4: Manufacturer: Lite-On Technology
[    2.389829] hub 1-1.4:1.0: USB hub found
[    2.392628] hub 1-1.4:1.0: 3 ports detected
[    2.420386]  sda: sda1 sda2
[    2.421190] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.685839] usb 1-1.4.1: new full-speed USB device number 4 using xhci_hcd
[    2.703955] brcmstb-i2c fef04500.i2c:  @97500hz registered in polling mode
[    2.704968] brcmstb-i2c fef09500.i2c:  @97500hz registered in polling mode
[    2.801217] usb 1-1.4.1: New USB device found, idVendor=04b3, idProduct=301b, bcdDevice= 2.00
[    2.801275] usb 1-1.4.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
[    2.801327] usb 1-1.4.1: Product: USB Productivity Option Keyboard( has the hub in # 1 )
[    2.801357] usb 1-1.4.1: Manufacturer: Lite-On Technology
[    2.919067] input: Lite-On Technology USB Productivity Option Keyboard( has the hub in # 1 ) as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/0003:04B3:301B.0001/input/input0
[    2.919229] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    2.984553] vc4-drm gpu: bound fe400000.hvs (ops vc4_hvs_ops [vc4])
[    2.993300] Registered IR keymap rc-cec
[    2.993475] rc rc0: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0
[    2.993695] input: vc4-hdmi-0 as /devices/platform/soc/fef00700.hdmi/rc/rc0/input1
[    3.000610] hid-generic 0003:04B3:301B.0001: input,hidraw0: USB HID v1.10 Keyboard [Lite-On Technology USB Productivity Option Keyboard( has the hub in # 1 )] on usb-0000:01:00.0-1.4.1/input0
[    3.004795] vc4-drm gpu: bound fef00700.hdmi (ops vc4_hdmi_ops [vc4])
[    3.011874] Registered IR keymap rc-cec
[    3.013997] rc rc1: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1
[    3.014343] input: Lite-On Technology USB Productivity Option Keyboard( has the hub in # 1 ) as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.1/0003:04B3:301B.0002/input/input3
[    3.014586] input: vc4-hdmi-1 as /devices/platform/soc/fef05700.hdmi/rc/rc1/input2
[    3.074351] hid-generic 0003:04B3:301B.0002: input,hidraw1: USB HID v1.10 Device [Lite-On Technology USB Productivity Option Keyboard( has the hub in # 1 )] on usb-0000:01:00.0-1.4.1/input1
...
... (shortened)
...
[    3.700771] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none.
[    4.405956] systemd[1]: System time before build time, advancing clock.
[    4.594347] NET: Registered PF_INET6 protocol family
[    4.596078] Segment Routing with IPv6
[    4.596139] In-situ OAM (IOAM) with IPv6
[    4.674329] systemd[1]: systemd 252.17-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
[    4.674416] systemd[1]: Detected architecture arm64.
[    4.682727] systemd[1]: Hostname set to <JotRPI>.
[    5.593383] systemd[1]: Queued start job for default target multi-user.target.
[    5.613930] systemd[1]: Created slice system-getty.slice - Slice /system/getty.
[    5.616000] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
[    5.618235] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
[    5.619540] systemd[1]: Created slice user.slice - User and Session Slice.
[    5.620259] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
[    5.621555] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
[    5.622058] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
[    5.622494] systemd[1]: Reached target slices.target - Slice Units.
[    5.622784] systemd[1]: Reached target swap.target - Swaps.
[    5.623065] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
[    5.624141] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket.
[    5.624754] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe.
[    5.626814] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket.
[    5.628025] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
[    5.629284] systemd[1]: Listening on systemd-journald.socket - Journal Socket.
[    5.631714] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
[    5.632794] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
[    5.634007] systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    5.654363] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
[    5.659954] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
[    5.665784] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
[    5.666715] systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab).
[    5.672683] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout...
[    5.679068] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
[    5.685261] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
[    5.692131] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
[    5.698946] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
[    5.705711] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
[    5.712671] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
[    5.719639] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
[    5.721073] systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root).
[    5.731977] systemd[1]: Starting systemd-journald.service - Journal Service...
[    5.751498] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
[    5.757439] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
[    5.763655] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
[    5.775406] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
[    5.777229] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
[    5.779098] systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
[    5.781700] systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
[    5.782902] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
[    5.790458] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[    5.791675] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
[    5.794774] systemd[1]: modprobe@drm.service: Deactivated successfully.
[    5.795905] systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
[    5.798987] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
[    5.800328] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
[    5.807734] fuse: init (API version 7.37)
[    5.807892] systemd[1]: modprobe@loop.service: Deactivated successfully.
[    5.809643] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
[    5.812817] systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
[    5.814299] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
[    5.817669] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[    5.819662] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
[    5.827706] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
[    5.834842] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
[    5.835892] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
[    5.838226] i2c_dev: i2c /dev entries driver
[    5.859719] systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
[    5.861643] systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
[    5.875637] EXT4-fs (mmcblk0p2): re-mounted. Quota mode: none.
[    5.894612] systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
[    5.897367] systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
[    5.899125] systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes).
[    5.899440] systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
[    5.930932] systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed...
[    5.936886] systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
[    5.945403] systemd[1]: Starting systemd-sysusers.service - Create System Users...
[    6.021776] systemd[1]: Finished systemd-sysusers.service - Create System Users.
[    6.050899] systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
[    6.053468] systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed.
[    6.055366] systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes).
[    6.070131] systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
[    6.153074] systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
[    6.182767] systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
[    6.183559] systemd[1]: Started systemd-journald.service - Journal Service.
[    6.257067] systemd-journald[285]: Received client request to flush runtime journal.
[    6.268840] systemd-journald[285]: File /var/log/journal/24aa1a509e954952af9531ca44634928/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    7.003094] bcmgenet fd580000.ethernet end0: renamed from eth0
[    7.718471] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    7.721553] mc: Linux media interface: v0.10
[    7.749574] rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000
[    7.753090] rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem
[    7.893104] bcm2835_audio bcm2835_audio: card created with 8 channels
[    7.912976] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.918350] videodev: Linux video capture interface: v2.00
[    8.002567] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    8.002619] [vc_sm_connected_init]: start
[    8.008307] [vc_sm_connected_init]: installed successfully
[    8.137197] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    8.159067] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    8.179056] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    8.180589] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    8.192108] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    8.201664] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    8.207143] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    8.237601] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    8.240547] rpivid feb10000.codec: Device registered as /dev/video19
[    8.266627] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    8.266704] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    8.294218] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    8.294261] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.294285] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.294305] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.294324] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.295019] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    8.295069] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    8.317687] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    8.317806] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    8.378005] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    8.380947] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    8.381422] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    8.381479] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    8.381538] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    8.385056] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    8.385522] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    8.385558] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.385591] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.385611] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.385629] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.387421] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    8.410816] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    8.410870] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    8.703132] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    8.704155] cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf'
[    8.705090] cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328'
[    8.706129] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    8.709373] platform regulatory.0: firmware: direct-loading firmware regulatory.db
[    8.710518] platform regulatory.0: firmware: direct-loading firmware regulatory.db.p7s
[    8.776055] Bluetooth: Core ver 2.22
[    8.776536] NET: Registered PF_BLUETOOTH protocol family
[    8.776556] Bluetooth: HCI device and connection manager initialized
[    8.776585] Bluetooth: HCI socket layer initialized
[    8.776605] Bluetooth: L2CAP socket layer initialized
[    8.776652] Bluetooth: SCO socket layer initialized
[    8.884657] Bluetooth: HCI UART driver ver 2.3
[    8.884688] Bluetooth: HCI UART protocol H4 registered
[    8.895404] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    8.923014] Bluetooth: HCI UART protocol Broadcom registered
[    8.923922] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator
[    8.924200] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator
[    8.966104] brcmfmac: F1 signature read @0x18000000=0x15264345
[    8.997315] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.998399] usbcore: registered new interface driver brcmfmac
[    9.030880] brcmfmac mmc1:0001:1: firmware: direct-loading firmware brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin
[    9.032452] brcmfmac mmc1:0001:1: firmware: direct-loading firmware brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt
[    9.033492] brcmfmac mmc1:0001:1: firmware: direct-loading firmware brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.clm_blob
[    9.042211] uart-pl011 fe201000.serial: no DMA platform data
[    9.347253] Bluetooth: hci0: BCM: chip id 107
[    9.347674] Bluetooth: hci0: BCM: features 0x2f
[    9.348920] Bluetooth: hci0: BCM4345C0
[    9.348949] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000
[    9.349114] bluetooth hci0: firmware: failed to load brcm/BCM4345C0.raspberrypi,4-model-b.hcd (-2)
[    9.349134] firmware_class: See https://wiki.debian.org/Firmware for information about missing firmware
[    9.352018] bluetooth hci0: firmware: direct-loading firmware brcm/BCM4345C0.hcd
[    9.352056] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch
[    9.356237] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[   10.126795] Bluetooth: hci0: BCM: features 0x2f
[   10.128294] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190
[   10.128313] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382
[   10.256959] systemd-journald[285]: Oldest entry in /var/log/journal/24aa1a509e954952af9531ca44634928/system.journal is older than the configured file retention duration (1month), suggesting rotation.
[   10.257005] systemd-journald[285]: /var/log/journal/24aa1a509e954952af9531ca44634928/system.journal: Journal header limits reached or header out-of-date, rotating.
[   10.849051] rtc-pcf85063 1-0051: registered as rtc0
[   10.850268] rtc-pcf85063 1-0051: setting system clock to 2023-10-25T21:37:34 UTC (1698269854)
[   10.850434] i2c i2c-1: new_device: Instantiated device pcf85063 at 0x51
[   10.977185] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   11.566272] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   11.566303] Bluetooth: BNEP filters: protocol multicast
[   11.566332] Bluetooth: BNEP socket layer initialized
[   11.579192] Bluetooth: MGMT ver 1.22
[   11.615294] NET: Registered PF_ALG protocol family
[   12.470796] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[   12.471678] bcmgenet fd580000.ethernet end0: Link is Down
[   12.490944] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   18.004220] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   45.081522] Bluetooth: RFCOMM TTY layer initialized
[   45.081560] Bluetooth: RFCOMM socket layer initialized
[   45.081585] Bluetooth: RFCOMM ver 1.11
5

Luckily found the solution: my service user pi (relict from the previous installation) was missing dialout group membership. Is there something I was missing during install?