Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

multiple feedings in a single day failing #64

Open
phrend opened this issue Jun 13, 2021 · 3 comments
Open

multiple feedings in a single day failing #64

phrend opened this issue Jun 13, 2021 · 3 comments

Comments

@phrend
Copy link

phrend commented Jun 13, 2021

I'll start by giving you a big THANK YOU for breathing new life into my feeder!
I successfully got everything set up about 3 months ago, and at the time, had only configured 1 feeding per day. It was working, but I ran into trouble when I tried to modify the feeding time by an hour - my feeder was still checking in regularly, but it was feeding at the, "old" time. I got around this by restarting the container, and switching the feeder off, then on via the switch on the back. Not a big deal, but unexpected - and I'm not sure if that behavior is related to my current issue, or not?

My current issue is that I added 2 additional feeding times (breakfast @9am and dinner @8pm), to see if I could rely on the feeder when I'm away from home overnight, but the new feeding times are not actually occurring (the original lunch feeding @1pm is still working).

So, my question is - are multiple feedings per day currently supported? If so, how can I help debug what's going wrong?

I do see this error in the logs, that occurs at 8pm, which is the new dinner feeding time - every night:

2021-06-12 20:00:40,825   ERROR    feeder.util.mqtt.client: Failed to decode message: bytearray(b'{"_|deviceHid":"cf4b00fd99b69286da9737c7bf4cbfba93b42e06","s|msg_type":"trace","i|tl":64,"s|ts":"Results - Actual: 536932240 tbsp(1g), End: 334565, \xf0\x08\xf8O\xf4\x80P\x01hI\x1c\x01"}')
Traceback (most recent call last):
  File "/tmp/feeder/util/mqtt/client.py", line 102, in handle_message
    payload = json.loads(packet.payload.data)
  File "/usr/local/lib/python3.8/json/__init__.py", line 343, in loads
    s = s.decode(detect_encoding(s), 'surrogatepass')
UnicodeDecodeError: 'utf-8' codec can't decode byte 0xf0 in position 148: invalid continuation byte
2021-06-12 20:00:41,673   INFO     feeder.util.mqtt.client: Committing feed result data for cf4b00fd99b69286da9737c7bf4cbfba93b42e06
2021-06-12 20:03:40,677   INFO     uvicorn.access: 172.18.0.2:52824 - "GET /home HTTP/1.0" 404
2021-06-12 20:13:41,119   INFO     uvicorn.access: 172.18.0.2:53306 - "GET /home HTTP/1.0" 404
2021-06-12 20:23:41,591   INFO     uvicorn.access: 172.18.0.2:54880 - "GET /home HTTP/1.0" 404
2021-06-12 20:33:42,142   INFO     uvicorn.access: 172.18.0.2:55362 - "GET /home HTTP/1.0" 404
2021-06-12 20:40:49,149   INFO     uvicorn.access: 172.18.0.2:55700 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-12 20:40:53,336   INFO     uvicorn.access: 172.18.0.2:55706 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-12 20:40:57,127   INFO     uvicorn.access: 172.18.0.2:55712 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-12 20:40:59,778   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-12 20:40:59,778   INFO     hbmqtt.broker: Connection from 172.16.17.230:62583 on listener 'tcp-ssl-1'
2021-06-12 20:43:42,588   INFO     uvicorn.access: 172.18.0.2:55850 - "GET /home HTTP/1.0" 404
2021-06-12 20:53:42,986   INFO     uvicorn.access: 172.18.0.2:56350 - "GET /home HTTP/1.0" 404
2021-06-12 21:01:47,606   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-12 21:03:43,447   INFO     uvicorn.access: 172.18.0.2:56896 - "GET /home HTTP/1.0" 404
2021-06-12 21:13:43,952   INFO     uvicorn.access: 172.18.0.2:57378 - "GET /home HTTP/1.0" 404
2021-06-12 21:23:44,440   INFO     uvicorn.access: 172.18.0.2:57860 - "GET /home HTTP/1.0" 404
2021-06-12 21:28:57,717   INFO     uvicorn.access: 172.18.0.2:59232 - "GET / HTTP/1.0" 200
2021-06-12 21:28:58,205   INFO     uvicorn.access: 172.18.0.2:59244 - "GET / HTTP/1.0" 200
2021-06-12 21:28:58,217   INFO     uvicorn.access: 172.18.0.2:59250 - "GET /token HTTP/1.0" 404
2021-06-12 21:28:58,228   INFO     uvicorn.access: 172.18.0.2:59256 - "GET /user/ajax/menu-bar?ts=1623531600&_=748 HTTP/1.0" 404
2021-06-12 21:28:58,238   INFO     uvicorn.access: 172.18.0.2:59262 - "GET / HTTP/1.0" 200
2021-06-12 21:33:45,040   INFO     uvicorn.access: 172.18.0.2:59584 - "GET /home HTTP/1.0" 404
2021-06-12 21:43:45,513   INFO     uvicorn.access: 172.18.0.2:60066 - "GET /home HTTP/1.0" 404
2021-06-12 21:53:46,450   INFO     uvicorn.access: 172.18.0.2:60548 - "GET /home HTTP/1.0" 404
2021-06-12 22:03:46,854   INFO     uvicorn.access: 172.18.0.2:32850 - "GET /home HTTP/1.0" 404
2021-06-12 22:13:47,361   INFO     uvicorn.access: 172.18.0.2:33332 - "GET /home HTTP/1.0" 404
2021-06-12 22:23:47,886   INFO     uvicorn.access: 172.18.0.2:33814 - "GET /home HTTP/1.0" 404
2021-06-12 22:33:48,346   INFO     uvicorn.access: 172.18.0.2:35394 - "GET /home HTTP/1.0" 404
2021-06-12 22:41:45,583   INFO     uvicorn.access: 172.18.0.2:35780 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-12 22:41:49,362   INFO     uvicorn.access: 172.18.0.2:35786 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-12 22:41:53,155   INFO     uvicorn.access: 172.18.0.2:35792 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-12 22:41:55,622   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-12 22:41:55,623   INFO     hbmqtt.broker: Connection from 172.16.17.230:63999 on listener 'tcp-ssl-1'
2021-06-12 22:43:48,803   INFO     uvicorn.access: 172.18.0.2:35894 - "GET /home HTTP/1.0" 404
2021-06-12 22:53:49,176   INFO     uvicorn.access: 172.18.0.2:36372 - "GET /home HTTP/1.0" 404
2021-06-12 23:02:45,718   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-12 23:03:49,665   INFO     uvicorn.access: 172.18.0.2:36906 - "GET /home HTTP/1.0" 404
2021-06-12 23:09:49,647   INFO     uvicorn.access: 172.18.0.2:37304 - "GET / HTTP/1.0" 200
2021-06-12 23:09:50,156   INFO     uvicorn.access: 172.18.0.2:37316 - "GET / HTTP/1.0" 200
2021-06-12 23:09:50,168   INFO     uvicorn.access: 172.18.0.2:37322 - "GET /token HTTP/1.0" 404
2021-06-12 23:09:50,180   INFO     uvicorn.access: 172.18.0.2:37328 - "GET /user/ajax/menu-bar?ts=1623538800&_=796 HTTP/1.0" 404
2021-06-12 23:09:50,190   INFO     uvicorn.access: 172.18.0.2:37334 - "GET / HTTP/1.0" 200
2021-06-12 23:13:50,138   INFO     uvicorn.access: 172.18.0.2:37620 - "GET /home HTTP/1.0" 404
2021-06-12 23:23:50,598   INFO     uvicorn.access: 172.18.0.2:38102 - "GET /home HTTP/1.0" 404
2021-06-12 23:33:51,131   INFO     uvicorn.access: 172.18.0.2:38584 - "GET /home HTTP/1.0" 404
2021-06-12 23:43:51,548   INFO     uvicorn.access: 172.18.0.2:40164 - "GET /home HTTP/1.0" 404
2021-06-12 23:53:52,122   INFO     uvicorn.access: 172.18.0.2:40646 - "GET /home HTTP/1.0" 404
2021-06-13 00:03:52,628   INFO     uvicorn.access: 172.18.0.2:41174 - "GET /home HTTP/1.0" 404
2021-06-13 00:13:18,600   INFO     uvicorn.access: 172.18.0.2:42860 - "GET /?sub1=20210613-1013-189e-b427-2e8de6061fc2 HTTP/1.0" 200
2021-06-13 00:13:49,058   INFO     uvicorn.access: 172.18.0.2:42964 - "GET / HTTP/1.0" 200
2021-06-13 00:13:49,636   INFO     uvicorn.access: 172.18.0.2:42976 - "GET / HTTP/1.0" 200
2021-06-13 00:13:49,648   INFO     uvicorn.access: 172.18.0.2:42982 - "GET /token HTTP/1.0" 404
2021-06-13 00:13:49,660   INFO     uvicorn.access: 172.18.0.2:42988 - "GET /user/ajax/menu-bar?ts=1623542400&_=748 HTTP/1.0" 404
2021-06-13 00:13:49,672   INFO     uvicorn.access: 172.18.0.2:42994 - "GET / HTTP/1.0" 200
2021-06-13 00:14:27,042   INFO     uvicorn.access: 172.18.0.2:43152 - "GET /home HTTP/1.0" 404
2021-06-13 00:14:28,032   INFO     uvicorn.access: 172.18.0.2:43168 - "GET /home HTTP/1.0" 404
2021-06-13 00:14:30,314   INFO     uvicorn.access: 172.18.0.2:43190 - "GET /home HTTP/1.0" 404
2021-06-13 00:24:31,317   INFO     uvicorn.access: 172.18.0.2:43748 - "GET /home HTTP/1.0" 404
2021-06-13 00:34:31,792   INFO     uvicorn.access: 172.18.0.2:44238 - "GET /home HTTP/1.0" 404
2021-06-13 00:42:45,393   INFO     uvicorn.access: 172.18.0.2:44636 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 00:42:49,180   INFO     uvicorn.access: 172.18.0.2:44638 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 00:42:52,896   INFO     uvicorn.access: 172.18.0.2:44644 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 00:42:55,415   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 00:42:55,415   INFO     hbmqtt.broker: Connection from 172.16.17.230:56304 on listener 'tcp-ssl-1'
2021-06-13 00:44:32,217   INFO     uvicorn.access: 172.18.0.2:44734 - "GET /home HTTP/1.0" 404
2021-06-13 00:54:32,729   INFO     uvicorn.access: 172.18.0.2:45220 - "GET /home HTTP/1.0" 404
2021-06-13 01:03:39,734   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 01:04:33,184   INFO     uvicorn.access: 172.18.0.2:45738 - "GET /home HTTP/1.0" 404
2021-06-13 01:14:33,628   INFO     uvicorn.access: 172.18.0.2:47376 - "GET /home HTTP/1.0" 404
2021-06-13 01:24:34,095   INFO     uvicorn.access: 172.18.0.2:47866 - "GET /home HTTP/1.0" 404
2021-06-13 01:34:34,602   INFO     uvicorn.access: 172.18.0.2:48356 - "GET /home HTTP/1.0" 404
2021-06-13 01:44:35,301   INFO     uvicorn.access: 172.18.0.2:48858 - "GET /home HTTP/1.0" 404
2021-06-13 01:54:15,426   INFO     uvicorn.access: 172.18.0.2:49352 - "GET / HTTP/1.0" 200
2021-06-13 01:54:15,911   INFO     uvicorn.access: 172.18.0.2:49364 - "GET / HTTP/1.0" 200
2021-06-13 01:54:15,923   INFO     uvicorn.access: 172.18.0.2:49370 - "GET /token HTTP/1.0" 404
2021-06-13 01:54:15,935   INFO     uvicorn.access: 172.18.0.2:49376 - "GET /user/ajax/menu-bar?ts=1623546000&_=748 HTTP/1.0" 404
2021-06-13 01:54:15,947   INFO     uvicorn.access: 172.18.0.2:49382 - "GET / HTTP/1.0" 200
2021-06-13 01:54:35,734   INFO     uvicorn.access: 172.18.0.2:49450 - "GET /home HTTP/1.0" 404
2021-06-13 02:04:36,232   INFO     uvicorn.access: 172.18.0.2:50004 - "GET /home HTTP/1.0" 404
2021-06-13 02:14:36,811   INFO     uvicorn.access: 172.18.0.2:50546 - "GET /home HTTP/1.0" 404
2021-06-13 02:24:37,460   INFO     uvicorn.access: 172.18.0.2:52130 - "GET /home HTTP/1.0" 404
2021-06-13 02:34:38,675   INFO     uvicorn.access: 172.18.0.2:52632 - "GET /home HTTP/1.0" 404
2021-06-13 02:43:39,579   INFO     uvicorn.access: 172.18.0.2:53098 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 02:43:43,370   INFO     uvicorn.access: 172.18.0.2:53100 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 02:43:47,456   INFO     uvicorn.access: 172.18.0.2:53106 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 02:43:49,927   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 02:43:49,927   INFO     hbmqtt.broker: Connection from 172.16.17.230:50041 on listener 'tcp-ssl-1'
2021-06-13 02:44:39,177   INFO     uvicorn.access: 172.18.0.2:53156 - "GET /home HTTP/1.0" 404
2021-06-13 02:54:39,707   INFO     uvicorn.access: 172.18.0.2:53646 - "GET /home HTTP/1.0" 404
2021-06-13 03:04:40,137   INFO     uvicorn.access: 172.18.0.2:54136 - "GET /home HTTP/1.0" 404
2021-06-13 03:04:54,230   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 03:14:40,583   INFO     uvicorn.access: 172.18.0.2:54692 - "GET /home HTTP/1.0" 404
2021-06-13 03:24:41,040   INFO     uvicorn.access: 172.18.0.2:56280 - "GET /home HTTP/1.0" 404
2021-06-13 03:34:41,554   INFO     uvicorn.access: 172.18.0.2:56770 - "GET /home HTTP/1.0" 404
2021-06-13 03:35:10,026   INFO     uvicorn.access: 172.18.0.2:56820 - "GET / HTTP/1.0" 200
2021-06-13 03:35:10,563   INFO     uvicorn.access: 172.18.0.2:56832 - "GET / HTTP/1.0" 200
2021-06-13 03:35:10,576   INFO     uvicorn.access: 172.18.0.2:56838 - "GET /token HTTP/1.0" 404
2021-06-13 03:35:10,587   INFO     uvicorn.access: 172.18.0.2:56844 - "GET /user/ajax/menu-bar?ts=1623553200&_=748 HTTP/1.0" 404
2021-06-13 03:35:10,599   INFO     uvicorn.access: 172.18.0.2:56850 - "GET / HTTP/1.0" 200
2021-06-13 03:44:42,053   INFO     uvicorn.access: 172.18.0.2:57404 - "GET /home HTTP/1.0" 404
2021-06-13 03:54:42,508   INFO     uvicorn.access: 172.18.0.2:57886 - "GET /home HTTP/1.0" 404
2021-06-13 04:04:43,031   INFO     uvicorn.access: 172.18.0.2:58376 - "GET /home HTTP/1.0" 404
2021-06-13 04:14:43,522   INFO     uvicorn.access: 172.18.0.2:58916 - "GET /home HTTP/1.0" 404
2021-06-13 04:24:44,014   INFO     uvicorn.access: 172.18.0.2:59398 - "GET /home HTTP/1.0" 404
2021-06-13 04:34:44,569   INFO     uvicorn.access: 172.18.0.2:60978 - "GET /home HTTP/1.0" 404
2021-06-13 04:44:37,136   INFO     uvicorn.access: 172.18.0.2:33212 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 04:44:40,919   INFO     uvicorn.access: 172.18.0.2:33218 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 04:44:44,917   INFO     uvicorn.access: 172.18.0.2:33226 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 04:44:45,106   INFO     uvicorn.access: 172.18.0.2:33234 - "GET /home HTTP/1.0" 404
2021-06-13 04:44:47,394   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 04:44:47,394   INFO     hbmqtt.broker: Connection from 172.16.17.230:51283 on listener 'tcp-ssl-1'
2021-06-13 04:54:45,587   INFO     uvicorn.access: 172.18.0.2:33754 - "GET /home HTTP/1.0" 404
2021-06-13 05:04:45,995   INFO     uvicorn.access: 172.18.0.2:34256 - "GET /home HTTP/1.0" 404
2021-06-13 05:06:41,494   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 05:14:46,440   INFO     uvicorn.access: 172.18.0.2:34790 - "GET /home HTTP/1.0" 404
2021-06-13 05:15:41,391   INFO     uvicorn.access: 172.18.0.2:34860 - "GET / HTTP/1.0" 200
2021-06-13 05:15:41,844   INFO     uvicorn.access: 172.18.0.2:34872 - "GET / HTTP/1.0" 200
2021-06-13 05:15:41,866   INFO     uvicorn.access: 172.18.0.2:34878 - "GET /token HTTP/1.0" 404
2021-06-13 05:15:41,880   INFO     uvicorn.access: 172.18.0.2:34884 - "GET /user/ajax/menu-bar?ts=1623560400&_=748 HTTP/1.0" 404
2021-06-13 05:15:41,892   INFO     uvicorn.access: 172.18.0.2:34890 - "GET / HTTP/1.0" 200
2021-06-13 05:24:46,861   INFO     uvicorn.access: 172.18.0.2:35416 - "GET /home HTTP/1.0" 404
2021-06-13 05:34:47,490   INFO     uvicorn.access: 172.18.0.2:36996 - "GET /home HTTP/1.0" 404
2021-06-13 05:44:47,982   INFO     uvicorn.access: 172.18.0.2:37478 - "GET /home HTTP/1.0" 404
2021-06-13 05:54:48,461   INFO     uvicorn.access: 172.18.0.2:37960 - "GET /home HTTP/1.0" 404
2021-06-13 06:04:48,916   INFO     uvicorn.access: 172.18.0.2:38442 - "GET /home HTTP/1.0" 404
2021-06-13 06:14:49,342   INFO     uvicorn.access: 172.18.0.2:38976 - "GET /home HTTP/1.0" 404
2021-06-13 06:24:49,744   INFO     uvicorn.access: 172.18.0.2:39458 - "GET /home HTTP/1.0" 404
2021-06-13 06:34:50,318   INFO     uvicorn.access: 172.18.0.2:39940 - "GET /home HTTP/1.0" 404
2021-06-13 06:44:50,761   INFO     uvicorn.access: 172.18.0.2:41528 - "GET /home HTTP/1.0" 404
2021-06-13 06:45:36,442   INFO     uvicorn.access: 172.18.0.2:41566 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 06:45:40,221   INFO     uvicorn.access: 172.18.0.2:41572 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 06:45:44,015   INFO     uvicorn.access: 172.18.0.2:41578 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 06:45:46,665   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 06:45:46,665   INFO     hbmqtt.broker: Connection from 172.16.17.230:53174 on listener 'tcp-ssl-1'
2021-06-13 06:54:51,515   INFO     uvicorn.access: 172.18.0.2:42016 - "GET /home HTTP/1.0" 404
2021-06-13 06:56:17,767   INFO     uvicorn.access: 172.18.0.2:42124 - "GET /user/ajax/menu-bar?ts=1623564000&_=748 HTTP/1.0" 404
2021-06-13 06:56:18,152   INFO     uvicorn.access: 172.18.0.2:42138 - "GET / HTTP/1.0" 200
2021-06-13 06:56:18,615   INFO     uvicorn.access: 172.18.0.2:42150 - "GET / HTTP/1.0" 200
2021-06-13 06:56:18,627   INFO     uvicorn.access: 172.18.0.2:42156 - "GET /token HTTP/1.0" 404
2021-06-13 06:56:18,641   INFO     uvicorn.access: 172.18.0.2:42162 - "GET /user/ajax/menu-bar?ts=1623564000&_=748 HTTP/1.0" 404
2021-06-13 06:56:18,657   INFO     uvicorn.access: 172.18.0.2:42168 - "GET / HTTP/1.0" 200
2021-06-13 07:04:51,919   INFO     uvicorn.access: 172.18.0.2:42670 - "GET /home HTTP/1.0" 404
2021-06-13 07:06:54,550   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 07:14:52,375   INFO     uvicorn.access: 172.18.0.2:43206 - "GET /home HTTP/1.0" 404
2021-06-13 07:24:52,952   INFO     uvicorn.access: 172.18.0.2:43688 - "GET /home HTTP/1.0" 404
2021-06-13 07:34:53,448   INFO     uvicorn.access: 172.18.0.2:44170 - "GET /home HTTP/1.0" 404
2021-06-13 07:44:53,934   INFO     uvicorn.access: 172.18.0.2:45750 - "GET /home HTTP/1.0" 404
2021-06-13 07:54:54,439   INFO     uvicorn.access: 172.18.0.2:46232 - "GET /home HTTP/1.0" 404
2021-06-13 08:04:54,889   INFO     uvicorn.access: 172.18.0.2:46710 - "GET /home HTTP/1.0" 404
2021-06-13 08:14:55,316   INFO     uvicorn.access: 172.18.0.2:47244 - "GET /home HTTP/1.0" 404
2021-06-13 08:24:55,838   INFO     uvicorn.access: 172.18.0.2:47726 - "GET /home HTTP/1.0" 404
2021-06-13 08:34:56,330   INFO     uvicorn.access: 172.18.0.2:48228 - "GET /home HTTP/1.0" 404
2021-06-13 08:36:51,148   INFO     uvicorn.access: 172.18.0.2:48350 - "GET / HTTP/1.0" 200
2021-06-13 08:36:51,621   INFO     uvicorn.access: 172.18.0.2:48362 - "GET / HTTP/1.0" 200
2021-06-13 08:36:51,644   INFO     uvicorn.access: 172.18.0.2:48368 - "GET /token HTTP/1.0" 404
2021-06-13 08:36:51,660   INFO     uvicorn.access: 172.18.0.2:48374 - "GET /user/ajax/menu-bar?ts=1623571200&_=748 HTTP/1.0" 404
2021-06-13 08:36:51,671   INFO     uvicorn.access: 172.18.0.2:48380 - "GET / HTTP/1.0" 200
2021-06-13 08:44:56,791   INFO     uvicorn.access: 172.18.0.2:48858 - "GET /home HTTP/1.0" 404
2021-06-13 08:46:31,747   INFO     uvicorn.access: 172.18.0.2:48936 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 08:46:35,528   INFO     uvicorn.access: 172.18.0.2:48938 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 08:46:39,322   INFO     uvicorn.access: 172.18.0.2:48944 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 08:46:43,100   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 08:46:43,100   INFO     hbmqtt.broker: Connection from 172.16.17.230:58196 on listener 'tcp-ssl-1'
2021-06-13 08:54:57,220   INFO     uvicorn.access: 172.18.0.2:50444 - "GET /home HTTP/1.0" 404
2021-06-13 09:04:57,664   INFO     uvicorn.access: 172.18.0.2:50926 - "GET /home HTTP/1.0" 404
2021-06-13 09:07:52,662   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 09:14:58,150   INFO     uvicorn.access: 172.18.0.2:51460 - "GET /home HTTP/1.0" 404
2021-06-13 09:24:58,507   INFO     uvicorn.access: 172.18.0.2:56786 - "GET /home HTTP/1.0" 404
2021-06-13 09:34:59,069   INFO     uvicorn.access: 172.18.0.2:59974 - "GET /home HTTP/1.0" 404
2021-06-13 09:44:59,530   INFO     uvicorn.access: 172.18.0.2:60452 - "GET /home HTTP/1.0" 404
2021-06-13 09:55:00,014   INFO     uvicorn.access: 172.18.0.2:33820 - "GET /home HTTP/1.0" 404
2021-06-13 10:05:00,443   INFO     uvicorn.access: 172.18.0.2:34302 - "GET /home HTTP/1.0" 404
2021-06-13 10:15:00,987   INFO     uvicorn.access: 172.18.0.2:34838 - "GET /home HTTP/1.0" 404
2021-06-13 10:17:25,182   INFO     uvicorn.access: 172.18.0.2:34980 - "GET / HTTP/1.0" 200
2021-06-13 10:17:25,621   INFO     uvicorn.access: 172.18.0.2:34992 - "GET / HTTP/1.0" 200
2021-06-13 10:17:25,636   INFO     uvicorn.access: 172.18.0.2:34998 - "GET /token HTTP/1.0" 404
2021-06-13 10:17:25,648   INFO     uvicorn.access: 172.18.0.2:35004 - "GET /user/ajax/menu-bar?ts=1623578400&_=796 HTTP/1.0" 404
2021-06-13 10:17:25,660   INFO     uvicorn.access: 172.18.0.2:35010 - "GET / HTTP/1.0" 200
2021-06-13 10:25:01,455   INFO     uvicorn.access: 172.18.0.2:35468 - "GET /home HTTP/1.0" 404
2021-06-13 10:35:02,000   INFO     uvicorn.access: 172.18.0.2:35950 - "GET /home HTTP/1.0" 404
2021-06-13 10:45:02,443   INFO     uvicorn.access: 172.18.0.2:36432 - "GET /home HTTP/1.0" 404
2021-06-13 10:47:31,977   INFO     uvicorn.access: 172.18.0.2:36550 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 10:47:35,754   INFO     uvicorn.access: 172.18.0.2:36556 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 10:47:39,444   INFO     uvicorn.access: 172.18.0.2:36558 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 10:47:41,995   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 10:47:41,995   INFO     hbmqtt.broker: Connection from 172.16.17.230:63212 on listener 'tcp-ssl-1'
2021-06-13 10:55:02,881   INFO     uvicorn.access: 172.18.0.2:36916 - "GET /home HTTP/1.0" 404
2021-06-13 11:05:03,345   INFO     uvicorn.access: 172.18.0.2:38496 - "GET /home HTTP/1.0" 404
2021-06-13 11:08:42,582   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 11:15:03,827   INFO     uvicorn.access: 172.18.0.2:39030 - "GET /home HTTP/1.0" 404
2021-06-13 11:25:04,255   INFO     uvicorn.access: 172.18.0.2:39512 - "GET /home HTTP/1.0" 404
2021-06-13 11:35:04,720   INFO     uvicorn.access: 172.18.0.2:40016 - "GET /home HTTP/1.0" 404
2021-06-13 11:45:05,134   INFO     uvicorn.access: 172.18.0.2:40498 - "GET /home HTTP/1.0" 404
2021-06-13 11:55:05,688   INFO     uvicorn.access: 172.18.0.2:40980 - "GET /home HTTP/1.0" 404
2021-06-13 11:58:19,057   INFO     uvicorn.access: 172.18.0.2:41162 - "GET / HTTP/1.0" 200
2021-06-13 11:58:19,510   INFO     uvicorn.access: 172.18.0.2:41174 - "GET / HTTP/1.0" 200
2021-06-13 11:58:19,531   INFO     uvicorn.access: 172.18.0.2:41180 - "GET /token HTTP/1.0" 404
2021-06-13 11:58:19,545   INFO     uvicorn.access: 172.18.0.2:41186 - "GET /user/ajax/menu-bar?ts=1623582000&_=748 HTTP/1.0" 404
2021-06-13 11:58:19,557   INFO     uvicorn.access: 172.18.0.2:41192 - "GET / HTTP/1.0" 200
2021-06-13 12:05:06,210   INFO     uvicorn.access: 172.18.0.2:42708 - "GET /home HTTP/1.0" 404
2021-06-13 12:15:06,615   INFO     uvicorn.access: 172.18.0.2:43256 - "GET /home HTTP/1.0" 404
2021-06-13 12:25:07,016   INFO     uvicorn.access: 172.18.0.2:43740 - "GET /home HTTP/1.0" 404
2021-06-13 12:35:07,506   INFO     uvicorn.access: 172.18.0.2:44222 - "GET /home HTTP/1.0" 404
2021-06-13 12:45:07,965   INFO     uvicorn.access: 172.18.0.2:44704 - "GET /home HTTP/1.0" 404
2021-06-13 12:48:26,567   INFO     uvicorn.access: 172.18.0.2:44862 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 12:48:30,346   INFO     uvicorn.access: 172.18.0.2:44868 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 12:48:51,036   INFO     uvicorn.access: 172.18.0.2:44886 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 12:48:52,535   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 12:48:52,536   INFO     hbmqtt.broker: Connection from 172.16.17.230:52174 on listener 'tcp-ssl-1'
2021-06-13 12:55:08,566   INFO     uvicorn.access: 172.18.0.2:45192 - "GET /home HTTP/1.0" 404
2021-06-13 13:05:09,017   INFO     uvicorn.access: 172.18.0.2:45674 - "GET /home HTTP/1.0" 404
2021-06-13 13:09:40,694   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 13:15:09,448   INFO     uvicorn.access: 172.18.0.2:47320 - "GET /home HTTP/1.0" 404
2021-06-13 13:25:09,903   INFO     uvicorn.access: 172.18.0.2:47802 - "GET /home HTTP/1.0" 404
2021-06-13 13:35:10,344   INFO     uvicorn.access: 172.18.0.2:48280 - "GET /home HTTP/1.0" 404
2021-06-13 13:38:53,934   INFO     uvicorn.access: 172.18.0.2:48486 - "GET / HTTP/1.0" 200
2021-06-13 13:38:54,414   INFO     uvicorn.access: 172.18.0.2:48498 - "GET / HTTP/1.0" 200
2021-06-13 13:38:54,435   INFO     uvicorn.access: 172.18.0.2:48504 - "GET /token HTTP/1.0" 404
2021-06-13 13:38:54,450   INFO     uvicorn.access: 172.18.0.2:48510 - "GET /user/ajax/menu-bar?ts=1623589200&_=796 HTTP/1.0" 404
2021-06-13 13:38:54,462   INFO     uvicorn.access: 172.18.0.2:48516 - "GET / HTTP/1.0" 200
2021-06-13 13:45:13,131   INFO     uvicorn.access: 172.18.0.2:48914 - "GET /home HTTP/1.0" 404
2021-06-13 13:55:13,659   INFO     uvicorn.access: 172.18.0.2:49396 - "GET /home HTTP/1.0" 404
2021-06-13 14:05:14,137   INFO     uvicorn.access: 172.18.0.2:49874 - "GET /home HTTP/1.0" 404
2021-06-13 14:15:14,583   INFO     uvicorn.access: 172.18.0.2:51506 - "GET /home HTTP/1.0" 404
2021-06-13 14:25:20,699   INFO     uvicorn.access: 172.18.0.2:51992 - "GET /home HTTP/1.0" 404
2021-06-13 14:35:21,262   INFO     uvicorn.access: 172.18.0.2:52474 - "GET /home HTTP/1.0" 404
2021-06-13 14:45:21,745   INFO     uvicorn.access: 172.18.0.2:52956 - "GET /home HTTP/1.0" 404
2021-06-13 14:49:26,793   INFO     uvicorn.access: 172.18.0.2:53162 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 14:49:30,573   INFO     uvicorn.access: 172.18.0.2:53168 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 14:49:34,386   INFO     uvicorn.access: 172.18.0.2:53174 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 14:49:36,913   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 14:49:36,913   INFO     hbmqtt.broker: Connection from 172.16.17.230:51621 on listener 'tcp-ssl-1'
2021-06-13 14:55:22,297   INFO     uvicorn.access: 172.18.0.2:53456 - "GET /home HTTP/1.0" 404
2021-06-13 15:05:22,758   INFO     uvicorn.access: 172.18.0.2:53938 - "GET /home HTTP/1.0" 404
2021-06-13 15:10:42,902   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 15:15:23,234   INFO     uvicorn.access: 172.18.0.2:54472 - "GET /home HTTP/1.0" 404
2021-06-13 15:19:48,810   INFO     uvicorn.access: 172.18.0.2:55808 - "GET / HTTP/1.0" 200
2021-06-13 15:19:49,749   INFO     uvicorn.access: 172.18.0.2:55820 - "GET / HTTP/1.0" 200
2021-06-13 15:19:49,761   INFO     uvicorn.access: 172.18.0.2:55826 - "GET /token HTTP/1.0" 404
2021-06-13 15:19:49,774   INFO     uvicorn.access: 172.18.0.2:55832 - "GET /user/ajax/menu-bar?ts=1623596400&_=796 HTTP/1.0" 404
2021-06-13 15:19:49,786   INFO     uvicorn.access: 172.18.0.2:55838 - "GET / HTTP/1.0" 200
2021-06-13 15:25:23,666   INFO     uvicorn.access: 172.18.0.2:56200 - "GET /home HTTP/1.0" 404
2021-06-13 15:35:24,188   INFO     uvicorn.access: 172.18.0.2:56678 - "GET /home HTTP/1.0" 404
2021-06-13 15:45:24,635   INFO     uvicorn.access: 172.18.0.2:57160 - "GET /home HTTP/1.0" 404
2021-06-13 15:55:25,145   INFO     uvicorn.access: 172.18.0.2:57642 - "GET /home HTTP/1.0" 404
2021-06-13 16:05:25,674   INFO     uvicorn.access: 172.18.0.2:58124 - "GET /home HTTP/1.0" 404
2021-06-13 16:15:26,124   INFO     uvicorn.access: 172.18.0.2:58678 - "GET /home HTTP/1.0" 404
2021-06-13 16:25:26,526   INFO     uvicorn.access: 172.18.0.2:60256 - "GET /home HTTP/1.0" 404
2021-06-13 16:35:27,046   INFO     uvicorn.access: 172.18.0.2:60738 - "GET /home HTTP/1.0" 404
2021-06-13 16:45:27,510   INFO     uvicorn.access: 172.18.0.2:32988 - "GET /home HTTP/1.0" 404
2021-06-13 16:50:22,202   INFO     uvicorn.access: 172.18.0.2:33222 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 16:50:25,937   INFO     uvicorn.access: 172.18.0.2:33224 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 16:50:29,777   INFO     uvicorn.access: 172.18.0.2:33230 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 16:50:32,327   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 16:50:32,327   INFO     hbmqtt.broker: Connection from 172.16.17.230:57429 on listener 'tcp-ssl-1'
2021-06-13 16:55:27,958   INFO     uvicorn.access: 172.18.0.2:33476 - "GET /home HTTP/1.0" 404
2021-06-13 17:00:26,849   INFO     uvicorn.access: 172.18.0.2:33738 - "GET / HTTP/1.0" 200
2021-06-13 17:00:27,321   INFO     uvicorn.access: 172.18.0.2:33750 - "GET / HTTP/1.0" 200
2021-06-13 17:00:27,336   INFO     uvicorn.access: 172.18.0.2:33756 - "GET /token HTTP/1.0" 404
2021-06-13 17:00:27,348   INFO     uvicorn.access: 172.18.0.2:33762 - "GET /user/ajax/menu-bar?ts=1623603600&_=748 HTTP/1.0" 404
2021-06-13 17:00:27,360   INFO     uvicorn.access: 172.18.0.2:33768 - "GET / HTTP/1.0" 200
2021-06-13 17:05:28,356   INFO     uvicorn.access: 172.18.0.2:34102 - "GET /home HTTP/1.0" 404
2021-06-13 17:11:57,398   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 17:15:28,790   INFO     uvicorn.access: 172.18.0.2:34584 - "GET /home HTTP/1.0" 404
2021-06-13 17:25:29,197   INFO     uvicorn.access: 172.18.0.2:35118 - "GET /home HTTP/1.0" 404
2021-06-13 17:35:29,610   INFO     uvicorn.access: 172.18.0.2:36698 - "GET /home HTTP/1.0" 404
2021-06-13 17:45:30,120   INFO     uvicorn.access: 172.18.0.2:37180 - "GET /home HTTP/1.0" 404
2021-06-13 17:55:30,711   INFO     uvicorn.access: 172.18.0.2:37662 - "GET /home HTTP/1.0" 404
2021-06-13 18:05:31,117   INFO     uvicorn.access: 172.18.0.2:38144 - "GET /home HTTP/1.0" 404
2021-06-13 18:15:31,596   INFO     uvicorn.access: 172.18.0.2:38626 - "GET /home HTTP/1.0" 404
2021-06-13 18:25:32,034   INFO     uvicorn.access: 172.18.0.2:39156 - "GET /home HTTP/1.0" 404
2021-06-13 18:35:32,436   INFO     uvicorn.access: 172.18.0.2:40750 - "GET /home HTTP/1.0" 404
2021-06-13 18:40:58,545   INFO     uvicorn.access: 172.18.0.2:41036 - "GET / HTTP/1.0" 200
2021-06-13 18:40:58,990   INFO     uvicorn.access: 172.18.0.2:41048 - "GET / HTTP/1.0" 200
2021-06-13 18:40:59,005   INFO     uvicorn.access: 172.18.0.2:41054 - "GET /token HTTP/1.0" 404
2021-06-13 18:40:59,017   INFO     uvicorn.access: 172.18.0.2:41060 - "GET /user/ajax/menu-bar?ts=1623607200&_=748 HTTP/1.0" 404
2021-06-13 18:40:59,029   INFO     uvicorn.access: 172.18.0.2:41066 - "GET / HTTP/1.0" 200
2021-06-13 18:45:32,867   INFO     uvicorn.access: 172.18.0.2:41382 - "GET /home HTTP/1.0" 404
2021-06-13 18:51:18,857   INFO     uvicorn.access: 172.18.0.2:41656 - "POST /api/v1/kronos/gateways HTTP/1.0" 200
2021-06-13 18:51:22,731   INFO     uvicorn.access: 172.18.0.2:41662 - "GET /api/v1/kronos/gateways/d04b3e0934f35ee0f523d5c8e1b734dc88ef7c4c/config HTTP/1.0" 200
2021-06-13 18:51:26,522   INFO     uvicorn.access: 172.18.0.2:41668 - "POST /api/v1/kronos/devices HTTP/1.0" 200
2021-06-13 18:51:28,763   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 2 connections acquired
2021-06-13 18:51:28,763   INFO     hbmqtt.broker: Connection from 172.16.17.230:54839 on listener 'tcp-ssl-1'
2021-06-13 18:55:33,383   INFO     uvicorn.access: 172.18.0.2:41870 - "GET /home HTTP/1.0" 404
2021-06-13 19:05:33,801   INFO     uvicorn.access: 172.18.0.2:42352 - "GET /home HTTP/1.0" 404
2021-06-13 19:12:39,126   INFO     hbmqtt.broker: Listener 'tcp-ssl-1': 1 connections acquired
2021-06-13 19:15:34,283   INFO     uvicorn.access: 172.18.0.2:42834 - "GET /home HTTP/1.0" 404
2021-06-13 19:25:34,794   INFO     uvicorn.access: 172.18.0.2:43316 - "GET /home HTTP/1.0" 404
2021-06-13 19:35:35,202   INFO     uvicorn.access: 172.18.0.2:43864 - "GET /home HTTP/1.0" 404
2021-06-13 19:45:35,753   INFO     uvicorn.access: 172.18.0.2:45438 - "GET /home HTTP/1.0" 404
2021-06-13 19:55:36,172   INFO     uvicorn.access: 172.18.0.2:45920 - "GET /home HTTP/1.0" 404
2021-06-13 20:00:28,252   ERROR    feeder.util.mqtt.client: Failed to decode message: bytearray(b'{"_|deviceHid":"cf4b00fd99b69286da9737c7bf4cbfba93b42e06","s|msg_type":"trace","i|tl":64,"s|ts":"Results - Actual: 536932240 tbsp(1g), End: 334565, \xf0\x08\xf8O\xf4\x80P\x01hI\x1c\x01"}')
Traceback (most recent call last):
  File "/tmp/feeder/util/mqtt/client.py", line 102, in handle_message
    payload = json.loads(packet.payload.data)
  File "/usr/local/lib/python3.8/json/__init__.py", line 343, in loads
    s = s.decode(detect_encoding(s), 'surrogatepass')
UnicodeDecodeError: 'utf-8' codec can't decode byte 0xf0 in position 148: invalid continuation byte

Thanks in advance, for any help you can provide!
@mbillow
Copy link
Member

mbillow commented Jun 15, 2021

First off, thanks for the gratitude, it is definitely appreciated!

So, this is actually the feeder trying to tell FeederNet that there was an error dispensing food. The problem is that there is a bug in their code that grabs the wrong bits from memory and returns gibberish at the end of the error message.

This means that FeederNet correctly sent the new schedule to the device and it is just failing to dispense for some reason. My suggestion is turning it off and on again using the switch on the back. If that doesn’t work, try removing a meal and re-adding it. This will force FeederNet to resend the right schedule.

@kruton
Copy link
Collaborator

kruton commented May 2, 2022

Is this still happening, @phrend ?

@phrend
Copy link
Author

phrend commented May 2, 2022

@kruton I'm sorry to say that I could not get my feeder to reliably respond to changes in the feeding schedule. With the right combination of reboots and luck, it would sometimes update, but since a change to my feeding schedule is almost always linked to me going away from home and needing to depend on the feeder to feed my pets, I decided to buy a new feeder from another manufacturer. (I kept my PetNet, with the idea that I might replace the controller with an ESP-32 or that this software might find better ways of dealing with the existing hardware/software limitations, but it's not currently in use.)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@kruton @phrend @mbillow