Help me clear these log error messages

bingobob · October 5, 2016, 6:50am

Sorry for copying and pasting it all but I’m not sure what’s significant… The one I really want to clear is the pysnmp (device tracker) one as that occurs the most frequently…

Everything is working pretty well but the device tracker error keeps appearing, any ideas why?

Device tracker is SNMP to a mikrotik router using the mib listed on the ha docs… works fine in the ui for presence detection by these errors keep coming…


16-10-04 17:40:26 homeassistant.components.recorder: Ended unfinished session (id=11 from 2016-10-04 16:33:37.785996)
16-10-04 17:40:26 homeassistant.components.recorder: Found unfinished sessions
16-10-04 18:32:00 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/home/pi/.homeassistant/deps/pysnmp/carrier/asyncore/dispatch.py", line 45, in runDispatcher
    use_poll=True, map=self.__sockMap, count=1)
  File "/usr/lib/python3.4/asyncore.py", line 212, in loop
    poll_fun(timeout, map)
  File "/usr/lib/python3.4/asyncore.py", line 193, in poll2
    readwrite(obj, flags)
  File "/usr/lib/python3.4/asyncore.py", line 123, in readwrite
    obj.handle_error()
  File "/usr/lib/python3.4/asyncore.py", line 108, in readwrite
    obj.handle_read_event()
  File "/usr/lib/python3.4/asyncore.py", line 442, in handle_read_event
    self.handle_read()
  File "/home/pi/.homeassistant/deps/pysnmp/carrier/asyncore/dgram/base.py", line 157, in handle_read
    self._cbFun(self, transportAddress, incomingMessage)
  File "/home/pi/.homeassistant/deps/pysnmp/carrier/base.py", line 68, in _cbFun
    self, transportDomain, transportAddress, incomingMessage
  File "/home/pi/.homeassistant/deps/pysnmp/entity/engine.py", line 145, in __receiveMessageCbFun
    self, transportDomain, transportAddress, wholeMsg
  File "/home/pi/.homeassistant/deps/pysnmp/proto/rfc3412.py", line 458, in receiveMessage
    cachedParams['cbCtx'])
  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 131, in processResponsePdu    cbFun(snmpEngine, origSendRequestHandle, None, PDU, cbCtx)
  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 268, in processResponseVarBinds
    varBindTable[-1], v2c.apiPDU.getVarBinds(reqPDU)
  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 31, in getNextVarBinds
    if v2c.ObjectIdentifier(origVarBinds[idx][0]).asTuple() >= varBinds[idx][0].asTuple():
IndexError: list index out of range

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 1162, in job_handler
    func(*args)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/device_tracker/__init__.py", line 425, in device_tracker_scan
    for mac in scanner.scan_devices():
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/device_tracker/snmp.py", line 65, in scan_devices
    self._update_info()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/util/__init__.py", line 296, in wrapper
    result = method(*args, **kwargs)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/device_tracker/snmp.py", line 86, in _update_info
    data = self.get_snmp_data()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/device_tracker/snmp.py", line 98, in get_snmp_data
    self.community, self.host, self.baseoid)
  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/oneliner/cmdgen.py", line 219, in nextCmd
    **kwargs):
  File "/home/pi/.homeassistant/deps/pysnmp/hlapi/asyncore/sync/cmdgen.py", line 347, in nextCmd
    snmpEngine.transportDispatcher.runDispatcher()
  File "/home/pi/.homeassistant/deps/pysnmp/carrier/asyncore/dispatch.py", line 49, in runDispatcher
    raise PySnmpError('poll error: %s' % ';'.join(format_exception(*exc_info())))
pysnmp.error.PySnmpError: poll error: Traceback (most recent call last):
;  File "/home/pi/.homeassistant/deps/pysnmp/carrier/asyncore/dispatch.py", line 45, in runDispatcher
    use_poll=True, map=self.__sockMap, count=1)
;  File "/usr/lib/python3.4/asyncore.py", line 212, in loop
    poll_fun(timeout, map)
;  File "/usr/lib/python3.4/asyncore.py", line 193, in poll2
    readwrite(obj, flags)
;  File "/usr/lib/python3.4/asyncore.py", line 123, in readwrite
    obj.handle_error()
;  File "/usr/lib/python3.4/asyncore.py", line 108, in readwrite
    obj.handle_read_event()
;  File "/usr/lib/python3.4/asyncore.py", line 442, in handle_read_event
    self.handle_read()
;  File "/home/pi/.homeassistant/deps/pysnmp/carrier/asyncore/dgram/base.py", line 157, in handle_read    self._cbFun(self, transportAddress, incomingMessage)
;  File "/home/pi/.homeassistant/deps/pysnmp/carrier/base.py", line 68, in _cbFun
    self, transportDomain, transportAddress, incomingMessage
;  File "/home/pi/.homeassistant/deps/pysnmp/entity/engine.py", line 145, in __receiveMessageCbFun
    self, transportDomain, transportAddress, wholeMsg
;  File "/home/pi/.homeassistant/deps/pysnmp/proto/rfc3412.py", line 458, in receiveMessage
    cachedParams['cbCtx'])
;  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 131, in processResponsePdu
    cbFun(snmpEngine, origSendRequestHandle, None, PDU, cbCtx)
;  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 268, in processResponseVarBinds
    varBindTable[-1], v2c.apiPDU.getVarBinds(reqPDU)
;  File "/home/pi/.homeassistant/deps/pysnmp/entity/rfc3413/cmdgen.py", line 31, in getNextVarBinds
    if v2c.ObjectIdentifier(origVarBinds[idx][0]).asTuple() >= varBinds[idx][0].asTuple():
;IndexError: list index out of range

16-10-04 19:57:55 homeassistant.components.media_player.kodi: Unable to fetch kodi data
16-10-04 20:01:10 homeassistant.components.media_player.kodi: Unable to fetch kodi data
16-10-04 22:00:10 homeassistant.components.sensor.rest: Error fetching data: <PreparedRequest [GET]>
16-10-04 22:00:10 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 1162, in job_handler
    func(*args)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 229, in _update_entity_states
    entity.update_ha_state(True)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 154, in update_ha_state
    self.update()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/pi_hole.py", line 101, in update
    self._state = json.loads(self.rest.data)
  File "/usr/lib/python3.4/json/__init__.py", line 312, in loads
    s.__class__.__name__))
TypeError: the JSON object must be str, not 'NoneType'
16-10-04 23:00:10 homeassistant.components.sensor.rest: Error fetching data: <PreparedRequest [GET]>
16-10-04 23:00:10 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 1162, in job_handler
    func(*args)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 229, in _update_entity_states
    entity.update_ha_state(True)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 154, in update_ha_state
    self.update()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/pi_hole.py", line 101, in update
    self._state = json.loads(self.rest.data)
  File "/usr/lib/python3.4/json/__init__.py", line 312, in loads
    s.__class__.__name__))
TypeError: the JSON object must be str, not 'NoneType'
16-10-04 23:11:10 homeassistant.components.sensor.rest: Error fetching data: <PreparedRequest [GET]>
16-10-04 23:11:10 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 1162, in job_handler
    func(*args)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 229, in _update_entity_states
    entity.update_ha_state(True)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 154, in update_ha_state
    self.update()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/pi_hole.py", line 101, in update
    self._state = json.loads(self.rest.data)
  File "/usr/lib/python3.4/json/__init__.py", line 312, in loads
    s.__class__.__name__))
TypeError: the JSON object must be str, not 'NoneType'
16-10-05 00:00:10 homeassistant.components.sensor.rest: Error fetching data: <PreparedRequest [GET]>
16-10-05 00:00:10 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 1162, in job_handler
    func(*args)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 229, in _update_entity_states
    entity.update_ha_state(True)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 154, in update_ha_state
    self.update()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/pi_hole.py", line 101, in update
    self._state = json.loads(self.rest.data)
  File "/usr/lib/python3.4/json/__init__.py", line 312, in loads
    s.__class__.__name__))
TypeError: the JSON object must be str, not 'NoneType'
16-10-05 03:22:11 pychromecast.socket_client: Heartbeat timeout, resetting connection
16-10-05 03:22:18 pychromecast.socket_client: Failed to connect, retrying in 5.000000s
Traceback (most recent call last):
  File "/home/pi/.homeassistant/deps/pychromecast/socket_client.py", line 233, in initialize_connection
    self.socket.connect((self.host, self.port))
  File "/usr/lib/python3.4/ssl.py", line 840, in connect
    self._real_connect(addr, False)
  File "/usr/lib/python3.4/ssl.py", line 827, in _real_connect
    socket.connect(self, addr)
ConnectionRefusedError: [Errno 111] Connection refused

jbardi · October 5, 2016, 6:51am

Did you just upgrade to 0.29.7?

I had to downgrade back to 0.29.6 as 0.29.7 actually created a whole slew of async errors rather than solving problems. After the downgrade everything is working perfectly again.

Looks like more work needs to be done to clean up the last bit of issues the devs are having from switching from threaded to asynchronous.

bingobob · October 5, 2016, 7:03am

Yes, I’m on the latest… But as I remember I’ve been seeing this stuff for a while not just with latest release…

rpitera · October 5, 2016, 2:25pm

Apparently the updater notifiction isn’t working again as I didn’t even know there was a new version until I saw it mentioned in another thread and then checked my custom update sensor. (Which is why I created one of my own BTW)

Guess I’ll be sitting this one out for a bit…