search

home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.83k stars 965 forks source link

HassOS 4.8 : Pizigate NoResponseError #704

Closed cyr-ius closed 4 years ago

cyr-ius commented 4 years ago

HassOS release with the issue: 4.8

Supervisor logs: RAS

Journal logs: RAS

Kernel logs: [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 5.4.42-v7 (builder@01d09e68e283) (gcc version 9.3.0 (Buildroot 2020.02.2-g426156dc)) #1 SMP Thu May 28 03:25:54 UTC 2020 [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] On node 0 totalpages: 242688 [ 0.000000] Normal zone: 2133 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 242688 pages, LIFO batch:63 [ 0.000000] percpu: Embedded 20 pages/cpu s49868 r8192 d23860 u81920 [ 0.000000] pcpu-alloc: s49868 r8192 d23860 u81920 alloc=20*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240555 [ 0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=e9cdbf9e88ef4debbe0446604f5239f0 cgroup_enable=memory coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rauc.slot=B [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 879404K/970752K available (10240K kernel code, 728K rwdata, 2868K rodata, 1024K init, 843K bss, 25812K reserved, 65536K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 32214 entries in 63 pages [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x328/0x50c with crng_init=0 [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000021] Switching to timer-based delay loop, resolution 52ns [ 0.000280] Console: colour dummy device 80x30 [ 0.000901] printk: console [tty1] enabled [ 0.000969] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) [ 0.001018] pid_max: default: 32768 minimum: 301 [ 0.001226] LSM: Security Framework initializing [ 0.001375] AppArmor: AppArmor initialized [ 0.001600] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.001646] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.002910] CPU: Testing write buffer coherency: ok [ 0.003485] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.004482] Setting up static identity map for 0x100000 - 0x10003c [ 0.004705] rcu: Hierarchical SRCU implementation. [ 0.005418] smp: Bringing up secondary CPUs ... [ 0.006549] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.007818] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 0.008976] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.009130] smp: Brought up 1 node, 4 CPUs [ 0.009219] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.009248] CPU: All CPU(s) started in HYP mode. [ 0.009274] CPU: Virtualization extensions available. [ 0.010348] devtmpfs: initialized [ 0.027015] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 [ 0.027330] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.027387] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.030665] pinctrl core: initialized pinctrl subsystem [ 0.031767] NET: Registered protocol family 16 [ 0.036162] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.040979] audit: initializing netlink subsys (disabled) [ 0.041272] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1 [ 0.042916] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.042955] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.043191] Serial: AMBA PL011 UART driver [ 0.045195] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.070090] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-05-27 12:10, variant start [ 0.080104] raspberrypi-firmware soc:firmware: Firmware hash is 369ed4e44cb5a080a2dfa7f854ae4ff46b7c9ef9 [ 0.139094] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.141578] SCSI subsystem initialized [ 0.141855] usbcore: registered new interface driver usbfs [ 0.141938] usbcore: registered new interface driver hub [ 0.142084] usbcore: registered new device driver usb [ 0.142166] mc: Linux media interface: v0.10 [ 0.142240] videodev: Linux video capture interface: v2.00 [ 0.144446] clocksource: Switched to clocksource arch_sys_counter [ 1.312728] VFS: Disk quotas dquot_6.6.0 [ 1.312860] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.313073] FS-Cache: Loaded [ 1.313353] CacheFiles: Loaded [ 1.314055] AppArmor: AppArmor Filesystem Enabled [ 1.325246] thermal_sys: Registered thermal governor 'step_wise' [ 1.325622] NET: Registered protocol family 2 [ 1.326680] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) [ 1.326754] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) [ 1.326896] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 1.327106] TCP: Hash tables configured (established 8192 bind 8192) [ 1.327285] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.327360] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.327692] NET: Registered protocol family 1 [ 1.328405] RPC: Registered named UNIX socket transport module. [ 1.328437] RPC: Registered udp transport module. [ 1.328463] RPC: Registered tcp transport module. [ 1.328490] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.330501] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available [ 1.333901] Initialise system trusted keyrings [ 1.334155] workingset: timestamp_bits=14 max_order=18 bucket_order=4 [ 1.346130] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.346498] FS-Cache: Netfs 'nfs' registered for caching [ 1.347172] NFS: Registering the id_resolver key type [ 1.347251] Key type id_resolver registered [ 1.347278] Key type id_legacy registered [ 1.347314] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.348715] Key type asymmetric registered [ 1.348744] Asymmetric key parser 'x509' registered [ 1.348812] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.349055] io scheduler mq-deadline registered [ 1.349085] io scheduler kyber registered [ 1.352261] bcm2708_fb soc:fb: FB found 1 display(s) [ 1.363820] Console: switching to colour frame buffer device 82x26 [ 1.371643] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416 [ 1.379100] raspberrypi-clk firmware-clocks: CPU frequency range: min 600000000, max 1400000000 [ 1.387347] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled [ 1.392472] bcm2835-rng 3f104000.rng: hwrng registered [ 1.396255] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.403500] vc-sm: Videocore shared memory driver [ 1.407287] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 1.426924] brd: module loaded [ 1.442815] loop: module loaded [ 1.446795] zram: Added device: zram0 [ 1.450665] zram: Added device: zram1 [ 1.454486] zram: Added device: zram2 [ 1.458828] Loading iSCSI transport class v2.0-870. [ 1.462787] libphy: Fixed MDIO Bus: probed [ 1.465878] tun: Universal TUN/TAP device driver, 1.6 [ 1.469173] usbcore: registered new interface driver lan78xx [ 1.472201] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 1.675792] Core Release: 2.80a [ 1.678735] Setting default values for core params [ 1.681755] Finished setting default values for core params [ 1.885176] Using Buffer DMA mode [ 1.888146] Periodic Transfer Interrupt Enhancement - disabled [ 1.891146] Multiprocessor Interrupt Enhancement - disabled [ 1.894092] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 1.896990] Dedicated Tx FIFOs mode [ 1.900375] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = b7514000 dma = 0xf7514000 len=9024 [ 1.906031] FIQ FSM acceleration enabled for : [ 1.906031] Non-periodic Split Transactions [ 1.906031] Periodic Split Transactions [ 1.906031] High-Speed Isochronous Endpoints [ 1.906031] Interrupt/Control Split Transaction hack enabled [ 1.919097] dwc_otg: Microframe scheduler enabled [ 1.919172] WARN::hcd_init_fiq:457: FIQ on core 1 [ 1.921719] WARN::hcd_init_fiq:458: FIQ ASM at 80736330 length 36 [ 1.924310] WARN::hcd_init_fiq:496: MPHI regs_base at bb810000 [ 1.926922] dwc_otg 3f980000.usb: DWC OTG Controller [ 1.929637] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 1.932499] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 [ 1.935374] Init: Port Power? op_state=1 [ 1.938144] Init: Power Port (0) [ 1.941221] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 [ 1.946837] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.949797] usb usb1: Product: DWC OTG Controller [ 1.952725] usb usb1: Manufacturer: Linux 5.4.42-v7 dwc_otg_hcd [ 1.955696] usb usb1: SerialNumber: 3f980000.usb [ 1.959278] hub 1-0:1.0: USB hub found [ 1.962184] hub 1-0:1.0: 1 port detected [ 1.965753] dwc_otg: FIQ enabled [ 1.965763] dwc_otg: NAK holdoff enabled [ 1.965772] dwc_otg: FIQ split-transaction FSM enabled [ 1.965788] Module dwc_common_port init [ 1.966129] usbcore: registered new interface driver usb-storage [ 1.969096] usbcore: registered new interface driver usbserial_generic [ 1.971951] usbserial: USB Serial support registered for generic [ 1.974989] mousedev: PS/2 mouse device common for all mice [ 1.979094] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 1.983145] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 600000 KHz [ 1.987438] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 700000 KHz [ 1.994064] sdhci: Secure Digital Host Controller Interface driver [ 1.996160] sdhci: Copyright(c) Pierre Ossman [ 1.998496] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe [ 2.000566] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe [ 2.002023] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.004600] ledtrig-cpu: registered to indicate activity on CPUs [ 2.006053] hidraw: raw HID events driver (C) Jiri Kosina [ 2.007527] usbcore: registered new interface driver usbhid [ 2.008901] usbhid: USB HID core driver [ 2.010795] vchiq: vchiq_init_state: slot_zero = (ptrval)

[ 2.020288] [vc_sm_connected_init]: end - returning 0 [ 2.022692] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 2.024016] IPVS: Connection hash table configured (size=4096, memory=32Kbytes) [ 2.025723] IPVS: ipvs loaded. [ 2.027029] IPVS: [rr] scheduler registered. [ 2.029071] NET: Registered protocol family 10 [ 2.031040] Segment Routing with IPv6 [ 2.032402] NET: Registered protocol family 17 [ 2.033773] Bridge firewalling registered [ 2.035178] Key type dns_resolver registered [ 2.036899] Registering SWP/SWPB emulation handler [ 2.038398] registered taskstats version 1 [ 2.039651] Loading compiled-in X.509 certificates [ 2.041145] Key type ._fscrypt registered [ 2.042364] Key type .fscrypt registered [ 2.043606] AppArmor: AppArmor sha1 policy hashing enabled [ 2.050637] uart-pl011 3f201000.serial: cts_event_workaround enabled [ 2.051911] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 [ 2.055567] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 53, base_baud = 50000000) is a 16550 [ 2.058268] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 2.060211] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 2.061505] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated [ 2.088092] sdhost: log_buf @ (ptrval) (f7513000) [ 2.126528] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 2.130061] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.133501] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.137994] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 2.139768] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 2.143317] printk: console [netcon0] enabled [ 2.145119] netconsole: network logging started [ 2.146895] of_cfs_init [ 2.148741] of_cfs_init: OK [ 2.171479] Waiting for root device PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20... [ 2.184524] Indeed it is in host mode hprt0 = 00021501 [ 2.205792] random: fast init done [ 2.233067] mmc1: new high speed SDIO card at address 0001 [ 2.237900] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.245563] mmc0: new high speed SDHC card at address aaaa [ 2.248037] mmcblk0: mmc0:aaaa SC16G 14.8 GiB [ 2.256840] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 [ 2.290915] VFS: Mounted root (squashfs filesystem) readonly on device 179:5. [ 2.295718] devtmpfs: mounted [ 2.302337] Freeing unused kernel memory: 1024K [ 2.344815] Run /sbin/init as init process [ 2.394514] usb 1-1: new high-speed USB device number 2 using dwc_otg [ 2.397319] Indeed it is in host mode hprt0 = 00001101 [ 2.634848] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 [ 2.638680] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 2.641480] hub 1-1:1.0: USB hub found [ 2.643708] hub 1-1:1.0: 4 ports detected [ 2.659643] systemd[1]: System time before build time, advancing clock. [ 2.686928] systemd[1]: systemd 244 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS -ACL -XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid) [ 2.694159] systemd[1]: Detected architecture arm. [ 2.789463] systemd[1]: Set hostname to . [ 2.791878] systemd[1]: Installed transient /etc/machine-id file. [ 2.964493] usb 1-1.1: new high-speed USB device number 3 using dwc_otg [ 3.027934] systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. [ 3.066046] systemd[1]: /usr/lib/systemd/system/dbus.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/dbus/system_bus_socket → /run/dbus/system_bus_socket; please update the unit file accordingly. [ 3.094713] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 [ 3.097873] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.099910] hub 1-1.1:1.0: USB hub found [ 3.101581] hub 1-1.1:1.0: 3 ports detected [ 3.211211] random: systemd: uninitialized urandom read (16 bytes read) [ 3.216624] systemd[1]: Created slice system-getty.slice. [ 3.220331] random: systemd: uninitialized urandom read (16 bytes read) [ 3.222108] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 3.227235] random: systemd: uninitialized urandom read (16 bytes read) [ 3.228976] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.234408] systemd[1]: Reached target HassOS zram targets. [ 3.238309] systemd[1]: Reached target Paths. [ 3.241959] systemd[1]: Reached target Remote File Systems. [ 3.245581] systemd[1]: Reached target Slices. [ 3.253539] systemd[1]: Listening on Process Core Dump Socket. [ 3.257367] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 3.261326] systemd[1]: Listening on Journal Audit Socket. [ 3.265106] systemd[1]: Listening on Journal Socket (/dev/log). [ 3.268992] systemd[1]: Listening on Journal Socket. [ 3.272789] systemd[1]: Listening on udev Control Socket. [ 3.276489] systemd[1]: Listening on udev Kernel Socket. [ 3.280204] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 3.286941] systemd[1]: Mounting POSIX Message Queue File System... [ 3.294987] systemd[1]: Mounting Kernel Debug File System... [ 3.305018] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 3.317151] systemd[1]: Starting Journal Service... [ 3.325026] systemd[1]: Starting Remount Root and Kernel File Systems... [ 3.333634] systemd[1]: Starting Wait Until Kernel Time Synchronized... [ 3.342590] systemd[1]: Starting udev Coldplug all Devices... [ 3.350580] systemd[1]: Starting HassOS ZRAM swap... [ 3.359347] systemd[1]: Starting HassOS ZRAM tmp... [ 3.367907] systemd[1]: Starting HassOS ZRAM var... [ 3.376343] systemd[1]: Mounted POSIX Message Queue File System. [ 3.381155] systemd[1]: Mounted Kernel Debug File System. [ 3.389274] systemd[1]: Started Create list of static device nodes for the current kernel. [ 3.395618] systemd[1]: Started Journal Service. [ 3.428059] zram2: detected capacity change from 0 to 16777216 [ 3.430560] zram1: detected capacity change from 0 to 33554432 [ 3.435534] zram0: detected capacity change from 0 to 242167808 [ 3.455954] dwc_otg_handle_wakeup_detected_intr lxstate = 2 [ 3.702203] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 3.702206] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 3.703527] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 3.706453] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 3.708448] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.

[ 3.723712] [vc_sm_connected_init]: installed successfully [ 3.731875] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 3.742181] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.742183] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.742239] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.744089] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 3.746996] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 3.751312] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 3.760292] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 3.766918] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 3.772175] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 3.773691] bcm2835_audio bcm2835_audio: card created with 8 channels [ 3.776847] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 3.782042] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 3.782054] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 3.816139] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 3.825037] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 3.840673] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 3.846646] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 3.847186] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 3.852656] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 3.854806] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 3.862104] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 3.863884] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 4.050859] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 4.094554] usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg [ 4.118268] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 4.230789] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 [ 4.234222] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 4.242762] brcmfmac: F1 signature read @0x18000000=0x15264345 [ 4.260041] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 4.324514] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 4.331653] usbcore: registered new interface driver brcmfmac [ 4.366921] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2 [ 4.505663] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed [ 4.509681] libphy: lan78xx-mdiobus: probed [ 4.519593] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 [ 4.642482] random: crng init done [ 4.644002] random: 7 urandom warning(s) missed due to ratelimiting [ 4.652874] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 4.666318] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 [ 4.751021] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 4.754126] ext4 filesystem being mounted at /var supports timestamps until 2038 (0x7fffffff) [ 5.304465] Adding 236488k swap on /dev/zram0. Priority:-2 extents:1 across:236488k SSFS [ 5.329845] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 5.334529] ext4 filesystem being mounted at /tmp supports timestamps until 2038 (0x7fffffff) [ 5.544427] EXT4-fs (mmcblk0p7): recovery complete [ 5.690585] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null) [ 5.696790] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff) [ 6.693979] EXT4-fs (mmcblk0p8): recovery complete [ 6.706499] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null) [ 6.893122] systemd-journald[120]: Received client request to flush runtime journal. [ 6.913753] systemd-journald[120]: File /var/log/journal/e9cdbf9e88ef4debbe0446604f5239f0/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 7.462435] systemd-journald[120]: File /var/log/journal/e9cdbf9e88ef4debbe0446604f5239f0/user-1008.journal corrupted or uncleanly shut down, renaming and replacing. [ 7.678749] uart-pl011 3f201000.serial: no DMA platform data [ 8.576210] systemd-journald[120]: Failed to read journal file /var/log/journal/e9cdbf9e88ef4debbe0446604f5239f0/user-1000.journal for rotation, trying to move it out of the way: Device or resource busy [ 8.591741] Bluetooth: Core ver 2.22 [ 8.603192] NET: Registered protocol family 31 [ 8.604571] Bluetooth: HCI device and connection manager initialized [ 8.607028] Bluetooth: HCI socket layer initialized [ 8.608289] Bluetooth: L2CAP socket layer initialized [ 8.609542] Bluetooth: SCO socket layer initialized [ 8.639357] systemd-journald[120]: Failed to read journal file /var/log/journal/e9cdbf9e88ef4debbe0446604f5239f0/user-1002.journal for rotation, trying to move it out of the way: Device or resource busy [ 8.648410] Bluetooth: HCI UART driver ver 2.3 [ 8.649845] Bluetooth: HCI UART protocol H4 registered [ 8.651368] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 8.652933] Bluetooth: HCI UART protocol Broadcom registered [ 8.770229] audit: type=1400 audit(1591031556.309:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="094d7360_dnsmasqadvanced" pid=332 comm="apparmor_parser" [ 8.824886] audit: type=1400 audit(1591031556.359:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=334 comm="apparmor_parser" [ 8.829540] audit: type=1400 audit(1591031556.359:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=334 comm="apparmor_parser" [ 8.840587] audit: type=1400 audit(1591031556.359:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=334 comm="apparmor_parser" [ 8.845491] audit: type=1400 audit(1591031556.359:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=334 comm="apparmor_parser" [ 9.014639] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 9.016308] Bluetooth: BNEP filters: protocol multicast [ 9.017894] Bluetooth: BNEP socket layer initialized [ 9.056499] NET: Registered protocol family 38 [ 9.104586] cryptd: max_cpu_qlen set to 1000 [ 9.521276] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 9.573718] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [ 10.098081] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [ 16.136618] audit: type=1400 audit(1591031563.679:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=420 comm="apparmor_parser" [ 21.568992] audit: type=1325 audit(1591031569.109:5): table=nat family=2 entries=5 [ 21.599055] audit: type=1325 audit(1591031569.139:6): table=filter family=2 entries=4 [ 21.619621] audit: type=1325 audit(1591031569.159:7): table=filter family=2 entries=6 [ 21.660401] audit: type=1325 audit(1591031569.199:8): table=filter family=2 entries=8 [ 21.699704] audit: type=1325 audit(1591031569.239:9): table=filter family=2 entries=10 [ 21.721927] audit: type=1325 audit(1591031569.259:10): table=filter family=2 entries=11 [ 21.740233] audit: type=1325 audit(1591031569.279:11): table=filter family=2 entries=12 [ 21.766030] Initializing XFRM netlink socket [ 21.853765] audit: type=1325 audit(1591031569.389:12): table=nat family=2 entries=7 [ 21.878945] audit: type=1325 audit(1591031569.419:13): table=nat family=2 entries=8 [ 21.930741] audit: type=1325 audit(1591031569.469:14): table=filter family=2 entries=12 [ 31.371337] kauditd_printk_skb: 18 callbacks suppressed [ 31.371344] audit: type=1325 audit(1591031578.909:33): table=nat family=2 entries=13 [ 31.394660] audit: type=1325 audit(1591031578.939:34): table=nat family=2 entries=12 [ 31.419892] audit: type=1325 audit(1591031578.959:35): table=filter family=2 entries=25 [ 31.439404] audit: type=1325 audit(1591031578.979:36): table=filter family=2 entries=24 [ 31.473179] audit: type=1325 audit(1591031579.009:37): table=filter family=2 entries=23 [ 31.504827] audit: type=1325 audit(1591031579.049:38): table=filter family=2 entries=22 [ 31.527444] audit: type=1325 audit(1591031579.069:39): table=filter family=2 entries=21 [ 31.545274] audit: type=1325 audit(1591031579.089:40): table=filter family=2 entries=20 [ 31.592199] audit: type=1325 audit(1591031579.129:41): table=nat family=2 entries=11 [ 31.610305] audit: type=1325 audit(1591031579.149:42): table=nat family=2 entries=12 [ 35.640125] docker0: port 1(veth8202c25) entered blocking state [ 35.642398] docker0: port 1(veth8202c25) entered disabled state [ 35.649835] device veth8202c25 entered promiscuous mode [ 35.710169] hassio: port 1(vethcfd528f) entered blocking state [ 35.712004] hassio: port 1(vethcfd528f) entered disabled state [ 35.713908] device vethcfd528f entered promiscuous mode [ 35.725855] hassio: port 1(vethcfd528f) entered blocking state [ 35.727526] hassio: port 1(vethcfd528f) entered forwarding state [ 35.729286] hassio: port 1(vethcfd528f) entered disabled state [ 36.291791] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 36.894403] eth0: renamed from vethfa37bc6 [ 36.945683] IPv6: ADDRCONF(NETDEV_CHANGE): veth8202c25: link becomes ready [ 36.947297] docker0: port 1(veth8202c25) entered blocking state [ 36.948729] docker0: port 1(veth8202c25) entered forwarding state [ 36.950251] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 37.176765] kauditd_printk_skb: 13 callbacks suppressed [ 37.176771] audit: type=1325 audit(1591031584.719:56): table=nat family=2 entries=0 [ 37.198353] audit: type=1325 audit(1591031584.739:57): table=filter family=2 entries=0 [ 37.249003] audit: type=1325 audit(1591031584.789:58): table=nat family=2 entries=5 [ 37.266754] audit: type=1325 audit(1591031584.809:59): table=nat family=2 entries=7 [ 37.286517] audit: type=1325 audit(1591031584.829:60): table=nat family=2 entries=8 [ 37.297929] audit: type=1325 audit(1591031584.839:61): table=nat family=2 entries=10 [ 37.313960] audit: type=1325 audit(1591031584.849:62): table=nat family=2 entries=11 [ 37.326092] audit: type=1325 audit(1591031584.869:63): table=nat family=2 entries=12 [ 37.340373] audit: type=1325 audit(1591031584.879:64): table=nat family=2 entries=13 [ 37.360268] audit: type=1325 audit(1591031584.899:65): table=nat family=2 entries=14 [ 37.405781] eth1: renamed from veth8e4b0cf [ 37.445593] IPv6: ADDRCONF(NETDEV_CHANGE): vethcfd528f: link becomes ready [ 37.447846] hassio: port 1(vethcfd528f) entered blocking state [ 37.449978] hassio: port 1(vethcfd528f) entered forwarding state [ 37.452210] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 38.485231] udevd[188]: starting version 3.2.9 [ 38.513797] udevd[189]: starting eudev-3.2.9 [ 46.092588] audit: type=1400 audit(1591031731.093:66): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="094d7360_dnsmasqadvanced" pid=1181 comm="apparmor_parser" [ 46.112264] audit: type=1400 audit(1591031731.113:67): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1184 comm="apparmor_parser" [ 46.118070] audit: type=1400 audit(1591031731.113:67): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1184 comm="apparmor_parser" [ 46.123130] audit: type=1400 audit(1591031731.113:67): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1184 comm="apparmor_parser" [ 46.128472] audit: type=1400 audit(1591031731.113:67): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/socat" pid=1184 comm="apparmor_parser" [ 47.602267] hassio: port 2(vetha2171b5) entered blocking state [ 47.604192] hassio: port 2(vetha2171b5) entered disabled state [ 47.607769] device vetha2171b5 entered promiscuous mode [ 47.609686] audit: type=1700 audit(1591031732.603:68): dev=vetha2171b5 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 47.696088] hassio: port 3(vethf578463) entered blocking state [ 47.697865] hassio: port 3(vethf578463) entered disabled state [ 47.699989] device vethf578463 entered promiscuous mode [ 47.701955] audit: type=1700 audit(1591031732.703:69): dev=vethf578463 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 47.702610] hassio: port 3(vethf578463) entered blocking state [ 47.707510] hassio: port 3(vethf578463) entered forwarding state [ 47.766151] hassio: port 4(vethc4f2c48) entered blocking state [ 47.767874] hassio: port 4(vethc4f2c48) entered disabled state [ 47.770163] device vethc4f2c48 entered promiscuous mode [ 47.771845] audit: type=1700 audit(1591031732.773:70): dev=vethc4f2c48 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 47.772329] hassio: port 4(vethc4f2c48) entered blocking state [ 47.776597] hassio: port 4(vethc4f2c48) entered forwarding state [ 48.634783] hassio: port 3(vethf578463) entered disabled state [ 48.637521] hassio: port 4(vethc4f2c48) entered disabled state [ 48.756424] audit: type=1325 audit(1591031733.763:71): table=nat family=2 entries=0 [ 48.808300] audit: type=1325 audit(1591031733.813:72): table=filter family=2 entries=0 [ 49.015390] eth0: renamed from veth0245677 [ 49.097722] IPv6: ADDRCONF(NETDEV_CHANGE): vetha2171b5: link becomes ready [ 49.099532] hassio: port 2(vetha2171b5) entered blocking state [ 49.101191] hassio: port 2(vetha2171b5) entered forwarding state [ 49.237437] eth0: renamed from vethd7326d7 [ 49.297960] IPv6: ADDRCONF(NETDEV_CHANGE): vethf578463: link becomes ready [ 49.300361] hassio: port 3(vethf578463) entered blocking state [ 49.302581] hassio: port 3(vethf578463) entered forwarding state [ 49.396879] eth0: renamed from veth27352a9 [ 49.426357] IPv6: ADDRCONF(NETDEV_CHANGE): vethc4f2c48: link becomes ready [ 49.428642] hassio: port 4(vethc4f2c48) entered blocking state [ 49.430675] hassio: port 4(vethc4f2c48) entered forwarding state [ 51.273405] udevd[322]: starting version 3.2.9 [ 51.483787] udevd[323]: starting eudev-3.2.9 [ 69.396313] udevd[181]: starting version 3.2.9 [ 69.436762] udevd[182]: starting eudev-3.2.9 [ 145.005789] hassio: port 5(vethbdedf9f) entered blocking state [ 145.007195] hassio: port 5(vethbdedf9f) entered disabled state [ 145.010246] device vethbdedf9f entered promiscuous mode [ 145.011745] kauditd_printk_skb: 28 callbacks suppressed [ 145.011756] audit: type=1700 audit(1591031830.013:101): dev=vethbdedf9f prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 145.877402] audit: type=1325 audit(1591031830.883:102): table=nat family=2 entries=0 [ 145.911600] audit: type=1325 audit(1591031830.913:103): table=filter family=2 entries=0 [ 145.940293] audit: type=1325 audit(1591031830.943:104): table=nat family=2 entries=5 [ 145.950051] audit: type=1325 audit(1591031830.953:105): table=nat family=2 entries=7 [ 145.971136] audit: type=1325 audit(1591031830.973:106): table=nat family=2 entries=8 [ 145.983545] audit: type=1325 audit(1591031830.983:107): table=nat family=2 entries=10 [ 146.001583] audit: type=1325 audit(1591031831.003:108): table=nat family=2 entries=11 [ 146.017326] audit: type=1325 audit(1591031831.023:109): table=nat family=2 entries=12 [ 146.030556] audit: type=1325 audit(1591031831.033:110): table=nat family=2 entries=13 [ 146.106475] eth0: renamed from vethda254c9 [ 146.182684] IPv6: ADDRCONF(NETDEV_CHANGE): vethbdedf9f: link becomes ready [ 146.185120] hassio: port 5(vethbdedf9f) entered blocking state [ 146.187339] hassio: port 5(vethbdedf9f) entered forwarding state [ 355.619498] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled

Description of problem: Since the update in 4.8, impossible to operate the Pizygate mode

2020-06-01 19:16:54 ERROR (MainThread) [zigpy.application] Couldn't start application 2020-06-01 19:16:54 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry pizigate:/dev/ttyAMA0 for zha Traceback (most recent call last): File "/usr/local/lib/python3.7/site-packages/zigpy_zigate/api.py", line 95, in command timeout=COMMAND_TIMEOUT File "/usr/local/lib/python3.7/asyncio/tasks.py", line 449, in wait_for raise futures.TimeoutError() concurrent.futures._base.TimeoutError

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/config_entries.py", line 217, in async_setup hass, self File "/usr/src/homeassistant/homeassistant/components/zha/init.py", line 100, in async_setup_entry await zha_gateway.async_initialize() File "/usr/src/homeassistant/homeassistant/components/zha/core/gateway.py", line 141, in async_initialize app_config, auto_form=True, start_radio=True File "/usr/local/lib/python3.7/site-packages/zigpy/application.py", line 65, in new await app.startup(auto_form) File "/usr/local/lib/python3.7/site-packages/zigpy_zigate/zigbee/application.py", line 37, in startup await self._api.set_raw_mode() File "/usr/local/lib/python3.7/site-packages/zigpy_zigate/api.py", line 120, in set_raw_mode await self.command(0x0002, data) File "/usr/local/lib/python3.7/site-packages/zigpy_zigate/api.py", line 99, in command raise NoResponseError zigpy_zigate.api.NoResponseError

With PiZigate_test :

mkdir /boot mount /dev/mmcblk0p1 /boot

RPI Model searching ... Warning !!! you must modify /boot/config.txt Verifying /boot/cmdline.txt ... /boot/cmdline.txt seems to be OK Verifying /boot/config.txt ... /boot/config.txt seems to be OK Warning !!! if it's not the case, you have to execute the following commands : sudo systemctl disable hciuart sudo usermod -aG gpio pi then reboot the PI Searching /dev/ttyAMA0... Port : /dev/ttyAMA0 is not used Verif GPIOs ... GPIO 0 (RESET) --> OK GPIO 2 (FLASH) --> OK Config GPIOs ... GPIO 0 (RESET) --> OK GPIO 2 (FLASH) --> OK Opening : /dev/ttyAMA0 ... /dev/ttyAMA0 opened --> OK Packet 01 02 10 10 02 10 02 10 10 03 sent --> OK No packet received - size sent : 0 - error : 0

My config

PiZigate (GPIO Card) https://zigate.fr/produit/pizigate-v1-0/ In config.txt , i've added dtoverlay=pi3-disable-bt enable_uart=1 Works great with the previous version HassOS 3.13

cyr-ius commented 4 years ago

If you've a pizigate add in config.txt , dtoverlay = disable-bt