Json error in console after reflash and restore snapshot

Hass.io on my Pi started acting strangely, finally giving me HTTP errors when trying to restart homeassistant from the SSH console, reboot the host, or pretty much any hassio command. Powering it down and back up got me a dead system.

So I reflashed the SD card, and once I’d set up that first account I installed the SSH server and SCP’d the snapshots from another PC to the /backup directory so I could to a wipe and restore to get me up and running again. This brought me back to 0.76.1 (I think that the reflash started out as .82) and everything looked okay from the web interface and logging in through SSH.

But for fun I decided to check the configuration file the the console with “hassio homeassistant check” and I got the weird error: “Error decoding json invalid character ‘:’ after top-level value: 403: Forbidden” This happens with the “hassio homeassistant restart” command as well. So I can’t change configuration.yaml now…

Sorry about the huge about of insteon debug info, but I can’t change the logging level there due to this problem. Is it the sqlite error that’s killing me? Any ideas?

Thanks

(homeassistant.log follows)

2018-11-24 22:24:30 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=38 from 2018-09-14 01:17:15.309817)
2018-11-24 22:24:51 WARNING (SyncWorker_1) [pywemo.ssdp] Error fetching description at http://[fe80::d4e2:6588:1d90:ba8a]:6544/getDeviceDesc
2018-11-24 22:24:55 WARNING (MainThread) [homeassistant.setup] Setup of config is taking over 10 seconds.
2018-11-24 22:25:02 INFO (MainThread) [insteonplm] Connecting to PLM on /dev/ttyUSB0
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 400bf8 cat is 2
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 400bf8 subcat is 56
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 48b4f1 cat is 1
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 48b4f1 subcat is 0
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 3de908 cat is 1
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 3de908 subcat is 36
2018-11-24 22:25:02 INFO (MainThread) [insteonplm.linkedDevices] New override for 15fea3 cat is 1
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 15fea3 subcat is 0
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 45faa8 cat is 16
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 45faa8 subcat is 22
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 4168cb cat is 16
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 4168cb subcat is 22
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 372574 cat is 2
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.linkedDevices] New override for 372574 subcat is 56
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] Added new callback <function async_setup..async_plm_new_device at 0x6ff1ed68>
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B16’: X10 On / Off Device
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B15’: X10 On / Off Device
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B14’: X10 On / Off Device
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B13’: X10 On / Off Device
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B09’: X10 On / Off Device
2018-11-24 22:25:03 DEBUG (MainThread) [insteonplm.linkedDevices] New X10 Device ‘x10B01’: X10 On / Off Device
2018-11-24 22:25:03 INFO (MainThread) [insteonplm.plm] Connection established to PLM
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Received 2 bytes from PLM: b’0000’
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Loading saved device info.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Really Loading saved device info.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Saved device file loaded
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 400bf8
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 4168cb
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 3de908
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 321d21
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 3ea9aa
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 3ea7b8
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 45faa8
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 372574
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 15fea3
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 48b4f1
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 41578c
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 3b3d21
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 415a88
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] Found saved device with address 3049c5
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Found 14 saved devices
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.plm] Requesting PLM Info
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 400bf8 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘400bf8’: On/Off Outdoor Module (02:38)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 4168cb added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘4168cb’: Motion Sensor II (10:16)
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.states] Registering callbacks for DimmableSwitch device 3D.E9.08
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 3de908 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘3de908’: SwitchLinc 2-Wire Dimmer (01:24)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 321d21 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘321d21’: Leak Sensor (10:08)
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.states] Registering callbacks for DimmableSwitch device 3E.A9.AA
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 3ea9aa added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘3ea9aa’: LampLinc Dimmer (01:0e)
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.states] Registering callbacks for DimmableSwitch device 3E.A7.B8
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 3ea7b8 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘3ea7b8’: LampLinc Dimmer (01:0e)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 45faa8 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘45faa8’: Motion Sensor II (10:16)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 372574 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘372574’: On/Off Outdoor Module (02:38)
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.states] Registering callbacks for DimmableSwitch device 15.FE.A3
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 15fea3 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘15fea3’: LampLinc Dimmer (01:00)
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.states] Registering callbacks for DimmableSwitch device 48.B4.F1
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 48b4f1 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘48b4f1’: LampLinc Dimmer (01:00)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 41578c added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘41578c’: Door Sensor (10:11)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 3b3d21 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘3b3d21’: Door Sensor (10:11)
2018-11-24 22:25:04 INFO (MainThread) [insteonplm.linkedDevices] Device with id 415a88 added to device list from saved device data.
2018-11-24 22:25:04 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘415a88’: Door Sensor (10:11)
2018-11-24 22:25:05 INFO (MainThread) [insteonplm.linkedDevices] Device with id 3049c5 added to device list from saved device data.
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.linkedDevices] New INSTEON Device ‘3049c5’: I/O Linc (07:00)
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Starting: _load_all_link_database
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Starting: _get_first_all_link_record
2018-11-24 22:25:05 INFO (MainThread) [insteonplm.plm] Requesting ALL-Link Records
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Ending: _get_first_all_link_record
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Ending: _load_all_link_database
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0000’
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.messages] Buffer content: b’0000’
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.messages] Trimming leading buffer garbage
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.messages] Buffer content: b’00’
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.messages] Trimming leading buffer garbage
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 0
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Aquiring write lock
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x60, ‘address’: 00.00.00, ‘category’: 0xNone, ‘subcategory’: 0xNone, ‘firmware’: 0xNone, ‘acknak’: 0xNone}
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:05 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Received 9 bytes from PLM: b’026044851a03159e06’
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’026044851a03159e06’
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x60, ‘address’: 44.85.1A, ‘category’: 0x03, ‘subcategory’: 0x15, ‘firmware’: 0x9e, ‘acknak’: 0x06}
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026044851a03159e06
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x60, ‘address’: 44.85.1A, ‘category’: 0x03, ‘subcategory’: 0x15, ‘firmware’: 0x9e, ‘acknak’: 0x06}
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] ACK or NAK received
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] {‘code’: 0x60, ‘address’: 44.85.1A, ‘category’: 0x03, ‘subcategory’: 0x15, ‘firmware’: 0x9e, ‘acknak’: 0x06}
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Starting _handle_get_plm_info
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Ending _handle_get_plm_info
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: motionSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: lightSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: batterySensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: wetLeakSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: motionSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: lightSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: batterySensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: doorSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: doorSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: doorSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.states] Registered callback for state: openClosedSensor
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x69, ‘acknak’: 0xNone}
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:06 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b’0269060257a201400bf8000000’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0269060257a201400bf8000000’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x69, ‘acknak’: 0x06}
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’0257a201400bf8000000’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026906
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’0257a201400bf8000000’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0257a201400bf8000000’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x57, ‘controlFlags’: 0xa2, ‘group’: 0x01, ‘address’: 40.0B.F8, ‘linkdata1’: 0x00, ‘linkdata2’: 0x00, ‘linkdata3’: 0x00}
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 0257a201400bf8000000
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x69, ‘acknak’: 0x06}
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x57, ‘controlFlags’: 0xa2, ‘group’: 0x01, ‘address’: 40.0B.F8, ‘linkdata1’: 0x00, ‘linkdata2’: 0x00, ‘linkdata3’: 0x00}
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.devices] Starting Device.receive_message
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.devices] Ending Device.receive_message
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] ACK or NAK received
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] {‘code’: 0x69, ‘acknak’: 0x06}
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Found all link record for device 400bf8
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_record
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:07 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_record
2018-11-24 22:25:08 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x6a, ‘acknak’: 0xNone}
2018-11-24 22:25:08 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:08 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b’026a060257e201400bf8023843’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’026a060257e201400bf8023843’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’0257e201400bf8023843’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026a06
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’0257e201400bf8023843’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0257e201400bf8023843’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x57, ‘controlFlags’: 0xe2, ‘group’: 0x01, ‘address’: 40.0B.F8, ‘linkdata1’: 0x02, ‘linkdata2’: 0x38, ‘linkdata3’: 0x43}
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 0257e201400bf8023843
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x57, ‘controlFlags’: 0xe2, ‘group’: 0x01, ‘address’: 40.0B.F8, ‘linkdata1’: 0x02, ‘linkdata2’: 0x38, ‘linkdata3’: 0x43}
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.devices] Starting Device.receive_message
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.devices] Ending Device.receive_message
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] ACK or NAK received
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Found all link record for device 400bf8
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_record
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:09 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_record
2018-11-24 22:25:09 ERROR (MainThread) [homeassistant.components.climate] radiotherm: Error on device update!
Traceback (most recent call last):
File “/usr/local/lib/python3.6/site-packages/homeassistant/helpers/entity_platform.py”, line 248, in _async_add_entity
await entity.async_device_update(warning=False)
File “/usr/local/lib/python3.6/site-packages/homeassistant/helpers/entity.py”, line 322, in async_device_update
yield from self.hass.async_add_job(self.update)
File “/usr/local/lib/python3.6/concurrent/futures/thread.py”, line 56, in run
result = self.fn(*self.args, **self.kwargs)
File “/usr/local/lib/python3.6/site-packages/homeassistant/components/climate/radiotherm.py”, line 237, in update
self._name = self.device.name[‘raw’]
AttributeError: ‘NoneType’ object has no attribute ‘name’
2018-11-24 22:25:10 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x6a, ‘acknak’: 0xNone}
2018-11-24 22:25:10 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:10 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b’026a060257e2014168cb101646’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’026a060257e2014168cb101646’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’0257e2014168cb101646’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026a06
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’0257e2014168cb101646’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0257e2014168cb101646’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x57, ‘controlFlags’: 0xe2, ‘group’: 0x01, ‘address’: 41.68.CB, ‘linkdata1’: 0x10, ‘linkdata2’: 0x16, ‘linkdata3’: 0x46}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 0257e2014168cb101646
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x57, ‘controlFlags’: 0xe2, ‘group’: 0x01, ‘address’: 41.68.CB, ‘linkdata1’: 0x10, ‘linkdata2’: 0x16, ‘linkdata3’: 0x46}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.devices] Starting Device.receive_message
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.devices] Ending Device.receive_message
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] ACK or NAK received
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Found all link record for device 4168cb
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_record
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_record
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x6a, ‘acknak’: 0xNone}
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:11 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b’026a060257a2014168cb101646’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’026a060257a2014168cb101646’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’0257a2014168cb101646’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026a06
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’0257a2014168cb101646’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0257a2014168cb101646’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x57, ‘controlFlags’: 0xa2, ‘group’: 0x01, ‘address’: 41.68.CB, ‘linkdata1’: 0x10, ‘linkdata2’: 0x16, ‘linkdata3’: 0x46}
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 0257a2014168cb101646
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x57, ‘controlFlags’: 0xa2, ‘group’: 0x01, ‘address’: 41.68.CB, ‘linkdata1’: 0x10, ‘linkdata2’: 0x16, ‘linkdata3’: 0x46}
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.devices] Starting Device.receive_message
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.devices] Ending Device.receive_message
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] ACK or NAK received
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Found all link record for device 4168cb
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_record
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-11-24 22:25:12 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_record
2018-11-24 22:25:13 DEBUG (MainThread) [insteonplm.plm] Writing message: {‘code’: 0x6a, ‘acknak’: 0xNone}
2018-11-24 22:25:13 DEBUG (MainThread) [insteonplm.plm] Transport is open
2018-11-24 22:25:13 DEBUG (MainThread) [insteonplm.plm] Waiting for ACK or NAK message
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b’026a060257a2013de908101646’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’026a060257a2013de908101646’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x6a, ‘acknak’: 0x06}
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’0257a2013de908101646’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 026a06
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’0257a2013de908101646’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Total buffer: b’0257a2013de908101646’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.messages] Returning msg: {‘code’: 0x57, ‘controlFlags’: 0xa2, ‘group’: 0x01, ‘address’: 3D.E9.08, ‘linkdata1’: 0x10, ‘linkdata2’: 0x16, ‘linkdata3’: 0x46}
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.messages] Returning buffer: b’’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Msg buffer: 0257a2013de908101646
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Post buffer: b’’
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Buffer too short to have a message
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-11-24 22:25:14 DEBUG (MainThread) [insteonplm.plm] Processing message {‘code’: 0x6a, ‘acknak’:

Oops, had to amputate most of the log for char limit. Here’s the error I wondered about:

2018-11-24 22:25:58 ERROR (Recorder) [homeassistant.components.recorder.util] Error executing query: (sqlite3.DatabaseError) database disk image is malformed [SQL: ‘INSERT INTO events (event_type, event_data, origin, time_fired, created, context_id, context_user_id) VALUES (?, ?, ?, ?, ?, ?, ?)’] [parameters: (‘service_registered’, ‘{“domain”: “recorder”, “service”: “purge”}’, ‘LOCAL’, ‘2018-11-25 04:24:27.176972’, ‘2018-11-25 04:25:58.611961’, ‘685fa6c46e8342daaeb28e61299db1c4’, None)] (Background on this error at: Error Messages — SQLAlchemy 2.0 Documentation)

Okay, nevermind, I guess. The problem disappeared sometime overnight without any effort on my part: I can now successfully check (hassio homeassistant check) the configuration for validity and restart homeassistant (hassio homeassistant restart) from SSH console without json errors. No idea what the problem was, but it went away by itself . . .