Page 1 of 1

Import PYZM.ml.face_train as train - module not found

Posted: Wed Apr 07, 2021 11:25 pm
by mjalafoo
Hi there, I am lost with my trials to rebuild the system and end up with the same error.

I am trying to get Object Detection and Face Detection/Recognition to work, but I am unable to get the Hooks to send out result "0".

If I train to train the Face Detection using the following command:

Code: Select all

sudo -u www-data /var/lib/zmeventnotification/bin/zm_train_faces.py
I end up with an error as follows:

Code: Select all

INF [zm_train_faces] [Setting up signal handler for logs]
INF [zm_train_faces] [Switching global logger to ZMLog]
Traceback (most recent call last):
  File "/var/lib/zmeventnotification/bin/zm_train_faces.py", line 12, in <module>
    import pyzm.ml.face_train as train
ModuleNotFoundError: No module named 'pyzm.ml.face_train'
Anyone can help??

Re: Import PYZM.ml.face_train as train - module not found

Posted: Thu Apr 08, 2021 12:03 am
by asker
That's a bug. Please update to master of zmeventnotification
(commit fix: https://github.com/pliablepixels/zmeven ... 6d09d1d070)

Re: Import PYZM.ml.face_train as train - module not found

Posted: Thu Apr 08, 2021 8:23 pm
by mjalafoo
Thank you very much. I applied the fix to the ZM_TRAIN_FACES.PY and that fixed the problem, but now the process ends with a KILL as below:

Code: Select all

INF [zm_train_faces] [Setting up signal handler for logs]
INF [zm_train_faces] [Switching global logger to ZMLog]
INF [zm_train_faces] [Ignoring monitor specific settings, as you did not provide a monitor id]
Killed
Any thoughts?

Re: Import PYZM.ml.face_train as train - module not found

Posted: Sat Apr 10, 2021 10:09 pm
by mjalafoo
So I upgraded to the master of zmeventnotification following the guide in the ES FAQ. Then followed the troubleshooting guide to dig deeper, and enabled the logging as instructed.

As I looked in the debugging logs, I noticed the following:
  • The system is detecting objects but ignoring them with low confedence (being lower than 0.3) even though "use_sequence=yes" in the objectdetection.ini settings file
  • Many objects are detected with high confedence but are not identified with polygons for some reason and the system opts for a full frame polygon
  • Face Recognition uses HOG when in the settings file I am specifying CNN
  • Face Detection heads to the training by triggering ZM_TRAIN_FACES.PY and identifies the direction structure and picks up the training JPG to start analysing, then nothing happens
Below is the logs for quick reference:

Code: Select all

tail -F  /var/log/zm/zmesdetect*.log /var/log/zm/zmeventnotification.log
==> /var/log/zm/zmesdetect_m5.log <==

==> /var/log/zm/zmesdetect_m6.log <==

==> /var/log/zm/zmesdetect_m7.log <==

==> /var/log/zm/zmesdetect_m8.log <==

==> /var/log/zm/zmesdetect_m9.log <==

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:04:02.635820 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:02.635850 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:02.635875 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:02.635903 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:02.635928 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:02.635956 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:02.635981 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:02.636014 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:02.636033 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:02.636058 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:07.635370 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:3 min)<-                  -------------]
04/11/2021 00:04:07.635519 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:07.635787 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:07.635885 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:07.635991 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:07.636072 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:07.636119 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:07.636177 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:07.636225 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:07.636263 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:07.636310 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:07.636349 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:07.636383 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:07.636434 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:07.636457 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:07.636485 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:12.635288 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:3 min)<-                  -------------]
04/11/2021 00:04:12.635421 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:12.635584 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:12.635627 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:12.635694 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:12.635773 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:12.635811 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:12.635845 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:12.635886 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:12.635925 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:12.635954 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:12.635985 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:12.636013 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:12.636049 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:12.636072 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:12.636124 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:17.635472 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:3 min)<-                  -------------]
04/11/2021 00:04:17.635610 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:17.635896 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:17.635991 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:17.636080 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:17.636167 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:17.636199 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:17.636255 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:17.636312 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:17.636358 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:17.636420 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:17.636471 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:17.636515 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:17.636560 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:17.636585 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:17.636627 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:22.635108 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:3 min)<-                  -------------]
04/11/2021 00:04:22.635380 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:22.635424 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:22.635457 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:22.635548 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:22.635601 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:22.635632 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:22.635675 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:22.635772 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:22.635827 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:22.635858 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:22.635893 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:22.635993 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:22.636094 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:22.636171 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:22.636243 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:27.631139 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:4 min)<-                  -------------]
04/11/2021 00:04:27.631722 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:27.631831 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:27.631935 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:27.632114 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:27.632239 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:27.632339 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:27.632438 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:27.632539 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:27.632625 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:27.632700 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:27.632790 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:27.632882 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:27.632972 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:27.633039 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:27.633135 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:0)<--------------]
04/11/2021 00:04:32.634494 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:0, running for:4 min)<-                  -------------]
04/11/2021 00:04:32.634590 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:32.634620 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:32.634655 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:32.634714 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:32.634785 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:32.634816 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:32.634996 zmeventnotification[30118].INF [main:1010] [PARENT: New event 12078 reported for Monitor:6 (Name:Living Room) Motion All[last                   processed eid:]]
04/11/2021 00:04:32.636826 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:32.636888 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:32.636939 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:32.636971 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:32.637140 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:32.637243 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:32.637303 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=1]
04/11/2021 00:04:32.637328 zmeventnotification[30118].DBG [main:998] [PARENT: There are 1 new Events to process]
04/11/2021 00:04:32.644021 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:1)<--------------]
04/11/2021 00:04:32.644697 zmeventnotification[30736].DBG [ZoneMinder::Logger:294] [LogOpts: level=DBG/DBG, screen=OFF, database=INF, logfile=DBG->/var/l                  og/zm/zmeventnotification.log, syslog=INF]
04/11/2021 00:04:32.644859 zmeventnotification[30736].DBG [main:998] [PARENT: Forked process:30736 to handle alarm eid:12078]
04/11/2021 00:04:32.645071 zmeventnotification[30736].DBG [ZoneMinder::Object:119] [Loading ZoneMinder::Event from Events WHERE Id = 12078]
04/11/2021 00:04:32.647579 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Adding event path:/var/cache/zoneminder/even                  ts/6/2021-04-11/12078 to hook for image storage]
04/11/2021 00:04:32.647634 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Invoking hook on event start:'/var/lib/zmeve                  ntnotification/bin/zm_event_start.sh' 12078 6 "Living Room" "Motion All" "/var/cache/zoneminder/events/6/2021-04-11/12078"]

==> /var/log/zm/zmesdetect_m6.log <==
04/11/21 00:04:33 zmesdetect_m6[30740] INF ZMLog.py:225 [Setting up signal handler for logs]
04/11/21 00:04:33 zmesdetect_m6[30740] INF ZMLog.py:234 [Switching global logger to ZMLog]
04/11/21 00:04:33 zmesdetect_m6[30740] INF zm_detect.py:271 [---------| app:6.1.20, pyzm:0.3.45, ES:6.1.20 , OpenCV:4.5.2-dev|------------]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG1 utils.py:410 [secret filename: /etc/zm/secrets.ini]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ZM_PORTAL]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ZM_USER]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ZM_PASSWORD]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ZM_API_PORTAL]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ML_USER]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !ML_PASSWORD]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG2 utils.py:383 [Secret token found in config: !PLATEREC_ALPR_KEY]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG1 utils.py:445 [allowing self-signed certs to work...]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG4 utils.py:455 [Now checking for monitor overrides]
04/11/21 00:04:33 zmesdetect_m6[30740] DBG4 utils.py:523 [Finally, doing parameter substitution]
04/11/21 00:04:33 zmesdetect_m6[30740] INF zm_detect.py:296 [Importing local classes for Object/Face]
04/11/21 00:04:34 zmesdetect_m6[30740] INF zm_detect.py:319 [Connecting with ZM APIs]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 api.py:67 [API SSL certificate check has been disbled]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 api.py:176 [using username/password for login]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 api.py:205 [Using new token API]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 api.py:212 [Access token expires on:2021-04-11 02:04:34.297566 [7200s]]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 api.py:216 [Refresh token expires on:2021-04-12 00:04:34.297879 [86400s]]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 zm_detect.py:327 [using ml_sequence]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 zm_detect.py:339 [using stream_sequence]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 detect_sequence.py:145 [Resetting models, will be loaded on next run]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 detect_sequence.py:606 [Using automatic locking as we are switching between models]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 Media.py:53 [Media get SSL certificate check has been disbled]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 Media.py:101 [Using URL 12078 for stream]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 Media.py:114 [We will only process frames: ['snapshot', 'alarm']]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 Media.py:137 [No need to start streams, we are picking images from https://PORTAL/zm/index.php?view=image&                  eid=12078]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 Media.py:271 [Reading https://PORTAL/zm/index.php?view=image&eid=12078&fid=snapshot]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 api.py:139 [No need to relogin as access token still has 119.99988425 minutes remaining]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG4 api.py:267 [make_request called with url=https://PORTAL/zm/index.php?view=image&eid=12078&fid=snapshot pay                  load={} type=get query={'token': 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpc3MiOiJab25lTWluZGVyIiwiaWF0IjoxNjE4MDg4Njc0LCJleHAiOjE2MTgwOTU4NzQsInVzZXIiOi                  JhZG1pbiIsInR5cGUiOiJhY2Nlc3MifQ.gHbB6RawWF6vMkvTpkU167seJXWPk4QvOjLndb2PEqw'}]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 detect_sequence.py:628 [perf: Starting for frame:snapshot]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running object model in sequence ==================]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG4 detect_sequence.py:160 [Skipping TPU object detection as it is disabled]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG4 detect_sequence.py:164 [Loading sequence: YoloV4 GPU/CPU]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 detect_sequence.py:165 [Initializing model  type:object with options:{'name': 'YoloV4 GPU/CPU', 'enabled': 'y                  es', 'object_config': '/var/lib/zmeventnotification/models/yolov4/yolov4.cfg', 'object_weights': '/var/lib/zmeventnotification/models/yolov4/yolov4.weigh                  ts', 'object_labels': '/var/lib/zmeventnotification/models/yolov4/coco.names', 'object_min_confidence': 0.3, 'object_framework': 'opencv', 'object_proces                  sor': 'gpu', 'gpu_max_processes': 1, 'gpu_max_lock_wait': 100, 'cpu_max_processes': 1, 'cpu_max_lock_wait': 100, 'max_detection_size': '90%', 'disable_lo                  cks': 'no'}]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 yolo.py:39 [portalock: max:1, name:pyzm_uid33_gpu_lock, timeout:100]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 detect_sequence.py:662 [object has a same_model_sequence strategy of first]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 detect_sequence.py:674 [--------- Frame:snapshot Running variation: #1 -------------]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 yolo.py:51 [Waiting for pyzm_uid33_gpu_lock portalock...]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 yolo.py:53 [Got pyzm_uid33_gpu_lock portalock]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 yolo.py:86 [|--------- Loading "YoloV4 GPU/CPU" model from disk -------------|]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 yolo.py:93 [perf: processor:gpu Yolo initialization (loading /var/lib/zmeventnotification/models/yolov4/yolov                  4.weights model from disk) took: 440.95 ms]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG2 yolo.py:109 [Setting CUDA backend for OpenCV]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG3 yolo.py:110 [If you did not set your CUDA_ARCH_BIN correctly during OpenCV compilation, you will get errors d                  uring detection related to invalid device/make_policy]
04/11/21 00:04:34 zmesdetect_m6[30740] DBG1 yolo.py:148 [|---------- YOLO (input image: 800w*450h, model resize dimensions: 416w*416h) ----------|]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 yolo.py:69 [Released pyzm_uid33_gpu_lock portalock]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG1 yolo.py:174 [perf: processor:gpu Yolo detection took: 1341.61 ms]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 yolo.py:209 [perf: processor:gpu Yolo NMS filtering took: 2.91 ms]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 object.py:59 [core model detection over, got 10 objects. Now filtering]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 object.py:63 [Max object size found to be: 90%]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 object.py:71 [Converted 90% to 324000.0]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 object.py:94 [Ignoring diningtable [604, -2, 774, 78] as conf. level 0.24081453680992126 is lower than 0.3]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 object.py:94 [Ignoring person [-2, 102, 52, 294] as conf. level 0.2187693566083908 is lower than 0.3]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 object.py:96 [Returning filtered list of 8 objects.]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG4 detect_sequence.py:684 [This model iteration inside object found: labels: ['person', 'chair', 'sofa', 'sofa',                   'chair', 'chair', 'chair', 'book'],conf:[0.9924638271331787, 0.9615816473960876, 0.8519368767738342, 0.8476040363311768, 0.6415079236030579, 0.534684836                  8644714, 0.47819289565086365, 0.3203613758087158]]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:398 [Max object size found to be: 90%]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:406 [Converted 90% to 324000.0]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:422 [No polygons, adding full image polygon: {'name': 'full_image', 'value': [(0, 0), (128                  0, 0), (1280, 720), (0, 720)], 'pattern': None}]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:214 [resized polygons x=0.625/y=0.625: [{'name': 'full_image', 'value': [(0, 0), (800, 0),                   (800, 450), (0, 450)], 'pattern': None}]]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:person,POLYGON ((262 84, 414 84, 414 336, 262 336, 262                   84)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:499 [full_image intersects object:person[[(262, 84), (414, 84), (414, 336), (262, 336)]]]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((403 0, 487 0, 487 94, 403 94, 403 0))                   to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(403, 0), (487, 0), (487, 94), (403, 94)]] but do                  es NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:sofa,POLYGON ((460 8, 776 8, 776 232, 460 232, 460 8))                   to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:sofa[[(460, 8), (776, 8), (776, 232), (460, 232)]] but d                  oes NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:sofa,POLYGON ((-2 28, 284 28, 284 282, -2 282, -2 28))                   to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:sofa[[(-2, 28), (284, 28), (284, 282), (-2, 282)]] but d                  oes NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((564 212, 678 212, 678 364, 564 364, 56                  4 212)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(564, 212), (678, 212), (678, 364), (564, 364)]]                   but does NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((218 -1, 350 -1, 350 107, 218 107, 218                   -1)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(218, -1), (350, -1), (350, 107), (218, 107)]] bu                  t does NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((744 1, 800 1, 800 97, 744 97, 744 1))                   to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(744, 1), (800, 1), (800, 97), (744, 97)]] but do                  es NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:473 [intersection: comparing object:book,POLYGON ((606 84, 644 84, 644 114, 606 114, 606 8                  4)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:508 [full_image intersects object:book[[(606, 84), (644, 84), (644, 114), (606, 114)]] but                   does NOT match your detect pattern filter]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG3 detect_sequence.py:726 [breaking out of same model loop, as matches found and strategy is "first"]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running face model in sequence ==================]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG4 detect_sequence.py:176 [Skipping TPU face detection as it is disabled]

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:04:37.656313 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:1, running for:4 min)<-                  -------------]
04/11/2021 00:04:37.670243 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:37.671584 zmeventnotification[30118].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:04:37.681812 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:37.692733 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:37.695793 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:37.695892 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:37.696762 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:37.697043 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:37.697684 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:37.697761 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:37.698018 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:37.698075 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:37.698315 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:37.698486 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:37.700778 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:1)<--------------]

==> /var/log/zm/zmesdetect_m6.log <==
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:43 [perf: processor:cpu Face Recognition library load time took: 0.00 ms ]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:51 [Initializing face recognition with model:hog upsample:1, jitters:0]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG2 face_dlib.py:74 [portalock: max:1, name:pyzm_uid33_cpu_lock, timeout:100]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:98 [trained file not found, reading from images and doing training...]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:101 [If you are using a GPU and run out of memory, do the training using zm_train_faces.py. In t                  his case, other models like yolo may already take up a lot of GPU memory]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:57 [FOLDER is a directory. Processing all images inside it]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:63 [loading face from  FOLDER/1.jpg]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:75 [resizing to 800]

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:04:41.020979 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 parse of hook: and []]
04/11/2021 00:04:41.028615 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 hook start returned with text: json:[] exit:                  1]
04/11/2021 00:04:41.035704 zmeventnotification[30736].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:41.040647 zmeventnotification[30736].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:41.042657 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 For 6 (Living Room), SHM says: state=0, eid=                  12078]
04/11/2021 00:04:41.062490 zmeventnotification[30736].INF [main:1010] [|----> FORK:Living Room (6), eid:12078 Event 12078 for Monitor 6 has finished]
04/11/2021 00:04:41.154689 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Event end object is: state=>pending with cau                  se=>Motion: All]
04/11/2021 00:04:42.637260 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:1, running for:4 min)<-                  -------------]
04/11/2021 00:04:42.639610 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:42.639944 zmeventnotification[30118].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:04:42.642801 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:42.643790 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:42.644660 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:42.644714 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:42.644792 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:42.644980 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:42.645283 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:42.645347 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:42.645565 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:42.645621 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:42.645792 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:42.646035 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:42.647003 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:1)<--------------]
04/11/2021 00:04:43.172616 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 rules: Checking rules for alarm caused by ei                  d:12078, monitor:6, at: Sun Apr 11 00:04:43 2021 with cause:Motion All]
04/11/2021 00:04:43.173396 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 rules: No rules found for Monitor, allowing:                  6]
04/11/2021 00:04:43.174858 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Matching alarm to connection rules...]
04/11/2021 00:04:43.177969 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Checking alarm conditions for token ending i                  n:...pXO4DVXWQn]
04/11/2021 00:04:43.180757 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Monitor 6 event: last time not found, so sho                  uld send]
04/11/2021 00:04:43.180873 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 token is unique, shouldSendEventToConn retur                  ned true, so calling sendEvent]
04/11/2021 00:04:43.184070 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 isAllowedChannel: got type:event_start resCo                  de:1]
04/11/2021 00:04:43.184612 zmeventnotification[30736].INF [main:1010] [|----> FORK:Living Room (6), eid:12078 Not sending over FCM as notify filters are                   on_success:all and on_fail:none]
04/11/2021 00:04:43.187782 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 child finished writing to parent]
04/11/2021 00:04:43.187957 zmeventnotification[30736].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:43.188057 zmeventnotification[30736].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:45.189683 zmeventnotification[30736].INF [main:1010] [|----> FORK:Living Room (6), eid:12078 end hooks/use hooks not being used, going t                  o directly send out a notification if checks pass]
04/11/2021 00:04:45.192562 zmeventnotification[30736].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:45.192701 zmeventnotification[30736].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:47.193027 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 rules: Checking rules for alarm caused by ei                  d:12078, monitor:6, at: Sun Apr 11 00:04:47 2021 with cause:Motion All]
04/11/2021 00:04:47.193515 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 rules: No rules found for Monitor, allowing:                  6]
04/11/2021 00:04:47.193992 zmeventnotification[30736].INF [main:1010] [|----> FORK:Living Room (6), eid:12078 Not sending event end alarm, as we did not                   send a start alarm for this, or start hook processing failed]
04/11/2021 00:04:47.196275 zmeventnotification[30736].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:47.196370 zmeventnotification[30736].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:47.633269 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:1, running for:4 min)<-                  -------------]
04/11/2021 00:04:47.633354 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:47.655031 zmeventnotification[30118].DBG [main:998] [PARENT: RAW TEXT-->timestamp--TYPE--1618088482.61787--SPLIT--6--SPLIT--1618088683.1                  8165]
04/11/2021 00:04:47.657735 zmeventnotification[30118].DBG [main:998] [PARENT: Job: Update last sent timestamp of monitor:6 to 1618088683.18165 for id:161                  8088482.61787]
04/11/2021 00:04:47.658645 zmeventnotification[30118].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:04:47.658696 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:47.658752 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:47.658908 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:47.658952 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:47.659009 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:47.659045 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:47.659082 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:47.659133 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:47.659169 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:47.659200 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:47.659241 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:47.659266 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:47.659397 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:1)<--------------]
04/11/2021 00:04:49.196664 zmeventnotification[30736].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:49.196801 zmeventnotification[30736].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:51.197104 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 exiting]
04/11/2021 00:04:51.197845 zmeventnotification[30736].DBG [main:998] [|----> FORK:Living Room (6), eid:12078 Ending process:30736 to handle alarms]
04/11/2021 00:04:52.634542 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:1, running for:4 min)<-                  -------------]
04/11/2021 00:04:52.634754 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:52.634988 zmeventnotification[30118].DBG [main:998] [PARENT: RAW TEXT-->active_event_delete--TYPE--6--SPLIT--12078]
04/11/2021 00:04:52.635410 zmeventnotification[30118].DBG [main:998] [PARENT: Job: Deleting active_event eid:12078, mid:6]
04/11/2021 00:04:52.636253 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:52.636297 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:52.636413 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:52.636510 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:52.636551 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:52.636630 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:52.636671 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:52.636758 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:52.636797 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:52.636884 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:52.636943 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:52.637012 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:52.637041 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:52.637142 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]
04/11/2021 00:04:57.635350 zmeventnotification[30118].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:1, running for:4 min)<-                  -------------]
04/11/2021 00:04:57.635575 zmeventnotification[30118].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, i                  nvalid WEB: 0, PENDING: 0]
04/11/2021 00:04:57.635621 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:04:57.635652 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327356928 for 8]
04/11/2021 00:04:57.635705 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:04:57.635758 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327365120 for 6]
04/11/2021 00:04:57.635797 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:04:57.635844 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327361024 for 7]
04/11/2021 00:04:57.635879 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:04:57.635919 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327352832 for 9]
04/11/2021 00:04:57.635951 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:04:57.635989 zmeventnotification[30118].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 140395327619072 for 5]
04/11/2021 00:04:57.636021 zmeventnotification[30118].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:04:57.636058 zmeventnotification[30118].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:04:57.636090 zmeventnotification[30118].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:04:57.636134 zmeventnotification[30118].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]

Re: Import PYZM.ml.face_train as train - module not found

Posted: Sat Apr 10, 2021 10:14 pm
by mjalafoo
Then I updated the setting file to allow confidence level below 0.3 (even though it should not pick up this model, at least as per my understanding of the configuration since I have "use_sequence=yes"), and restarted the system. It looks like it picked up the change, and now the “IGNORING” of the objects log is not appearing, but still no polygons on objects, and the events are not filtered based on objects. The events are still listed in zoneminder with the zoneminder zone alarms.

Below are the logs for the setup after reducing the confedence level, for reference:

Code: Select all

tail -F  /var/log/zm/zmesdetect*.log /var/log/zm/zmeventnotification.log
==> /var/log/zm/zmesdetect_m5.log <==

==> /var/log/zm/zmesdetect_m6.log <==
04/11/21 00:04:36 zmesdetect_m6[30740] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running face model in sequence ==================]
04/11/21 00:04:36 zmesdetect_m6[30740] DBG4 detect_sequence.py:176 [Skipping TPU face detection as it is disabled]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:43 [perf: processor:cpu Face Recognition library load time took: 0.00 ms ]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:51 [Initializing face recognition with model:hog upsample:1, jitters:0]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG2 face_dlib.py:74 [portalock: max:1, name:pyzm_uid33_cpu_lock, timeout:100]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:98 [trained file not found, reading from images and doing training...]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_dlib.py:101 [If you are using a GPU and run out of memory, do the training using zm_train_faces.py. In this case, other models like yolo may already take up a lot of GPU memory]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:57 [FOLDER is a directory. Processing all images inside it]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:63 [loading face from  FOLDER/1.jpg]
04/11/21 00:04:38 zmesdetect_m6[30740] DBG1 face_train_dlib.py:75 [resizing to 800]

==> /var/log/zm/zmesdetect_m7.log <==

==> /var/log/zm/zmesdetect_m8.log <==
04/11/21 00:15:10 zmesdetect_m8[30885] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running face model in sequence ==================]
04/11/21 00:15:10 zmesdetect_m8[30885] DBG4 detect_sequence.py:176 [Skipping TPU face detection as it is disabled]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_dlib.py:43 [perf: processor:cpu Face Recognition library load time took: 0.01 ms ]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_dlib.py:51 [Initializing face recognition with model:hog upsample:1, jitters:0]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG2 face_dlib.py:74 [portalock: max:1, name:pyzm_uid33_cpu_lock, timeout:100]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_dlib.py:98 [trained file not found, reading from images and doing training...]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_dlib.py:101 [If you are using a GPU and run out of memory, do the training using zm_train_faces.py. In this case, other models like yolo may already take up a lot of GPU memory]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_train_dlib.py:57 [FOLDER is a directory. Processing all images inside it]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_train_dlib.py:63 [loading face from  FOLDER/1.jpg]
04/11/21 00:15:12 zmesdetect_m8[30885] DBG1 face_train_dlib.py:75 [resizing to 800]

==> /var/log/zm/zmesdetect_m9.log <==
04/11/21 00:28:33 zmesdetect_m9[1264] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running face model in sequence ==================]
04/11/21 00:28:33 zmesdetect_m9[1264] DBG4 detect_sequence.py:176 [Skipping TPU face detection as it is disabled]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_dlib.py:43 [perf: processor:cpu Face Recognition library load time took: 0.15 ms ]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_dlib.py:51 [Initializing face recognition with model:hog upsample:1, jitters:0]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG2 face_dlib.py:74 [portalock: max:1, name:pyzm_uid33_cpu_lock, timeout:100]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_dlib.py:98 [trained file not found, reading from images and doing training...]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_dlib.py:101 [If you are using a GPU and run out of memory, do the training using zm_train_faces.py. In this case, other models like yolo may already take up a lot of GPU memory]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_train_dlib.py:57 [FOLDER is a directory. Processing all images inside it]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_train_dlib.py:63 [loading face from  FOLDER/1.jpg]
04/11/21 00:28:35 zmesdetect_m9[1264] DBG1 face_train_dlib.py:75 [resizing to 800]

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:29:50.283016 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:29:50.283059 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:29:50.283093 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:29:50.283134 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:29:50.283168 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:29:50.283208 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:29:50.283242 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:29:50.283298 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:29:50.283326 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:29:50.283360 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]
04/11/2021 00:29:55.284444 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:1, running for:3 min)<--------------]
04/11/2021 00:29:55.284523 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:29:55.284550 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:29:55.284577 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:29:55.284642 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:29:55.284698 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:29:55.284759 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:29:55.284804 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:29:55.284841 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:29:55.284878 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:29:55.284911 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:29:55.284994 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:29:55.285024 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:29:55.285076 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:29:55.285098 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:29:55.285131 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]
04/11/2021 00:30:00.284420 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:1, running for:3 min)<--------------]
04/11/2021 00:30:00.284516 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:00.284684 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:00.284717 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:00.284968 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:00.285036 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:00.285070 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:00.285103 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:00.285130 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:00.285160 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:00.285185 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:00.285214 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:00.285239 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:00.285295 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:00.285319 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:00.285345 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]
04/11/2021 00:30:05.284423 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:1, running for:3 min)<--------------]
04/11/2021 00:30:05.284502 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:05.284762 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:05.284800 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:05.284857 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:05.284912 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:05.284940 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:05.284973 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:05.284998 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:05.285030 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:05.285055 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:05.285085 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:05.285109 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:05.285149 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:05.285170 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:05.285198 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:1)<--------------]
04/11/2021 00:30:10.281806 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:1, running for:3 min)<--------------]
04/11/2021 00:30:10.281889 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:10.281921 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:10.281949 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:10.282003 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:10.282047 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:10.282079 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:10.282112 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:10.282139 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:10.282167 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:10.282192 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:10.284815 zmeventnotification[1200].INF [main:1010] [PARENT: New event 12081 reported for Monitor:6 (Name:Living Room) Motion All[last processed eid:]]
04/11/2021 00:30:10.292325 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:10.293209 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:10.293359 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=1]
04/11/2021 00:30:10.293437 zmeventnotification[1200].DBG [main:998] [PARENT: There are 1 new Events to process]
04/11/2021 00:30:10.306069 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:2)<--------------]
04/11/2021 00:30:10.308108 zmeventnotification[1446].DBG [ZoneMinder::Logger:294] [LogOpts: level=DBG/DBG, screen=OFF, database=INF, logfile=DBG->/var/log/zm/zmeventnotification.log, syslog=INF]
04/11/2021 00:30:10.308215 zmeventnotification[1446].DBG [main:998] [PARENT: Forked process:1446 to handle alarm eid:12081]
04/11/2021 00:30:10.310637 zmeventnotification[1446].DBG [ZoneMinder::Object:119] [Loading ZoneMinder::Event from Events WHERE Id = 12081]
04/11/2021 00:30:10.326540 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Adding event path:/var/cache/zoneminder/events/6/2021-04-11/12081 to hook for image storage]
04/11/2021 00:30:10.326610 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Invoking hook on event start:'/var/lib/zmeventnotification/bin/zm_event_start.sh' 12081 6 "Living Room" "Motion All" "/var/cache/zoneminder/events/6/2021-04-11/12081"]

==> /var/log/zm/zmesdetect_m6.log <==
04/11/21 00:30:11 zmesdetect_m6[1450] INF ZMLog.py:225 [Setting up signal handler for logs]
04/11/21 00:30:11 zmesdetect_m6[1450] INF ZMLog.py:234 [Switching global logger to ZMLog]
04/11/21 00:30:11 zmesdetect_m6[1450] INF zm_detect.py:271 [---------| app:6.1.20, pyzm:0.3.45, ES:6.1.20 , OpenCV:4.5.2-dev|------------]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 utils.py:410 [secret filename: /etc/zm/secrets.ini]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ZM_PORTAL]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ZM_USER]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ZM_PASSWORD]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ZM_API_PORTAL]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ML_USER]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !ML_PASSWORD]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 utils.py:383 [Secret token found in config: !PLATEREC_ALPR_KEY]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 utils.py:445 [allowing self-signed certs to work...]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG4 utils.py:455 [Now checking for monitor overrides]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG4 utils.py:523 [Finally, doing parameter substitution]
04/11/21 00:30:11 zmesdetect_m6[1450] INF zm_detect.py:296 [Importing local classes for Object/Face]
04/11/21 00:30:11 zmesdetect_m6[1450] INF zm_detect.py:319 [Connecting with ZM APIs]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 api.py:67 [API SSL certificate check has been disbled]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 api.py:176 [using username/password for login]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 api.py:205 [Using new token API]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 api.py:212 [Access token expires on:2021-04-11 02:30:11.861916 [7200s]]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 api.py:216 [Refresh token expires on:2021-04-12 00:30:11.862261 [86400s]]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 zm_detect.py:327 [using ml_sequence]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 zm_detect.py:339 [using stream_sequence]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 detect_sequence.py:145 [Resetting models, will be loaded on next run]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG3 detect_sequence.py:606 [Using automatic locking as we are switching between models]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 Media.py:53 [Media get SSL certificate check has been disbled]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 Media.py:101 [Using URL 12081 for stream]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 Media.py:114 [We will only process frames: ['snapshot', 'alarm']]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 Media.py:137 [No need to start streams, we are picking images from https://PORTAL/zm/index.php?view=image&eid=12081]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG3 Media.py:271 [Reading https://PORTAL/zm/index.php?view=image&eid=12081&fid=snapshot]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG3 api.py:139 [No need to relogin as access token still has 119.99988809999999 minutes remaining]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG4 api.py:267 [make_request called with url=https://PORTAL/zm/index.php?view=image&eid=12081&fid=snapshot payload={} type=get query={'token': 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpc3MiOiJab25lTWluZGVyIiwiaWF0IjoxNjE4MDkwMjExLCJleHAiOjE2MTgwOTc0MTEsInVzZXIiOiJhZG1pbiIsInR5cGUiOiJhY2Nlc3MifQ.4febU-47CxOtBVh_5VqXze7eLJCXXW8wpqLC5E2BWBw'}]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 detect_sequence.py:628 [perf: Starting for frame:snapshot]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running object model in sequence ==================]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG4 detect_sequence.py:160 [Skipping TPU object detection as it is disabled]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG4 detect_sequence.py:164 [Loading sequence: YoloV4 GPU/CPU]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 detect_sequence.py:165 [Initializing model  type:object with options:{'name': 'YoloV4 GPU/CPU', 'enabled': 'yes', 'object_config': '/var/lib/zmeventnotification/models/yolov4/yolov4.cfg', 'object_weights': '/var/lib/zmeventnotification/models/yolov4/yolov4.weights', 'object_labels': '/var/lib/zmeventnotification/models/yolov4/coco.names', 'object_min_confidence': 0.1, 'object_framework': 'opencv', 'object_processor': 'gpu', 'gpu_max_processes': 1, 'gpu_max_lock_wait': 100, 'cpu_max_processes': 1, 'cpu_max_lock_wait': 100, 'max_detection_size': '90%', 'disable_locks': 'no'}]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 yolo.py:39 [portalock: max:1, name:pyzm_uid33_gpu_lock, timeout:100]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG3 detect_sequence.py:662 [object has a same_model_sequence strategy of first]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG3 detect_sequence.py:674 [--------- Frame:snapshot Running variation: #1 -------------]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 yolo.py:51 [Waiting for pyzm_uid33_gpu_lock portalock...]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG2 yolo.py:53 [Got pyzm_uid33_gpu_lock portalock]
04/11/21 00:30:11 zmesdetect_m6[1450] DBG1 yolo.py:86 [|--------- Loading "YoloV4 GPU/CPU" model from disk -------------|]
04/11/21 00:30:12 zmesdetect_m6[1450] DBG1 yolo.py:93 [perf: processor:gpu Yolo initialization (loading /var/lib/zmeventnotification/models/yolov4/yolov4.weights model from disk) took: 263.46 ms]
04/11/21 00:30:12 zmesdetect_m6[1450] DBG2 yolo.py:109 [Setting CUDA backend for OpenCV]
04/11/21 00:30:12 zmesdetect_m6[1450] DBG3 yolo.py:110 [If you did not set your CUDA_ARCH_BIN correctly during OpenCV compilation, you will get errors during detection related to invalid device/make_policy]
04/11/21 00:30:12 zmesdetect_m6[1450] DBG1 yolo.py:148 [|---------- YOLO (input image: 800w*450h, model resize dimensions: 416w*416h) ----------|]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 yolo.py:69 [Released pyzm_uid33_gpu_lock portalock]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG1 yolo.py:174 [perf: processor:gpu Yolo detection took: 1247.12 ms]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 yolo.py:209 [perf: processor:gpu Yolo NMS filtering took: 3.22 ms]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 object.py:59 [core model detection over, got 9 objects. Now filtering]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 object.py:63 [Max object size found to be: 90%]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 object.py:71 [Converted 90% to 324000.0]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 object.py:96 [Returning filtered list of 9 objects.]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG4 detect_sequence.py:684 [This model iteration inside object found: labels: ['person', 'chair', 'sofa', 'chair', 'sofa', 'chair', 'chair', 'book', 'diningtable'],conf:[0.9886476993560791, 0.9552034139633179, 0.9212812185287476, 0.8973908424377441, 0.8610964417457581, 0.5743203163146973, 0.3098158836364746, 0.3035195767879486, 0.2865641415119171]]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:398 [Max object size found to be: 90%]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:406 [Converted 90% to 324000.0]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:422 [No polygons, adding full image polygon: {'name': 'full_image', 'value': [(0, 0), (1280, 0), (1280, 720), (0, 720)], 'pattern': None}]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:214 [resized polygons x=0.625/y=0.625: [{'name': 'full_image', 'value': [(0, 0), (800, 0), (800, 450), (0, 450)], 'pattern': None}]]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:person,POLYGON ((352 210, 528 210, 528 454, 352 454, 352 210)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:499 [full_image intersects object:person[[(352, 210), (528, 210), (528, 454), (352, 454)]]]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((400 0, 484 0, 484 96, 400 96, 400 0)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(400, 0), (484, 0), (484, 96), (400, 96)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:sofa,POLYGON ((460 7, 776 7, 776 233, 460 233, 460 7)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:sofa[[(460, 7), (776, 7), (776, 233), (460, 233)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((562 216, 678 216, 678 360, 562 360, 562 216)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(562, 216), (678, 216), (678, 360), (562, 360)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:sofa,POLYGON ((-2 26, 282 26, 282 282, -2 282, -2 26)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:sofa[[(-2, 26), (282, 26), (282, 282), (-2, 282)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((214 -1, 348 -1, 348 109, 214 109, 214 -1)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(214, -1), (348, -1), (348, 109), (214, 109)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:chair,POLYGON ((752 0, 800 0, 800 96, 752 96, 752 0)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:chair[[(752, 0), (800, 0), (800, 96), (752, 96)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:book,POLYGON ((606 83, 644 83, 644 115, 606 115, 606 83)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:book[[(606, 83), (644, 83), (644, 115), (606, 115)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:473 [intersection: comparing object:diningtable,POLYGON ((606 -2, 766 -2, 766 76, 606 76, 606 -2)) to polygon:full_image,POLYGON ((0 0, 800 0, 800 450, 0 450, 0 0))]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG2 detect_sequence.py:491 [Using global match pattern: (person|car|motorbike|bus|truck|boat)]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:508 [full_image intersects object:diningtable[[(606, -2), (766, -2), (766, 76), (606, 76)]] but does NOT match your detect pattern filter]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG3 detect_sequence.py:726 [breaking out of same model loop, as matches found and strategy is "first"]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG1 detect_sequence.py:642 [============ Frame: snapshot Running face model in sequence ==================]
04/11/21 00:30:13 zmesdetect_m6[1450] DBG4 detect_sequence.py:176 [Skipping TPU face detection as it is disabled]

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:30:15.298185 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:2, running for:3 min)<--------------]
04/11/2021 00:30:15.303755 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:15.304838 zmeventnotification[1200].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:30:15.316499 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:15.319684 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:15.320408 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:15.320472 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:15.320777 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:15.320820 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:15.320860 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:15.320898 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:15.321694 zmeventnotification[1200].DBG [main:998] [PARENT: We've already worked on Monitor:6, Event:12081, not doing anything more]
04/11/2021 00:30:15.321727 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:15.321760 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:15.322023 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:15.322463 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:15.323766 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:2)<--------------]

==> /var/log/zm/zmesdetect_m6.log <==
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_dlib.py:43 [perf: processor:cpu Face Recognition library load time took: 0.01 ms ]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_dlib.py:51 [Initializing face recognition with model:hog upsample:1, jitters:0]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG2 face_dlib.py:74 [portalock: max:1, name:pyzm_uid33_cpu_lock, timeout:100]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_dlib.py:98 [trained file not found, reading from images and doing training...]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_dlib.py:101 [If you are using a GPU and run out of memory, do the training using zm_train_faces.py. In this case, other models like yolo may already take up a lot of GPU memory]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_train_dlib.py:57 [FOLDER is a directory. Processing all images inside it]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_train_dlib.py:63 [loading face from  FOLDER/1.jpg]
04/11/21 00:30:15 zmesdetect_m6[1450] DBG1 face_train_dlib.py:75 [resizing to 800]

==> /var/log/zm/zmeventnotification.log <==
04/11/2021 00:30:18.444737 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 parse of hook: and []]
04/11/2021 00:30:18.453074 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 hook start returned with text: json:[] exit:1]
04/11/2021 00:30:18.459476 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:18.464123 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:20.286001 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:2, running for:3 min)<--------------]
04/11/2021 00:30:20.288263 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:20.288962 zmeventnotification[1200].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:30:20.291179 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:20.292322 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:20.293002 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:20.293138 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:20.293266 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:20.293388 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:20.293504 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:20.293597 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:20.294229 zmeventnotification[1200].DBG [main:998] [PARENT: We've already worked on Monitor:6, Event:12081, not doing anything more]
04/11/2021 00:30:20.294308 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:20.294394 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:20.294694 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:20.294950 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:20.295662 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:2)<--------------]
04/11/2021 00:30:20.485244 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 rules: Checking rules for alarm caused by eid:12081, monitor:6, at: Sun Apr 11 00:30:20 2021 with cause:Motion All]
04/11/2021 00:30:20.485835 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 rules: No rules found for Monitor, allowing:6]
04/11/2021 00:30:20.488468 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Matching alarm to connection rules...]
04/11/2021 00:30:20.491775 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Checking alarm conditions for token ending in:...pXO4DVXWQn]
04/11/2021 00:30:20.493961 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Monitor 6 event: last time not found, so should send]
04/11/2021 00:30:20.494002 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 token is unique, shouldSendEventToConn returned true, so calling sendEvent]
04/11/2021 00:30:20.497094 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 isAllowedChannel: got type:event_start resCode:1]
04/11/2021 00:30:20.509211 zmeventnotification[1446].INF [main:1010] [|----> FORK:Living Room (6), eid:12081 Not sending over FCM as notify filters are on_success:all and on_fail:none]
04/11/2021 00:30:20.537558 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 child finished writing to parent]
04/11/2021 00:30:20.537931 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:20.538040 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:22.538249 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:22.538384 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:24.538533 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:24.538641 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:25.281403 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:2, running for:4 min)<--------------]
04/11/2021 00:30:25.281484 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:25.294940 zmeventnotification[1200].DBG [main:998] [PARENT: RAW TEXT-->timestamp--TYPE--1618090045.26976--SPLIT--6--SPLIT--1618090220.49451]
04/11/2021 00:30:25.296243 zmeventnotification[1200].DBG [main:998] [PARENT: Job: Update last sent timestamp of monitor:6 to 1618090220.49451 for id:1618090045.26976]
04/11/2021 00:30:25.296485 zmeventnotification[1200].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:30:25.296578 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:25.296697 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:25.296801 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:25.296899 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:25.297000 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:25.297084 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:25.297161 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:25.297226 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:25.297347 zmeventnotification[1200].DBG [main:998] [PARENT: We've already worked on Monitor:6, Event:12081, not doing anything more]
04/11/2021 00:30:25.297376 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:25.297472 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:25.297554 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:25.297610 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:25.297684 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:2)<--------------]
04/11/2021 00:30:26.538798 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:26.538993 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:26.539138 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 For 6 (Living Room), SHM says: state=0, eid=12081]
04/11/2021 00:30:26.539706 zmeventnotification[1446].INF [main:1010] [|----> FORK:Living Room (6), eid:12081 Event 12081 for Monitor 6 has finished]
04/11/2021 00:30:26.548271 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Event end object is: state=>pending with cause=>Motion: All]
04/11/2021 00:30:28.549688 zmeventnotification[1446].INF [main:1010] [|----> FORK:Living Room (6), eid:12081 end hooks/use hooks not being used, going to directly send out a notification if checks pass]
04/11/2021 00:30:28.551824 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:28.552151 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:30.282598 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:2, running for:4 min)<--------------]
04/11/2021 00:30:30.282690 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:30.282719 zmeventnotification[1200].DBG [main:998] [PARENT: There are 1 active child forks...]
04/11/2021 00:30:30.282850 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:30.282968 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:30.283064 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:30.283108 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:30.283233 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:30.283309 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:30.283407 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:30.283464 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:30.283612 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:30.283701 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:30.283805 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:30.283836 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:30.283915 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:1, total forks:2)<--------------]
04/11/2021 00:30:30.552611 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 rules: Checking rules for alarm caused by eid:12081, monitor:6, at: Sun Apr 11 00:30:30 2021 with cause:Motion All]
04/11/2021 00:30:30.552940 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 rules: No rules found for Monitor, allowing:6]
04/11/2021 00:30:30.553372 zmeventnotification[1446].INF [main:1010] [|----> FORK:Living Room (6), eid:12081 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
04/11/2021 00:30:30.555349 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:30.555504 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:32.555793 zmeventnotification[1446].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:32.555932 zmeventnotification[1446].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:34.556130 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 exiting]
04/11/2021 00:30:34.556550 zmeventnotification[1446].DBG [main:998] [|----> FORK:Living Room (6), eid:12081 Ending process:1446 to handle alarms]
04/11/2021 00:30:35.282454 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:1, total forks:2, running for:4 min)<--------------]
04/11/2021 00:30:35.282539 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:35.282806 zmeventnotification[1200].DBG [main:998] [PARENT: RAW TEXT-->active_event_delete--TYPE--6--SPLIT--12081]
04/11/2021 00:30:35.283115 zmeventnotification[1200].DBG [main:998] [PARENT: Job: Deleting active_event eid:12081, mid:6]
04/11/2021 00:30:35.283543 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:35.283593 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:35.283656 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:35.283716 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:35.283757 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:35.283799 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:35.283852 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:35.283891 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:35.283945 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:35.284000 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:35.284031 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:35.284100 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:35.284158 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:35.284188 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:2)<--------------]
04/11/2021 00:30:40.282230 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:2, running for:4 min)<--------------]
04/11/2021 00:30:40.282324 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:40.282356 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:40.282384 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:40.282623 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:40.282685 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:40.282757 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:40.282828 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:40.282880 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:40.282942 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:40.283003 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:40.283089 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:40.283152 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:40.283224 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:40.283255 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:40.283284 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:2)<--------------]
04/11/2021 00:30:45.281383 zmeventnotification[1200].DBG [main:998] [PARENT: ----------> Tick START (active forks:0, total forks:2, running for:4 min)<--------------]
04/11/2021 00:30:45.281460 zmeventnotification[1200].DBG [main:998] [PARENT: After tick: TOTAL: 1,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 1, WEB: 0, MQTT:0, invalid WEB: 0, PENDING: 0]
04/11/2021 00:30:45.281490 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 active child forks...]
04/11/2021 00:30:45.281518 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449799680 for 5]
04/11/2021 00:30:45.281579 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 5: 1]
04/11/2021 00:30:45.281636 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449537536 for 8]
04/11/2021 00:30:45.281678 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 8: 1]
04/11/2021 00:30:45.281715 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449541632 for 7]
04/11/2021 00:30:45.281751 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 7: 1]
04/11/2021 00:30:45.281802 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449545728 for 6]
04/11/2021 00:30:45.281838 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 6: 1]
04/11/2021 00:30:45.281895 zmeventnotification[1200].DBG [ZoneMinder::Memory::Mapped:84] [zmMemAttach already attached at 139873449533440 for 9]
04/11/2021 00:30:45.281936 zmeventnotification[1200].DBG [ZoneMinder::Memory:300] [Shared data appears valid for monitor 9: 1]
04/11/2021 00:30:45.281987 zmeventnotification[1200].DBG [main:998] [PARENT: checkEvents() new events found=0]
04/11/2021 00:30:45.282016 zmeventnotification[1200].DBG [main:998] [PARENT: There are 0 new Events to process]
04/11/2021 00:30:45.282048 zmeventnotification[1200].DBG [main:998] [PARENT: ---------->Tick END (active forks:0, total forks:2)<--------------]

What are the next recommended steps?