zha-device-handlers: [BUG] Tuya Outlet _TZ3000_cehuw1lw goes offline within 1min of pairing

Describe the bug The zigbee socket does not pair reliably, and goes unavailable within 1min of pairing. The problem is identical on two devices, both of which work fine on zigbee2mqtt.

To Reproduce Steps to reproduce the behavior:

  1. Power on a Tuya UK power monitoring socket, identifying as _TZ3000_cehuw1lw
  2. Click ‘add devices’ in Home assistant.
  3. Long press the button on the side of the _TZ3000_cehuw1lw to enter pairing mode
  4. Wait for the device to pair.

Expected behavior The device should join the network, the blue LED on the side should stop flashing, and all parameters such as on/off, voltage, current, power, cumulative energy should be reliably visible.

Screenshots If applicable, add screenshots to help explain your problem.

Device signature 
{
  "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
  "endpoints": {
    "1": {
      "profile_id": 260,
      "device_type": "0x010a",
      "in_clusters": [
        "0x0000",
        "0x0003",
        "0x0004",
        "0x0005",
        "0x0006",
        "0x0702",
        "0x0b04",
        "0xe000",
        "0xe001"
      ],
      "out_clusters": [
        "0x000a",
        "0x0019"
      ]
    }
  },
  "manufacturer": "_TZ3000_cehuw1lw",
  "model": "TS011F",
  "class": "zhaquirks.tuya.ts011f_plug.Plug"
}
Diagnostic information 
{
  "home_assistant": {
    "installation_type": "Home Assistant OS",
    "version": "2023.3.1",
    "dev": false,
    "hassio": true,
    "virtualenv": false,
    "python_version": "3.10.10",
    "docker": true,
    "arch": "aarch64",
    "timezone": "Europe/London",
    "os_name": "Linux",
    "os_version": "5.15.84-v8",
    "supervisor": "2023.01.1",
    "host_os": "Home Assistant OS 9.5",
    "docker_version": "20.10.22",
    "chassis": "embedded",
    "run_as_root": true
  },
  "custom_components": {
    "aurora_abb_powerone": {
      "version": "1.0.0",
      "requirements": [
        "aurorapy==0.2.7"
      ]
    }
  },
  "integration_manifest": {
    "domain": "zha",
    "name": "Zigbee Home Automation",
    "after_dependencies": [
      "onboarding",
      "usb"
    ],
    "codeowners": [
      "@dmulcahey",
      "@adminiuga",
      "@puddly"
    ],
    "config_flow": true,
    "dependencies": [
      "file_upload"
    ],
    "documentation": "https://www.home-assistant.io/integrations/zha",
    "iot_class": "local_polling",
    "loggers": [
      "aiosqlite",
      "bellows",
      "crccheck",
      "pure_pcapy3",
      "zhaquirks",
      "zigpy",
      "zigpy_deconz",
      "zigpy_xbee",
      "zigpy_zigate",
      "zigpy_znp"
    ],
    "requirements": [
      "bellows==0.34.9",
      "pyserial==3.5",
      "pyserial-asyncio==0.6",
      "zha-quirks==0.0.93",
      "zigpy-deconz==0.19.2",
      "zigpy==0.53.2",
      "zigpy-xbee==0.16.2",
      "zigpy-zigate==0.10.3",
      "zigpy-znp==0.9.3"
    ],
    "usb": [
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*2652*",
        "known_devices": [
          "slae.sh cc2652rb stick"
        ]
      },
      {
        "vid": "1A86",
        "pid": "55D4",
        "description": "*sonoff*plus*",
        "known_devices": [
          "sonoff zigbee dongle plus v2"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*sonoff*plus*",
        "known_devices": [
          "sonoff zigbee dongle plus"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*tubeszb*",
        "known_devices": [
          "TubesZB Coordinator"
        ]
      },
      {
        "vid": "1A86",
        "pid": "7523",
        "description": "*zigstar*",
        "known_devices": [
          "ZigStar Coordinators"
        ]
      },
      {
        "vid": "1CF1",
        "pid": "0030",
        "description": "*conbee*",
        "known_devices": [
          "Conbee II"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8A2A",
        "description": "*zigbee*",
        "known_devices": [
          "Nortek HUSBZB-1"
        ]
      },
      {
        "vid": "0403",
        "pid": "6015",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate+"
        ]
      },
      {
        "vid": "10C4",
        "pid": "EA60",
        "description": "*zigate*",
        "known_devices": [
          "ZiGate"
        ]
      },
      {
        "vid": "10C4",
        "pid": "8B34",
        "description": "*bv 2010/10*",
        "known_devices": [
          "Bitron Video AV2010/10"
        ]
      }
    ],
    "zeroconf": [
      {
        "type": "_esphomelib._tcp.local.",
        "name": "tube*"
      },
      {
        "type": "_zigate-zigbee-gateway._tcp.local.",
        "name": "*zigate*"
      },
      {
        "type": "_zigstar_gw._tcp.local.",
        "name": "*zigstar*"
      },
      {
        "type": "_slzb-06._tcp.local.",
        "name": "slzb-06*"
      }
    ],
    "is_built_in": true
  },
  "data": {
    "ieee": "**REDACTED**",
    "nwk": 21464,
    "manufacturer": "_TZ3000_cehuw1lw",
    "model": "TS011F",
    "name": "_TZ3000_cehuw1lw TS011F",
    "quirk_applied": true,
    "quirk_class": "zhaquirks.tuya.ts011f_plug.Plug",
    "manufacturer_code": 4417,
    "power_source": "Mains",
    "lqi": 192,
    "rssi": -52,
    "last_seen": "2023-03-07T20:55:05",
    "available": true,
    "device_type": "Router",
    "signature": {
      "node_descriptor": "NodeDescriptor(logical_type=<LogicalType.Router: 1>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress|RxOnWhenIdle|MainsPowered|FullFunctionDevice: 142>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=False, *is_full_function_device=True, *is_mains_powered=True, *is_receiver_on_when_idle=True, *is_router=True, *is_security_capable=False)",
      "endpoints": {
        "1": {
          "profile_id": 260,
          "device_type": "0x010a",
          "in_clusters": [
            "0x0000",
            "0x0003",
            "0x0004",
            "0x0005",
            "0x0006",
            "0x0702",
            "0x0b04",
            "0xe000",
            "0xe001"
          ],
          "out_clusters": [
            "0x000a",
            "0x0019"
          ]
        }
      }
    },
    "active_coordinator": false,
    "entities": [
      {
        "entity_id": "button.tz3000_cehuw1lw_ts011f_identify",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "select.tz3000_cehuw1lw_ts011f_power_on_state",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "select.tz3000_cehuw1lw_ts011f_backlight_mode",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "sensor.tz3000_cehuw1lw_ts011f_active_power",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "sensor.tz3000_cehuw1lw_ts011f_rms_current",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "sensor.tz3000_cehuw1lw_ts011f_rms_voltage",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "sensor.tz3000_cehuw1lw_ts011f_power_factor",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "sensor.tz3000_cehuw1lw_ts011f_summation_delivered",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "switch.tz3000_cehuw1lw_ts011f_switch",
        "name": "_TZ3000_cehuw1lw TS011F"
      },
      {
        "entity_id": "switch.tz3000_cehuw1lw_ts011f_child_lock",
        "name": "_TZ3000_cehuw1lw TS011F"
      }
    ],
    "neighbors": [],
    "routes": [],
    "endpoint_names": [
      {
        "name": "ON_OFF_PLUG_IN_UNIT"
      }
    ],
    "user_given_name": null,
    "device_reg_id": "1753fc07fedb9ab49d831d5677c27405",
    "area_id": "4ada9f86171311eba0e4fde906c13e65",
    "cluster_details": {
      "1": {
        "device_type": {
          "name": "ON_OFF_PLUG_IN_UNIT",
          "id": 266
        },
        "profile_id": 260,
        "in_clusters": {
          "0x0000": {
            "endpoint_attribute": "basic",
            "attributes": {
              "0x0000": {
                "attribute_name": "zcl_version",
                "value": 3
              },
              "0x0001": {
                "attribute_name": "app_version",
                "value": 77
              },
              "0x0004": {
                "attribute_name": "manufacturer",
                "value": "_TZ3000_cehuw1lw"
              },
              "0x0005": {
                "attribute_name": "model",
                "value": "TS011F"
              },
              "0x0007": {
                "attribute_name": "power_source",
                "value": 1
              },
              "0xfffe": {
                "attribute_name": "attr_reporting_status",
                "value": 0
              }
            },
            "unsupported_attributes": {}
          },
          "0x0003": {
            "endpoint_attribute": "identify",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0004": {
            "endpoint_attribute": "groups",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0005": {
            "endpoint_attribute": "scenes",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0006": {
            "endpoint_attribute": "on_off",
            "attributes": {
              "0x0000": {
                "attribute_name": "on_off",
                "value": 1
              },
              "0x8000": {
                "attribute_name": "child_lock",
                "value": 0
              },
              "0x8001": {
                "attribute_name": "backlight_mode",
                "value": 1
              },
              "0x8002": {
                "attribute_name": "power_on_state",
                "value": 0
              }
            },
            "unsupported_attributes": {
              "0x4003": {
                "attribute_name": "start_up_on_off"
              }
            }
          },
          "0x0702": {
            "endpoint_attribute": "smartenergy_metering",
            "attributes": {
              "0x0000": {
                "attribute_name": "current_summ_delivered",
                "value": 0
              },
              "0x0200": {
                "attribute_name": "status",
                "value": 0
              },
              "0x0300": {
                "attribute_name": "unit_of_measure",
                "value": 0
              },
              "0x0301": {
                "attribute_name": "multiplier",
                "value": 1
              },
              "0x0302": {
                "attribute_name": "divisor",
                "value": 100
              },
              "0x0303": {
                "attribute_name": "summation_formatting",
                "value": 0
              },
              "0x0306": {
                "attribute_name": "metering_device_type",
                "value": 0
              }
            },
            "unsupported_attributes": {
              "0x0400": {
                "attribute_name": "instantaneous_demand"
              },
              "0x0100": {
                "attribute_name": "current_tier1_summ_delivered"
              },
              "0x0102": {
                "attribute_name": "current_tier2_summ_delivered"
              },
              "0x0104": {
                "attribute_name": "current_tier3_summ_delivered"
              },
              "0x0304": {
                "attribute_name": "demand_formatting"
              },
              "0x0106": {
                "attribute_name": "current_tier4_summ_delivered"
              },
              "0x0108": {
                "attribute_name": "current_tier5_summ_delivered"
              },
              "0x010a": {
                "attribute_name": "current_tier6_summ_delivered"
              }
            }
          },
          "0x0b04": {
            "endpoint_attribute": "electrical_measurement",
            "attributes": {
              "0x0505": {
                "attribute_name": "rms_voltage",
                "value": 244
              },
              "0x0508": {
                "attribute_name": "rms_current",
                "value": 84
              },
              "0x050b": {
                "attribute_name": "active_power",
                "value": 13
              },
              "0x0602": {
                "attribute_name": "ac_current_multiplier",
                "value": 1
              },
              "0x0603": {
                "attribute_name": "ac_current_divisor",
                "value": 1000
              }
            },
            "unsupported_attributes": {
              "0x0300": {
                "attribute_name": "ac_frequency"
              },
              "0x0601": {
                "attribute_name": "ac_voltage_divisor"
              },
              "0x0302": {
                "attribute_name": "ac_frequency_max"
              },
              "0x0600": {
                "attribute_name": "ac_voltage_multiplier"
              },
              "0x0604": {
                "attribute_name": "ac_power_multiplier"
              },
              "0x0605": {
                "attribute_name": "ac_power_divisor"
              },
              "0x0401": {
                "attribute_name": "ac_frequency_divisor"
              },
              "0x0507": {
                "attribute_name": "rms_voltage_max"
              },
              "0x0400": {
                "attribute_name": "ac_frequency_multiplier"
              },
              "0x0000": {
                "attribute_name": "measurement_type"
              },
              "0x050a": {
                "attribute_name": "rms_current_max"
              },
              "0x0403": {
                "attribute_name": "power_divisor"
              },
              "0x050d": {
                "attribute_name": "active_power_max"
              },
              "0x050f": {
                "attribute_name": "apparent_power"
              },
              "0x0402": {
                "attribute_name": "power_multiplier"
              }
            }
          },
          "0xe000": {
            "endpoint_attribute": "tuya_is_pita_0",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0xe001": {
            "endpoint_attribute": "tuya_external_switch_type",
            "attributes": {},
            "unsupported_attributes": {}
          }
        },
        "out_clusters": {
          "0x000a": {
            "endpoint_attribute": "time",
            "attributes": {},
            "unsupported_attributes": {}
          },
          "0x0019": {
            "endpoint_attribute": "ota",
            "attributes": {},
            "unsupported_attributes": {}
          }
        }
      }
    }
  }
}
Additional logs 
2023-03-07 20:53:29.938 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): started configuration
2023-03-07 20:53:29.939 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:ZDO](TS011F): 'async_configure' stage succeeded
2023-03-07 20:53:29.943 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Performing cluster binding
2023-03-07 20:53:29.948 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Performing cluster binding
2023-03-07 20:53:29.950 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: Configuring cluster attribute reporting
2023-03-07 20:53:29.951 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: finished channel configuration
2023-03-07 20:53:29.951 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Performing cluster binding
2023-03-07 20:53:29.953 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: Configuring cluster attribute reporting
2023-03-07 20:53:29.954 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: finished channel configuration
2023-03-07 20:53:29.954 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: finished channel configuration
2023-03-07 20:53:29.964 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: True - changed: True
2023-03-07 20:53:29.964 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device availability changed and device became available, reinitializing channels
2023-03-07 20:53:29.970 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): started initialization
2023-03-07 20:53:29.971 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:ZDO](TS011F): 'async_initialize' stage succeeded
2023-03-07 20:53:29.980 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing channel: from_cache: False
2023-03-07 20:53:29.981 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing cached channel attributes: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:29.981 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:29.982 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2023-03-07 20:53:29.982 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['on_off']
2023-03-07 20:53:29.987 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing channel: from_cache: False
2023-03-07 20:53:29.988 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing cached channel attributes: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting', 'unit_of_measure']
2023-03-07 20:53:29.989 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting']
2023-03-07 20:53:29.990 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['unit_of_measure']
2023-03-07 20:53:29.990 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing uncached channel attributes: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status'] - from cache[False]
2023-03-07 20:53:29.990 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered']
2023-03-07 20:53:29.995 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: initializing channel: from_cache: False
2023-03-07 20:53:29.995 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: finished channel initialization
2023-03-07 20:53:29.996 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing channel: from_cache: False
2023-03-07 20:53:29.996 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing cached channel attributes: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor', 'ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor', 'power_multiplier']
2023-03-07 20:53:29.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor']
2023-03-07 20:53:29.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor']
2023-03-07 20:53:29.997 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['power_multiplier']
2023-03-07 20:53:29.998 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing uncached channel attributes: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max', 'rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max'] - from cache[False]
2023-03-07 20:53:29.998 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max']
2023-03-07 20:53:30.004 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: initializing channel: from_cache: False
2023-03-07 20:53:30.005 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: finished channel initialization
2023-03-07 20:53:30.005 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: initializing channel: from_cache: False
2023-03-07 20:53:30.005 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: finished channel initialization
2023-03-07 20:53:30.231 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: bound 'smartenergy_metering' cluster: Status.SUCCESS
2023-03-07 20:53:30.232 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Configuring cluster attribute reporting
2023-03-07 20:53:30.242 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: bound 'electrical_measurement' cluster: Status.SUCCESS
2023-03-07 20:53:30.243 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Configuring cluster attribute reporting
2023-03-07 20:53:30.270 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: finished channel initialization
2023-03-07 20:53:30.291 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:30.340 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
2023-03-07 20:53:30.341 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Configuring cluster attribute reporting
2023-03-07 20:53:30.349 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max']
2023-03-07 20:53:30.396 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Successfully configured reporting for '{'current_summ_delivered'}' on 'smartenergy_metering' cluster
2023-03-07 20:53:30.397 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Failed to configure reporting for '['instantaneous_demand', 'current_tier1_summ_delivered']' on 'smartenergy_metering' cluster: [ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=1024), ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=256)]
2023-03-07 20:53:30.450 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Successfully configured reporting for '{'active_power'}' on 'electrical_measurement' cluster
2023-03-07 20:53:30.451 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Failed to configure reporting for '['active_power_max', 'apparent_power']' on 'electrical_measurement' cluster: [ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=1293), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=1295)]
2023-03-07 20:53:30.481 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Performing channel specific initialization: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:30.481 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: finished channel initialization
2023-03-07 20:53:30.636 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Successfully configured reporting for '{'on_off': (0, 900, 1)}' on 'on_off' cluster: [ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>)]
2023-03-07 20:53:30.637 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: finished channel configuration
2023-03-07 20:53:30.675 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Successfully configured reporting for '{'rms_current', 'rms_voltage'}' on 'electrical_measurement' cluster
2023-03-07 20:53:30.675 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Failed to configure reporting for '['rms_current_max']' on 'electrical_measurement' cluster: [ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=1290), ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>)]
2023-03-07 20:53:30.696 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Successfully configured reporting for 'set()' on 'smartenergy_metering' cluster
2023-03-07 20:53:30.696 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Failed to configure reporting for '['current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered']' on 'smartenergy_metering' cluster: [ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=258), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=260), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=262)]
2023-03-07 20:53:30.719 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: finished channel initialization
2023-03-07 20:53:30.726 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.726 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.727 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.727 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.727 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.728 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: 'async_initialize' stage succeeded
2023-03-07 20:53:30.736 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): power source: Mains
2023-03-07 20:53:30.736 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): completed initialization
2023-03-07 20:53:30.783 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Successfully configured reporting for 'set()' on 'electrical_measurement' cluster
2023-03-07 20:53:30.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Failed to configure reporting for '['rms_voltage_max', 'ac_frequency', 'ac_frequency_max']' on 'electrical_measurement' cluster: [ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=1287), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=768), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=770)]
2023-03-07 20:53:30.784 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: finished channel configuration
2023-03-07 20:53:30.814 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Successfully configured reporting for '{'status'}' on 'smartenergy_metering' cluster
2023-03-07 20:53:30.815 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Failed to configure reporting for '['current_tier5_summ_delivered', 'current_tier6_summ_delivered']' on 'smartenergy_metering' cluster: [ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=264), ConfigureReportingResponseRecord(status=<Status.UNSUPPORTED_ATTRIBUTE: 134>, direction=<ReportingDirection.SendReports: 0>, attrid=266), ConfigureReportingResponseRecord(status=<Status.SUCCESS: 0>)]
2023-03-07 20:53:30.816 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: finished channel configuration
2023-03-07 20:53:30.818 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: 'async_configure' stage succeeded
2023-03-07 20:53:30.818 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: 'async_configure' stage succeeded
2023-03-07 20:53:30.819 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: 'async_configure' stage succeeded
2023-03-07 20:53:30.820 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: 'async_configure' stage succeeded
2023-03-07 20:53:30.820 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: 'async_configure' stage succeeded
2023-03-07 20:53:30.821 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: 'async_configure' stage succeeded
2023-03-07 20:53:30.824 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): completed configuration
2023-03-07 20:53:30.881 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: executed 'trigger_effect' command with args: '()' kwargs: '{'effect_id': <EffectIdentifier.Okay: 2>, 'effect_variant': <EffectVariant.Default: 0>}' result: Default_Response(command_id=64, status=<Status.SUCCESS: 0>)
2023-03-07 20:53:30.883 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: True - changed: True
2023-03-07 20:53:30.883 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device availability changed and device became available, reinitializing channels
2023-03-07 20:53:30.885 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): started initialization
2023-03-07 20:53:30.885 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:ZDO](TS011F): 'async_initialize' stage succeeded
2023-03-07 20:53:30.891 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing channel: from_cache: False
2023-03-07 20:53:30.892 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing cached channel attributes: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:30.892 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:30.893 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2023-03-07 20:53:30.893 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['on_off']
2023-03-07 20:53:30.899 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing channel: from_cache: False
2023-03-07 20:53:30.899 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing cached channel attributes: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting', 'unit_of_measure']
2023-03-07 20:53:30.900 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting']
2023-03-07 20:53:30.900 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['unit_of_measure']
2023-03-07 20:53:30.900 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing uncached channel attributes: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status'] - from cache[False]
2023-03-07 20:53:30.901 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered']
2023-03-07 20:53:30.906 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: initializing channel: from_cache: False
2023-03-07 20:53:30.907 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: finished channel initialization
2023-03-07 20:53:30.907 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing channel: from_cache: False
2023-03-07 20:53:30.908 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing cached channel attributes: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor', 'ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor', 'power_multiplier']
2023-03-07 20:53:30.908 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor']
2023-03-07 20:53:30.908 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor']
2023-03-07 20:53:30.909 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['power_multiplier']
2023-03-07 20:53:30.909 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing uncached channel attributes: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max', 'rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max'] - from cache[False]
2023-03-07 20:53:30.910 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max']
2023-03-07 20:53:30.914 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: initializing channel: from_cache: False
2023-03-07 20:53:30.914 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: finished channel initialization
2023-03-07 20:53:30.914 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: initializing channel: from_cache: False
2023-03-07 20:53:30.916 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: finished channel initialization
2023-03-07 20:53:30.982 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: finished channel initialization
2023-03-07 20:53:31.021 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:31.061 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max']
2023-03-07 20:53:31.110 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Performing channel specific initialization: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:31.110 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: finished channel initialization
2023-03-07 20:53:31.143 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: finished channel initialization
2023-03-07 20:53:31.146 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.146 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.147 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.147 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.147 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.147 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: 'async_initialize' stage succeeded
2023-03-07 20:53:31.155 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): power source: Mains
2023-03-07 20:53:31.156 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): completed initialization
2023-03-07 20:53:32.391 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE70D](TRADFRI remote control): Device seen - marking the device available and resetting counter
2023-03-07 20:53:32.391 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xE70D](TRADFRI remote control): Update device availability - device available: True - new availability: True - changed: False
2023-03-07 20:53:34.375 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5632](lumi.sensor_magnet): last_seen is 1367309.8589618206 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:53:34.376 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5632](lumi.sensor_magnet): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:38.358 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8711](TRADFRI control outlet): last_seen is 8982407.03862524 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:53:38.360 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x8711](TRADFRI control outlet): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:39.374 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAF5B](SP600): Device seen - marking the device available and resetting counter
2023-03-07 20:53:39.375 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAF5B](SP600): Update device availability - device available: True - new availability: True - changed: False
2023-03-07 20:53:47.350 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6978](TRADFRI bulb E27 CWS opal 600lm): last_seen is 7506.1869513988495 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:53:47.351 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x6978](TRADFRI bulb E27 CWS opal 600lm): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:51.198 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: True - new availability: False - changed: True
2023-03-07 20:53:51.199 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device availability changed and device became unavailable
2023-03-07 20:53:51.224 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:51.226 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:51.229 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:51.232 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:51.239 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:57.352 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: True - changed: True
2023-03-07 20:53:57.352 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device availability changed and device became available, reinitializing channels
2023-03-07 20:53:57.407 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): started initialization
2023-03-07 20:53:57.407 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:ZDO](TS011F): 'async_initialize' stage succeeded
2023-03-07 20:53:57.427 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x735E](lumi.sensor_magnet): last_seen is 773732.0939536095 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:53:57.431 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x735E](lumi.sensor_magnet): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:57.438 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAD48](TRADFRI bulb E27 opal 1000lm): Device seen - marking the device available and resetting counter
2023-03-07 20:53:57.438 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xAD48](TRADFRI bulb E27 opal 1000lm): Update device availability - device available: True - new availability: True - changed: False
2023-03-07 20:53:57.440 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x10F0](01MINIZB): Device seen - marking the device available and resetting counter
2023-03-07 20:53:57.441 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x10F0](01MINIZB): Update device availability - device available: True - new availability: True - changed: False
2023-03-07 20:53:57.442 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device seen - marking the device available and resetting counter
2023-03-07 20:53:57.443 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: True - new availability: True - changed: False
2023-03-07 20:53:57.452 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5BC5](TS0207): last_seen is 5359005.784373283 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:53:57.453 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x5BC5](TS0207): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:53:57.477 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing channel: from_cache: False
2023-03-07 20:53:57.478 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing cached channel attributes: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:57.478 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['start_up_on_off', 'backlight_mode', 'power_on_state', 'child_lock']
2023-03-07 20:53:57.479 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: initializing uncached channel attributes: ['on_off'] - from cache[False]
2023-03-07 20:53:57.479 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: Reading attributes in chunks: ['on_off']
2023-03-07 20:53:57.488 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing channel: from_cache: False
2023-03-07 20:53:57.489 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing cached channel attributes: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting', 'unit_of_measure']
2023-03-07 20:53:57.489 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['demand_formatting', 'divisor', 'metering_device_type', 'multiplier', 'summation_formatting']
2023-03-07 20:53:57.490 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['unit_of_measure']
2023-03-07 20:53:57.492 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: initializing uncached channel attributes: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status'] - from cache[False]
2023-03-07 20:53:57.493 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered']
2023-03-07 20:53:57.500 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: initializing channel: from_cache: False
2023-03-07 20:53:57.500 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: finished channel initialization
2023-03-07 20:53:57.501 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing channel: from_cache: False
2023-03-07 20:53:57.501 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing cached channel attributes: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor', 'ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor', 'power_multiplier']
2023-03-07 20:53:57.501 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_current_divisor', 'ac_current_multiplier', 'ac_power_divisor', 'ac_power_multiplier', 'ac_voltage_divisor']
2023-03-07 20:53:57.502 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['ac_voltage_multiplier', 'ac_frequency_divisor', 'ac_frequency_multiplier', 'measurement_type', 'power_divisor']
2023-03-07 20:53:57.502 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['power_multiplier']
2023-03-07 20:53:57.502 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: initializing uncached channel attributes: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max', 'rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max'] - from cache[False]
2023-03-07 20:53:57.503 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['active_power', 'active_power_max', 'apparent_power', 'rms_current', 'rms_current_max']
2023-03-07 20:53:57.509 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: initializing channel: from_cache: False
2023-03-07 20:53:57.510 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: finished channel initialization
2023-03-07 20:53:57.510 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: initializing channel: from_cache: False
2023-03-07 20:53:57.511 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: finished channel initialization
2023-03-07 20:53:57.521 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: async_force_update
2023-03-07 20:53:57.521 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['current_summ_delivered', 'status']
2023-03-07 20:53:57.541 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: async_update
2023-03-07 20:53:57.542 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['active_power', 'rms_current', 'rms_voltage']
2023-03-07 20:53:57.711 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: finished channel initialization
2023-03-07 20:53:57.773 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Reading attributes in chunks: ['current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:57.803 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: Reading attributes in chunks: ['rms_voltage', 'rms_voltage_max', 'ac_frequency', 'ac_frequency_max']
2023-03-07 20:53:57.929 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: Performing channel specific initialization: ['instantaneous_demand', 'current_summ_delivered', 'current_tier1_summ_delivered', 'current_tier2_summ_delivered', 'current_tier3_summ_delivered', 'current_tier4_summ_delivered', 'current_tier5_summ_delivered', 'current_tier6_summ_delivered', 'status']
2023-03-07 20:53:57.930 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: finished channel initialization
2023-03-07 20:53:57.961 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: finished channel initialization
2023-03-07 20:53:57.964 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0006]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.964 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0702]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.964 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0003]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.965 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0b04]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.965 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0000]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.965 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0xff81:1:0x0019]: 'async_initialize' stage succeeded
2023-03-07 20:53:57.968 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): power source: Mains
2023-03-07 20:53:57.969 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): completed initialization
2023-03-07 20:54:12.697 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBE76](lumi.sensor_magnet.aq2): last_seen is 1595891.2948069572 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:54:12.698 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xBE76](lumi.sensor_magnet.aq2): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:12.714 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x06F5](DS01): last_seen is 775141.2435820103 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:54:12.715 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x06F5](DS01): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:12.715 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCB44](DS01): last_seen is 5632549.28476882 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:54:12.715 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xCB44](DS01): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:13.375 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x584C](TRADFRI bulb E27 WW 806lm): last_seen is 93481.28976416588 seconds ago and ping attempts have been exhausted, marking the device unavailable
2023-03-07 20:54:13.376 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x584C](TRADFRI bulb E27 WW 806lm): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:17.163 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: True - new availability: False - changed: True
2023-03-07 20:54:17.163 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Device availability changed and device became unavailable
2023-03-07 20:54:17.184 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:17.186 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:17.193 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:17.199 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False
2023-03-07 20:54:17.253 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xff81](TS011F): Update device availability - device available: False - new availability: False - changed: False

Additional context After pairing (including while the final green box is showing), the entities go offline, and the device is no longer readable or controllable. The device sometimes comes back online for a short period, but then usually offline within 1 minute. If the device is power cycled it remains permanently unavailable to Home Assistant/ZHA.

The device works ok on zigbee2mqtt. It is observed that the blue led stops flashing at the end of pairing. With ZHA the blue led keeps flashing.

About this issue

  • Original URL
  • State: closed
  • Created a year ago
  • Reactions: 1
  • Comments: 25 (11 by maintainers)

Most upvoted comments

Witch is the bad version ? I like updating the wiki with recommended version and RCP we is always running the latest version then its running in the addon and cant running on lower ones.

I upgraded the SkyConnect from the firmware it shipped with (7.1.1.0 build 273) to the latest available beta (7.3.1.0)

+1
phil-lipp on Oct 20, 2023

My two devices that have this problem have the following IEEE addresses:

0xa4c13811********
0xa4c1382c********
+1
davet2001 on Apr 15, 2023
Read more comments on GitHub
← zha-device-handlers: [BUG] TS110E eventually reports being turned off, but it is still on
zha-device-handlers: [BUG] Tuya TS0041 TS0042 TS0043 - no battery →