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)<--------------]