Problem staring zm_detetct.py

Support for the docker image maintained by dlandon
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

I don't need manual data. How will it help me debug your case when you said manual works but daemon mode does not?


What you need to do is:

1. Make sure you don't run detect manually
2. Make sure ES is running with use_hooks = yes
3. Trigger an event which has an object to be detected
4. Send the logs of detect and ES for that situation
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

Now is 19:08, so no logs are written since last manual script run. I have move the car, and I moved to be detected. Nothing happened in log:

Code: Select all

02/18/20 17:59:09 zmesdetect_m1[29788] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:138 [---------| app version: 5.7.4 |------------]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:229 [secret filename: /etc/zm/secrets.ini]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:203 [Secret token found in config: !PLATEREC_ALPR_KEY]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:254 [allowing self-signed certs to work...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:273 [[monitor-1] overrides key:models with value:yolo]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:273 [[monitor-1] overrides key:yolo_type with value:tiny]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [config] is '/var/lib/zmeventnotification/models/yolov3/yolov3.cfg' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [weights] is '/var/lib/zmeventnotification/models/yolov3/yolov3.weights' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [labels] is '/var/lib/zmeventnotification/models/yolov3/yolov3_classes.txt' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_config] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.cfg' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_weights] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.weights' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_labels] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.txt' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [known_images_path] is '/var/lib/zmeventnotification/known_faces' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [unknown_images_path] is '/var/lib/zmeventnotification/unknown_faces' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:160 [Importing local classes for Yolo/Face]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:163 [Trying to download my portal snapshot]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:202 [No polygon area specfied, so adding a full image polygon:[{'name': 'full_image', 'value': [(0, 0), (1280, 0), (1280, 720), (0, 720)]}]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:204 [resizing to 1200 before analysis...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:37 [resized polygons x=0.9375/y=0.9375: [{'name': 'full_image', 'value': [(0, 0), (1200, 0), (1200, 675), (0, 675)]}]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:222 [User ALPR if vehicle found: False]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:293 [Using model: yolo with /var/lib/zmeventnotification/images/923.jpg]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:46 [|---------- YOLO (input image: 1200w*675h, resized to: 416w*416h) ----------|]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:57 [Initializing Yolo]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:58 [config:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.cfg, weights:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.weights]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:76 [Not using CUDA backend]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:79 [YOLO initialization (loading model from disk) took: 11.869 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:91 [YOLO detection took: 69.065 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:122 [YOLO NMS filtering took: 0.14 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] INF yolo.py:140 [object:person at [149, 230, 335, 640] has a acceptable confidence:0.7402644157409668 compared to min confidence of: 0.3, adding]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:127 [intersection: polygon in process=[(149, 230), (335, 230), (335, 640), (149, 640)]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:133 [full_image intersects object:person[[(149, 230), (335, 230), (335, 640), (149, 640)]]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:476 [ALPR not in use, no need for look aheads in processing]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:493 [labels found: ['person']]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:494 [match found in /var/lib/zmeventnotification/images/923.jpg, breaking file loop...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:543 [Writing out debug bounding box image to /var/lib/zmeventnotification/images/923-bbox.jpg...]
02/18/20 17:59:11 zmesdetect_m1[29807] ERR zm_detect.py:561 [Could not write image to ZoneMinder as eventpath not present]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:566 [Removing matches to past detections]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:27 [trying to load /var/lib/zmeventnotification/images/monitor-1-data.pkl]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:92 [past detection person@[(149, 230), (335, 230), (335, 640), (149, 230)] approximately matches person@[(149, 230), (335, 230), (335, 640), (149, 230)] removing]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
root@44353fa5d618:/var/log/zm# tail -F  /var/log/zm/zmesdetect*.log
==> /var/log/zm/zmesdetect_m1.log <==
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:133 [full_image intersects object:person[[(149, 230), (335, 230), (335, 640), (149, 640)]]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:476 [ALPR not in use, no need for look aheads in processing]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:493 [labels found: ['person']]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:494 [match found in /var/lib/zmeventnotification/images/923.jpg, breaking file loop...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:543 [Writing out debug bounding box image to /var/lib/zmeventnotification/images/923-bbox.jpg...]
02/18/20 17:59:11 zmesdetect_m1[29807] ERR zm_detect.py:561 [Could not write image to ZoneMinder as eventpath not present]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:566 [Removing matches to past detections]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:27 [trying to load /var/lib/zmeventnotification/images/monitor-1-data.pkl]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:92 [past detection person@[(149, 230), (335, 230), (335, 640), (149, 230)] approximately matches person@[(149, 230), (335, 230), (335, 640), (149, 230)] removing]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]

Code: Select all

2020-02-18 19:12:26.823700 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:12:26.812120 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:12:25.849101 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 15300 - Analysing at 14.29 fps]
2020-02-18 19:12:24.536937 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:15300 - Capturing at 14.29 fps, capturing bandwidth 45401bytes/sec]
2020-02-18 19:12:24.500480 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
2020-02-18 19:12:22.496100 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 hook end returned with text:  json:[] exit:127]
2020-02-18 19:12:22.480830 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 955 1 "vhod" "Motion: All" "/var/cache/zoneminder/events/1/20/02/18/19/12/11"]
2020-02-18 19:12:21.820230 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:12:21.812160 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:12:21.705828 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46600 - Analysing at 10.00 fps]
2020-02-18 19:12:20.472010 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Event 955 for Monitor 1 has finished]
2020-02-18 19:12:20.076974 zma_m1[31373].INF-zm_event.cpp/588 [Updating frames delta by -1.11 sec to match video file]
2020-02-18 19:12:20.066887 zma_m1[31373].INF-zm_monitor.cpp/1661 [vhod: 15218 - Closing event 955, alarm end]
2020-02-18 19:12:20.056740 zma_m1[31373].INF-zm_monitor.cpp/1656 [vhod: 15218 - Left alarm state (955) - 125(20) images]
2020-02-18 19:12:18.776939 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 15200 - Analysing at 14.29 fps]
2020-02-18 19:12:17.520861 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:15200 - Capturing at 14.29 fps, capturing bandwidth 63969bytes/sec]
2020-02-18 19:12:17.263312 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46600 - Capturing at 10.00 fps, capturing bandwidth 20115bytes/sec]
2020-02-18 19:12:16.822580 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:12:16.815020 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:12:16.076703 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15161 - Gone into alert state]
2020-02-18 19:12:15.980863 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 15160 - Gone back into alarm state]
2020-02-18 19:12:15.957465 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15159 - Gone into alert state]
2020-02-18 19:12:15.276943 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 15149 - Gone back into alarm state]
2020-02-18 19:12:15.152859 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15148 - Gone into alert state]
2020-02-18 19:12:14.999056 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 15145 - Gone back into alarm state]
2020-02-18 19:12:14.882583 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15144 - Gone into alert state]
2020-02-18 19:12:14.811478 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 15143 - Gone back into alarm state]
2020-02-18 19:12:14.467370 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Sending event_start notification for EID:955 over MQTT]
2020-02-18 19:12:14.464480 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Monitor 1 event: should send out as  19.4926199913025 is >= interval of ]
2020-02-18 19:12:14.461590 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Checking alarm rules for MQTT 192.168.1.27]
2020-02-18 19:12:14.455500 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 FCM push message returned a 200 with body {"multicast_id":8591763831822221095,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049534259068"}]}]
2020-02-18 19:12:14.152170 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Sending event_start notification over FCM]
2020-02-18 19:12:14.150690 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Monitor 1 event: should send out as  19.8074998855591 is >= interval of 0]
2020-02-18 19:12:14.147320 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Checking alarm rules for token ending in:...5sxQF8jrbQ]
2020-02-18 19:12:14.141150 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 FCM push message returned a 200 with body {"multicast_id":7843036638935216559,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049533952954"}]}]
2020-02-18 19:12:13.847210 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Sending event_start notification over FCM]
2020-02-18 19:12:13.843990 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Monitor 1 event: should send out as  19.118940114975 is >= interval of 0]
2020-02-18 19:12:13.840770 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Checking alarm rules for token ending in:...w4M8bOGNjL]
2020-02-18 19:12:13.837330 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Matching alarm to connection rules...]
2020-02-18 19:12:13.771819 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15128 - Gone into alert state]
2020-02-18 19:12:13.556883 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 15125 - Gone back into alarm state]
2020-02-18 19:12:11.833780 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 hook start returned with text: json:[] exit:127]
2020-02-18 19:12:11.828590 zmeventnotification[32386].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:955 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 955 1 "vhod" "Motion All" "/var/cache/zoneminder/events/1/20/02/18/19/12/11"]
2020-02-18 19:12:11.824640 zmeventnotification[32386].INF-zmeventnotification.pl [PARENT: Forked process:32386 to handle alarm eid:955]
2020-02-18 19:12:11.821157 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 15100 - Gone into alert state]
2020-02-18 19:12:11.819070 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 new Events to process]
2020-02-18 19:12:11.818161 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 15100 - Analysing at 14.29 fps]
2020-02-18 19:12:11.815200 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: New event 955 reported for Monitor:1 (Name:vhod) Motion All]
2020-02-18 19:12:11.811590 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:12:11.776367 zmc_m1[31372].INF-zm_ffmpeg_camera.cpp/889 [Event video offset is -1.112 sec (<0 means video starts early)]
2020-02-18 19:12:11.772751 zmc_m1[31372].INF-zm_videostore.cpp/62 [Opening video storage stream /var/cache/zoneminder/events/1/20/02/18/19/12/11/955-video.mp4 format: mp4]
2020-02-18 19:12:11.713829 zma_m1[31373].INF-zm_monitor.cpp/1615 [vhod: 15098 - Opening new event 955, alarm start]
2020-02-18 19:12:11.705550 zma_m1[31373].INF-zm_monitor.cpp/1564 [vhod: 15098 - Gone into alarm state PreAlarmCount: 0 > AlarmFrameCount:1 Cause:Motion All]
2020-02-18 19:12:11.670287 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46500 - Analysing at 10.00 fps]
2020-02-18 19:12:10.516785 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:15100 - Capturing at 14.29 fps, capturing bandwidth 46562bytes/sec]
2020-02-18 19:12:07.259822 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46500 - Capturing at 10.00 fps, capturing bandwidth 23885bytes/sec]
2020-02-18 19:12:06.818900 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:12:06.812200 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:12:04.769061 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 15000 - Analysing at 14.29 fps]
2020-02-18 19:12:03.495719 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:15000 - Capturing at 14.29 fps, capturing bandwidth 44240bytes/sec]
2020-02-18 19:12:02.547710 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Ending process:32376 to handle alarms]
2020-02-18 19:12:01.821580 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:12:01.813560 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:12:01.671950 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46400 - Analysing at 10.00 fps]
2020-02-18 19:11:58.534990 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
2020-02-18 19:11:57.801869 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14900 - Analysing at 14.29 fps]
2020-02-18 19:11:57.276090 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46400 - Capturing at 10.00 fps, capturing bandwidth 20150bytes/sec]
2020-02-18 19:11:56.827310 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:56.818100 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:11:56.529430 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 hook end returned with text:  json:[] exit:127]
2020-02-18 19:11:56.515740 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 954 1 "vhod" "Motion: All" "/var/cache/zoneminder/events/1/20/02/18/19/11/47"]
2020-02-18 19:11:56.500471 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14900 - Capturing at 14.29 fps, capturing bandwidth 40171bytes/sec]
2020-02-18 19:11:54.511090 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Event 954 for Monitor 1 has finished]
2020-02-18 19:11:54.507530 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Sending event_start notification for EID:954 over MQTT]
2020-02-18 19:11:54.504070 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Monitor 1 event: should send out as  14.5404999256134 is >= interval of ]
2020-02-18 19:11:54.502760 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Checking alarm rules for MQTT 192.168.1.27]
2020-02-18 19:11:54.498920 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 FCM push message returned a 200 with body {"multicast_id":2367101436383940470,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049514307526"}]}]
2020-02-18 19:11:54.192930 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Sending event_start notification over FCM]
2020-02-18 19:11:54.188520 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Monitor 1 event: should send out as  14.81968998909 is >= interval of 0]
2020-02-18 19:11:54.183720 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Checking alarm rules for token ending in:...5sxQF8jrbQ]
2020-02-18 19:11:54.176070 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 FCM push message returned a 200 with body {"multicast_id":802259648318967070,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049513996655"}]}]
2020-02-18 19:11:54.083944 zma_m1[31373].INF-zm_event.cpp/588 [Updating frames delta by -2.66 sec to match video file]
2020-02-18 19:11:54.070485 zma_m1[31373].INF-zm_monitor.cpp/1661 [vhod: 14847 - Closing event 954, alarm end]
2020-02-18 19:11:54.063639 zma_m1[31373].INF-zm_monitor.cpp/1656 [vhod: 14847 - Left alarm state (954) - 98(16) images]
2020-02-18 19:11:53.881580 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Sending event_start notification over FCM]
2020-02-18 19:11:53.876920 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Monitor 1 event: should send out as  14.1512100696564 is >= interval of 0]
2020-02-18 19:11:53.872360 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Checking alarm rules for token ending in:...w4M8bOGNjL]
2020-02-18 19:11:53.864570 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Matching alarm to connection rules...]
2020-02-18 19:11:51.859870 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 hook start returned with text: json:[] exit:127]
2020-02-18 19:11:51.849020 zmeventnotification[32376].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:954 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 954 1 "vhod" "Motion All" "/var/cache/zoneminder/events/1/20/02/18/19/11/47"]
2020-02-18 19:11:51.840040 zmeventnotification[32376].INF-zmeventnotification.pl [PARENT: Forked process:32376 to handle alarm eid:954]
2020-02-18 19:11:51.823740 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 new Events to process]
2020-02-18 19:11:51.819030 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: New event 954 reported for Monitor:1 (Name:vhod) Motion All]
2020-02-18 19:11:51.812390 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:51.678967 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46300 - Analysing at 10.00 fps]
2020-02-18 19:11:51.497310 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Ending process:32357 to handle alarms]
2020-02-18 19:11:50.760767 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14800 - Analysing at 14.29 fps]
2020-02-18 19:11:49.485781 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14800 - Capturing at 14.29 fps, capturing bandwidth 53352bytes/sec]
2020-02-18 19:11:49.369058 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14780 - Gone into alert state]
2020-02-18 19:11:49.098671 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 14776 - Gone back into alarm state]
2020-02-18 19:11:48.719236 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14771 - Gone into alert state]
2020-02-18 19:11:48.604693 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 14769 - Gone back into alarm state]
2020-02-18 19:11:48.392062 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14765 - Gone into alert state]
2020-02-18 19:11:48.138479 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 14762 - Gone back into alarm state]
2020-02-18 19:11:48.086516 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14761 - Gone into alert state]
2020-02-18 19:11:47.688101 zmc_m1[31372].INF-zm_ffmpeg_camera.cpp/889 [Event video offset is -2.663 sec (<0 means video starts early)]
2020-02-18 19:11:47.684135 zmc_m1[31372].INF-zm_videostore.cpp/62 [Opening video storage stream /var/cache/zoneminder/events/1/20/02/18/19/11/47/954-video.mp4 format: mp4]
2020-02-18 19:11:47.571748 zma_m1[31373].INF-zm_monitor.cpp/1615 [vhod: 14754 - Opening new event 954, alarm start]
2020-02-18 19:11:47.564130 zma_m1[31373].INF-zm_monitor.cpp/1564 [vhod: 14754 - Gone into alarm state PreAlarmCount: 0 > AlarmFrameCount:1 Cause:Motion All]
2020-02-18 19:11:47.489080 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
2020-02-18 19:11:47.269808 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46300 - Capturing at 10.00 fps, capturing bandwidth 20004bytes/sec]
2020-02-18 19:11:46.818510 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:46.811780 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:11:45.484550 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 hook end returned with text:  json:[] exit:127]
2020-02-18 19:11:45.472740 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 953 1 "vhod" "Motion: All" "/var/cache/zoneminder/events/1/20/02/18/19/11/33"]
2020-02-18 19:11:43.759633 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14700 - Analysing at 16.67 fps]
2020-02-18 19:11:43.463700 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Event 953 for Monitor 1 has finished]
2020-02-18 19:11:42.824072 zma_m1[31373].INF-zm_event.cpp/588 [Updating frames delta by -1.06 sec to match video file]
2020-02-18 19:11:42.813260 zma_m1[31373].INF-zm_monitor.cpp/1661 [vhod: 14686 - Closing event 953, alarm end]
2020-02-18 19:11:42.809375 zma_m1[31373].INF-zm_monitor.cpp/1656 [vhod: 14686 - Left alarm state (953) - 150(139) images]
2020-02-18 19:11:42.466583 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14700 - Capturing at 16.67 fps, capturing bandwidth 83097bytes/sec]
2020-02-18 19:11:42.436228 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14681 - Gone into alert state]
2020-02-18 19:11:41.820900 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:41.814380 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 active child forks...]
2020-02-18 19:11:41.696003 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46200 - Analysing at 10.00 fps]
2020-02-18 19:11:39.459600 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Sending event_start notification for EID:953 over MQTT]
2020-02-18 19:11:39.456170 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Monitor 1 event: should send out as  284.567739963531 is >= interval of ]
2020-02-18 19:11:39.455030 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Checking alarm rules for MQTT 192.168.1.27]
2020-02-18 19:11:39.451640 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 FCM push message returned a 200 with body {"multicast_id":8040979603226237094,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049499271882"}]}]
2020-02-18 19:11:39.180360 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Sending event_start notification over FCM]
2020-02-18 19:11:39.179220 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Monitor 1 event: should send out as  284.831389904022 is >= interval of 0]
2020-02-18 19:11:39.176200 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Checking alarm rules for token ending in:...5sxQF8jrbQ]
2020-02-18 19:11:39.174000 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 FCM push message returned a 200 with body {"multicast_id":8572300909050483269,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582049498989261"}]}]
2020-02-18 19:11:38.849000 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Sending event_start notification over FCM]
2020-02-18 19:11:38.845420 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Monitor 1 event: should send out as  284.142339944839 is >= interval of 0]
2020-02-18 19:11:38.841950 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Checking alarm rules for token ending in:...w4M8bOGNjL]
2020-02-18 19:11:38.835170 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Matching alarm to connection rules...]
2020-02-18 19:11:37.824446 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14600 - Analysing at 11.11 fps]
2020-02-18 19:11:37.637691 zma_m1[31373].INF-zm_monitor.cpp/1642 [vhod: 14595 - Gone back into alarm state]
2020-02-18 19:11:37.524018 zma_m1[31373].INF-zm_monitor.cpp/1648 [vhod: 14594 - Gone into alert state]
2020-02-18 19:11:37.270035 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46200 - Capturing at 10.00 fps, capturing bandwidth 23675bytes/sec]
2020-02-18 19:11:36.941072 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14600 - Capturing at 10.00 fps, capturing bandwidth 58533bytes/sec]
2020-02-18 19:11:36.831150 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 hook start returned with text: json:[] exit:127]
2020-02-18 19:11:36.825740 zmeventnotification[32357].INF-zmeventnotification.pl [|----> FORK:vhod (1), eid:953 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 953 1 "vhod" "Motion All" "/var/cache/zoneminder/events/1/20/02/18/19/11/33"]
2020-02-18 19:11:36.821680 zmeventnotification[32357].INF-zmeventnotification.pl [PARENT: Forked process:32357 to handle alarm eid:953]
2020-02-18 19:11:36.815990 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 1 new Events to process]
2020-02-18 19:11:36.814690 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: New event 953 reported for Monitor:1 (Name:vhod) Motion All]
2020-02-18 19:11:36.811590 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:34.436001 zmc_m1[31372].INF-zm_ffmpeg_camera.cpp/889 [Event video offset is -1.062 sec (<0 means video starts early)]
2020-02-18 19:11:34.431135 zmc_m1[31372].INF-zm_videostore.cpp/62 [Opening video storage stream /var/cache/zoneminder/events/1/20/02/18/19/11/33/953-video.mp4 format: mp4]
2020-02-18 19:11:34.356726 zma_m1[31373].INF-zm_monitor.cpp/1615 [vhod: 14541 - Opening new event 953, alarm start]
2020-02-18 19:11:34.347644 zma_m1[31373].INF-zm_monitor.cpp/1564 [vhod: 14541 - Gone into alarm state PreAlarmCount: 0 > AlarmFrameCount:1 Cause:Motion All]
2020-02-18 19:11:31.820100 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:31.812100 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:31.669251 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46100 - Analysing at 10.00 fps]
2020-02-18 19:11:28.982401 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14500 - Analysing at 8.33 fps]
2020-02-18 19:11:27.260523 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46100 - Capturing at 10.00 fps, capturing bandwidth 20385bytes/sec]
2020-02-18 19:11:26.819910 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:26.812110 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:26.636611 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14500 - Capturing at 7.69 fps, capturing bandwidth 23889bytes/sec]
2020-02-18 19:11:21.821440 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:21.813620 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:21.687482 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 46000 - Analysing at 10.00 fps]
2020-02-18 19:11:17.259659 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:46000 - Capturing at 10.00 fps, capturing bandwidth 19985bytes/sec]
2020-02-18 19:11:16.826160 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:16.813980 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:16.007556 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14400 - Analysing at 7.14 fps]
2020-02-18 19:11:13.645172 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14400 - Capturing at 7.69 fps, capturing bandwidth 24865bytes/sec]
2020-02-18 19:11:11.818420 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:11.811680 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:11.671536 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 45900 - Analysing at 10.00 fps]
2020-02-18 19:11:07.271836 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:45900 - Capturing at 10.00 fps, capturing bandwidth 23747bytes/sec]
2020-02-18 19:11:06.825640 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:06.813720 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:02.983480 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14300 - Analysing at 7.69 fps]
2020-02-18 19:11:01.822130 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:11:01.813830 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:11:01.683128 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 45800 - Analysing at 10.00 fps]
2020-02-18 19:11:00.633458 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14300 - Capturing at 7.69 fps, capturing bandwidth 25324bytes/sec]
2020-02-18 19:10:57.256199 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:45800 - Capturing at 10.00 fps, capturing bandwidth 20396bytes/sec]
2020-02-18 19:10:56.826310 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:10:56.814160 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:10:51.840770 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:10:51.836540 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: ESCONTROL_INTERFACE is disabled. Not saving control data]
2020-02-18 19:10:51.829670 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: Re-loading monitors, emptying needsReload() list]
2020-02-18 19:10:51.824950 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: Reloading Monitors...]
2020-02-18 19:10:51.819880 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: Total event client connections: 3]
2020-02-18 19:10:51.812210 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
2020-02-18 19:10:51.718970 zma_m2[29455].INF-zm_monitor.cpp/1297 [Terasa: 45700 - Analysing at 10.00 fps]
2020-02-18 19:10:49.980013 zma_m1[31373].INF-zm_monitor.cpp/1297 [vhod: 14200 - Analysing at 7.69 fps]
2020-02-18 19:10:47.613673 zmc_m1[31372].INF-zm_monitor.cpp/2546 [vhod: images:14200 - Capturing at 7.69 fps, capturing bandwidth 23817bytes/sec]
2020-02-18 19:10:47.258630 zmc_m2[29447].INF-zm_monitor.cpp/2546 [Terasa: images:45700 - Capturing at 10.00 fps, capturing bandwidth 20148bytes/sec]
2020-02-18 19:10:46.822210 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 new Events to process]
2020-02-18 19:10:46.809920 zmeventnotification[29479].INF-zmeventnotification.pl [PARENT: There are 0 active child forks...]
For info I had to change base path in

Code: Select all

zmeventnotification.ini
to have working notifications:

Code: Select all

#base_data_path=/var/lib/zmeventnotification
base_data_path=/mnt/Zoneminder
I have tried this now:

Code: Select all

sudo -u www-data /usr/bin/zmeventnotification.pl
Can't locate ZoneMinder.pm in @INC (you may need to install the ZoneMinder module) (@INC contains: /etc/perl /usr/local/lib/x86_64-linux-gnu/perl/5.28.1 /usr/local/share/perl/5.28.1 /usr/lib/x86_64-linux-gnu/perl5/5.28 /usr/share/perl5 /usr/lib/x86_64-linux-gnu/perl/5.28 /usr/share/perl/5.28 /usr/local/lib/site_perl /usr/lib/x86_64-linux-gnu/perl-base) at /usr/bin/zmeventnotification.pl line 674.
BEGIN failed--compilation aborted at /usr/bin/zmeventnotification.pl line 674.
Posix missing?
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

No idea - I don't understand what you are posting at all.
I get the feeling you are mixing up between your host and your docker image.

Your last post shows issues in running zmeventnotification.pl but in your logs, zmeventnotification.pl is running just fine.

I'm sorry, but I can't help if you aren't doing exactly what I am asking.

Remember, if you are using dlandon's docker ALL commands need to be tried _inside_ the docker, not on your host system,
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

SORRY. Yes, I mixed up host and docker.
zmeventnotification.pl is running just fine in docker, sorry.
This is docker zmeventnotifications.log:

Code: Select all

9:34:54.837190 zmeventnotification[738].INF [main:833] [|----> FORK:Terasa (2), eid:967 Ending process:738 to handle alarms]
02/18/20 19:34:56.550470 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 Event 968 for Monitor 2 has finished]
02/18/20 19:34:56.819287 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:34:56.827716 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:34:58.559870 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 968 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/34/46"]
02/18/20 19:34:58.574703 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:00.579887 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:01.813514 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:35:01.820990 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:04.588026 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 Ending process:743 to handle alarms]
02/18/20 19:35:06.811842 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 19:35:06.815381 zmeventnotification[29479].INF [main:833] [PARENT: New event 969 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 19:35:06.818591 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 19:35:06.823356 zmeventnotification[761].INF [main:833] [PARENT: Forked process:761 to handle alarm eid:969]
02/18/20 19:35:06.827827 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 969 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/19/35/04"]
02/18/20 19:35:06.833480 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 hook start returned with text: json:[] exit:127]
02/18/20 19:35:08.837280 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Matching alarm to connection rules...]
02/18/20 19:35:08.848254 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 19:35:08.852467 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.108589887619 is >= interval of 0]
02/18/20 19:35:08.857176 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification over FCM]
02/18/20 19:35:09.176634 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 FCM push message returned a 200 with body {"multicast_id":2099089160472960940,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050908989857"}]}]
02/18/20 19:35:09.182595 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 19:35:09.186779 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.7860798835754 is >= interval of 0]
02/18/20 19:35:09.190035 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification over FCM]
02/18/20 19:35:09.422515 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 FCM push message returned a 200 with body {"multicast_id":3422618985457005421,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050909285549"}]}]
02/18/20 19:35:09.425986 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 19:35:09.429263 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.4552400112152 is >= interval of ]
02/18/20 19:35:09.432109 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification for EID:969 over MQTT]
02/18/20 19:35:11.816520 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:35:11.822667 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:13.440248 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Event 969 for Monitor 2 has finished]
02/18/20 19:35:15.448879 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 969 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/35/04"]
02/18/20 19:35:15.462864 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:16.813830 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:35:16.821663 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:17.468116 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:21.476360 zmeventnotification[761].INF [main:833] [|----> FORK:Terasa (2), eid:969 Ending process:761 to handle alarms]
02/18/20 19:35:21.813500 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 19:35:21.818878 zmeventnotification[29479].INF [main:833] [PARENT: New event 969 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 19:35:21.825085 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 19:35:21.835000 zmeventnotification[766].INF [main:833] [PARENT: Forked process:766 to handle alarm eid:969]
02/18/20 19:35:21.840467 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 969 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/19/35/04"]
02/18/20 19:35:21.845451 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 hook start returned with text: json:[] exit:127]
02/18/20 19:35:21.848546 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Event 969 for Monitor 2 has finished]
02/18/20 19:35:23.851926 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Matching alarm to connection rules...]
02/18/20 19:35:23.857974 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 19:35:23.861134 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.1434500217438 is >= interval of 0]
02/18/20 19:35:23.864225 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification over FCM]
02/18/20 19:35:24.206269 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 FCM push message returned a 200 with body {"multicast_id":124880963627186047,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050924001816"}]}]
02/18/20 19:35:24.213628 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 19:35:24.218049 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.8100299835205 is >= interval of 0]
02/18/20 19:35:24.222259 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification over FCM]
02/18/20 19:35:24.520352 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 FCM push message returned a 200 with body {"multicast_id":576857173489908476,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050924331722"}]}]
02/18/20 19:35:24.527694 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 19:35:24.532023 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Monitor 2 event: should send out as  14.5680499076843 is >= interval of ]
02/18/20 19:35:24.536254 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Sending event_start notification for EID:969 over MQTT]
02/18/20 19:35:26.541968 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 969 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/35/04"]
02/18/20 19:35:26.557511 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:26.815028 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:35:26.823664 zmeventnotification[29479].INF [main:833] [PARENT: New event 970 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 19:35:26.827781 zmeventnotification[29479].INF [main:833] [PARENT: New event 971 reported for Monitor:1 (Name:vhod) Motion All]
02/18/20 19:35:26.831660 zmeventnotification[29479].INF [main:833] [PARENT: There are 2 new Events to process]
02/18/20 19:35:26.843989 zmeventnotification[776].INF [main:833] [PARENT: Forked process:776 to handle alarm eid:970]
02/18/20 19:35:26.844994 zmeventnotification[777].INF [main:833] [PARENT: Forked process:777 to handle alarm eid:971]
02/18/20 19:35:26.851123 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 970 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/19/35/21"]
02/18/20 19:35:26.853758 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 971 1 "vhod" "Motion All" "/var/cache/zoneminder/events/1/20/02/18/19/35/22"]
02/18/20 19:35:26.860552 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 hook start returned with text: json:[] exit:127]
02/18/20 19:35:26.860566 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 hook start returned with text: json:[] exit:127]
02/18/20 19:35:28.562695 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:28.864504 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Matching alarm to connection rules...]
02/18/20 19:35:28.867194 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Matching alarm to connection rules...]
02/18/20 19:35:28.870862 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 19:35:28.873607 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 19:35:28.876288 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Monitor 1 event: should send out as  1214.11413002014 is >= interval of 0]
02/18/20 19:35:28.879117 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Monitor 2 event: should send out as  4.1358699798584 is >= interval of 0]
02/18/20 19:35:28.880035 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Sending event_start notification over FCM]
02/18/20 19:35:28.882894 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Sending event_start notification over FCM]
02/18/20 19:35:29.169700 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 FCM push message returned a 200 with body {"multicast_id":5299143482154101306,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050928978487"}]}]
02/18/20 19:35:29.174659 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 19:35:29.178960 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Monitor 1 event: should send out as  1214.75686001778 is >= interval of 0]
02/18/20 19:35:29.183506 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Sending event_start notification over FCM]
02/18/20 19:35:29.491642 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 FCM push message returned a 200 with body {"multicast_id":3669926665962556494,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050929305731"}]}]
02/18/20 19:35:29.498416 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 19:35:29.502783 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Monitor 1 event: should send out as  1214.45338010788 is >= interval of ]
02/18/20 19:35:29.507445 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Sending event_start notification for EID:971 over MQTT]
02/18/20 19:35:29.512273 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Event 971 for Monitor 1 has finished]
02/18/20 19:35:29.534629 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 FCM push message returned a 200 with body {"multicast_id":1651045230450611175,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050929399747"}]}]
02/18/20 19:35:29.539563 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 19:35:29.544608 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Monitor 2 event: should send out as  4.77806997299194 is >= interval of 0]
02/18/20 19:35:29.549179 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Sending event_start notification over FCM]
02/18/20 19:35:29.853540 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 FCM push message returned a 200 with body {"multicast_id":9146880971088175738,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050929664122"}]}]
02/18/20 19:35:29.859792 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 19:35:29.863143 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Monitor 2 event: should send out as  4.4640998840332 is >= interval of ]
02/18/20 19:35:29.864230 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Sending event_start notification for EID:970 over MQTT]
02/18/20 19:35:29.867383 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Event 970 for Monitor 2 has finished]
02/18/20 19:35:31.517816 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 971 1 "vhod" "Motion: All" "/var/cache/zoneminder/events/1/20/02/18/19/35/22"]
02/18/20 19:35:31.523460 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:31.814500 zmeventnotification[29479].INF [main:833] [PARENT: There are 3 active child forks...]
02/18/20 19:35:31.818389 zmeventnotification[29479].INF [main:833] [PARENT: New event 972 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 19:35:31.821559 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 19:35:31.827346 zmeventnotification[797].INF [main:833] [PARENT: Forked process:797 to handle alarm eid:972]
02/18/20 19:35:31.831255 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 972 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/19/35/29"]
02/18/20 19:35:31.836272 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 hook start returned with text: json:[] exit:127]
02/18/20 19:35:31.871146 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 970 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/35/21"]
02/18/20 19:35:31.876564 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:32.569485 zmeventnotification[766].INF [main:833] [|----> FORK:Terasa (2), eid:969 Ending process:766 to handle alarms]
02/18/20 19:35:33.526866 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:33.839676 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Matching alarm to connection rules...]
02/18/20 19:35:33.843022 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 19:35:33.844271 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Monitor 2 event: should send out as  4.11719989776611 is >= interval of 0]
02/18/20 19:35:33.847536 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Sending event_start notification over FCM]
02/18/20 19:35:33.881040 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:34.132758 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 FCM push message returned a 200 with body {"multicast_id":5079611195175915636,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050933955234"}]}]
02/18/20 19:35:34.136162 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 19:35:34.139449 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Monitor 2 event: should send out as  4.45115995407104 is >= interval of 0]
02/18/20 19:35:34.142698 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Sending event_start notification over FCM]
02/18/20 19:35:34.418423 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 FCM push message returned a 200 with body {"multicast_id":7910801054269544798,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050934233605"}]}]
02/18/20 19:35:34.422946 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 19:35:34.427225 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Monitor 2 event: should send out as  4.13583993911743 is >= interval of ]
02/18/20 19:35:34.431566 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Sending event_start notification for EID:972 over MQTT]
02/18/20 19:35:36.816943 zmeventnotification[29479].INF [main:833] [PARENT: There are 3 active child forks...]
02/18/20 19:35:36.825747 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:37.531299 zmeventnotification[777].INF [main:833] [|----> FORK:vhod (1), eid:971 Ending process:777 to handle alarms]
02/18/20 19:35:37.885415 zmeventnotification[776].INF [main:833] [|----> FORK:Terasa (2), eid:970 Ending process:776 to handle alarms]
02/18/20 19:35:38.437150 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Event 972 for Monitor 2 has finished]
02/18/20 19:35:40.444496 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 972 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/35/29"]
02/18/20 19:35:40.452391 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 hook end returned with text:  json:[] exit:127]
02/18/20 19:35:41.814709 zmeventnotification[29479].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 19:35:41.819798 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:42.456115 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 19:35:46.461606 zmeventnotification[797].INF [main:833] [|----> FORK:Terasa (2), eid:972 Ending process:797 to handle alarms]
02/18/20 19:35:46.811728 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 19:35:46.815434 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 19:35:51.813541 zmeventnotification[29479].INF [main:833] [PARENT: There are 0 active child forks...]
Docker zmesdetect_m1.log, time is 19:52 not at me:

Code: Select all

02/18/20 17:59:09 zmesdetect_m1[29788] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:138 [---------| app version: 5.7.4 |------------]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:229 [secret filename: /etc/zm/secrets.ini]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:203 [Secret token found in config: !PLATEREC_ALPR_KEY]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:254 [allowing self-signed certs to work...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:273 [[monitor-1] overrides key:models with value:yolo]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:273 [[monitor-1] overrides key:yolo_type with value:tiny]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [config] is '/var/lib/zmeventnotification/models/yolov3/yolov3.cfg' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [weights] is '/var/lib/zmeventnotification/models/yolov3/yolov3.weights' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [labels] is '/var/lib/zmeventnotification/models/yolov3/yolov3_classes.txt' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_config] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.cfg' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_weights] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.weights' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [tiny_labels] is '/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.txt' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [known_images_path] is '/var/lib/zmeventnotification/known_faces' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:304 [key [unknown_images_path] is '/var/lib/zmeventnotification/unknown_faces' after substitution]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:160 [Importing local classes for Yolo/Face]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:163 [Trying to download my portal snapshot]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:202 [No polygon area specfied, so adding a full image polygon:[{'name': 'full_image', 'value': [(0, 0), (1280, 0), (1280, 720), (0, 720)]}]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:204 [resizing to 1200 before analysis...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG utils.py:37 [resized polygons x=0.9375/y=0.9375: [{'name': 'full_image', 'value': [(0, 0), (1200, 0), (1200, 675), (0, 675)]}]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:222 [User ALPR if vehicle found: False]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:293 [Using model: yolo with /var/lib/zmeventnotification/images/923.jpg]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:46 [|---------- YOLO (input image: 1200w*675h, resized to: 416w*416h) ----------|]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:57 [Initializing Yolo]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:58 [config:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.cfg, weights:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.weights]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:76 [Not using CUDA backend]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:79 [YOLO initialization (loading model from disk) took: 11.869 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:91 [YOLO detection took: 69.065 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:122 [YOLO NMS filtering took: 0.14 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] INF yolo.py:140 [object:person at [149, 230, 335, 640] has a acceptable confidence:0.7402644157409668 compared to min confidence of: 0.3, adding]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:127 [intersection: polygon in process=[(149, 230), (335, 230), (335, 640), (149, 640)]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:133 [full_image intersects object:person[[(149, 230), (335, 230), (335, 640), (149, 640)]]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:476 [ALPR not in use, no need for look aheads in processing]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:493 [labels found: ['person']]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:494 [match found in /var/lib/zmeventnotification/images/923.jpg, breaking file loop...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:543 [Writing out debug bounding box image to /var/lib/zmeventnotification/images/923-bbox.jpg...]
02/18/20 17:59:11 zmesdetect_m1[29807] ERR zm_detect.py:561 [Could not write image to ZoneMinder as eventpath not present]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:566 [Removing matches to past detections]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:27 [trying to load /var/lib/zmeventnotification/images/monitor-1-data.pkl]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:92 [past detection person@[(149, 230), (335, 230), (335, 640), (149, 230)] approximately matches person@[(149, 230), (335, 230), (335, 640), (149, 230)] removing]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
root@44353fa5d618:/var/log/zm# tail -F  /var/log/zm/zmesdetect*.log
==> /var/log/zm/zmesdetect_m1.log <==
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:133 [full_image intersects object:person[[(149, 230), (335, 230), (335, 640), (149, 640)]]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:476 [ALPR not in use, no need for look aheads in processing]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:493 [labels found: ['person']]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:494 [match found in /var/lib/zmeventnotification/images/923.jpg, breaking file loop...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:543 [Writing out debug bounding box image to /var/lib/zmeventnotification/images/923-bbox.jpg...]
02/18/20 17:59:11 zmesdetect_m1[29807] ERR zm_detect.py:561 [Could not write image to ZoneMinder as eventpath not present]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:566 [Removing matches to past detections]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:27 [trying to load /var/lib/zmeventnotification/images/monitor-1-data.pkl]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:92 [past detection person@[(149, 230), (335, 230), (335, 640), (149, 230)] approximately matches person@[(149, 230), (335, 230), (335, 640), (149, 230)] removing]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
Docker zmdc.log:

Code: Select all

NSOLE DEBUG:2020-02-18,19:34:26 PARENT: After tick: TOTAL: 3,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 2, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
CONSOLE DEBUG:2020-02-18,19:34:26 PARENT: Finished processJobs()
CONSOLE INFO:2020-02-18,19:34:26 PARENT: There are 1 active child forks...
CONSOLE DEBUG:2020-02-18,19:34:26 PARENT: checkEvents() new events found=0
CONSOLE INFO:2020-02-18,19:34:26 PARENT: There are 0 new Events to process
CONSOLE DEBUG:2020-02-18,19:34:26 PARENT: ---------->Tick END<--------------
CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: ---------->Tick START<--------------
CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: After tick: TOTAL: 3,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 2, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: RAW TEXT-->active_event_delete--TYPE--2--SPLIT--965
CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: Job: Deleting active_event eid:965, mid:2
CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: Finished processJobs()
CONSOLE INFO:2020-02-18,19:34:31 PARENT: There are 0 active child forks...
CONSOLE INFO:2020-02-18,19:34:31 PARENT: New event 966 reported for Monitor:2 (Name:Terasa) Motion All

CONSOLE DEBUG:2020-02-18,19:34:31 PARENT: checkEvents() new events found=1
CONSOLE INFO:2020-02-18,19:34:31 PARENT: There are 1 new Events to process
CONSOLE INFO:2020-02-18,19:34:31 PARENT: Forked process:731 to handle alarm eid:966
CONSOLE DEBUG:2020-02-18,19:34:31 |----> FORK:Terasa (2), eid:966 Adding event path:/var/cache/zoneminder/events/2/20/02/18/19/34/28 to hook for image storage
CONSOLE INFO:2020-02-18,19:34:31 |----> FORK:Terasa (2), eid:966 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 966 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/19/34/28"
sh: 1: /mnt/Zoneminder/hook/zm_event_start.sh: not found
CONSOLE DEBUG:2020-02-18,19:34:31 |----> FORK:Terasa (2), eid:966 parse of hook: and []
CONSOLE INFO:2020-02-18,19:34:31 |----> FORK:Terasa (2), eid:966 hook start returned with text: json:[] exit:127
CONSOLE INFO:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 Matching alarm to connection rules...
CONSOLE INFO:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 Checking alarm rules for token ending in:...w4M8bOGNjL
CONSOLE INFO:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 Monitor 2 event: should send out as  19.1510200500488 is >= interval of 0
CONSOLE DEBUG:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 shouldSendEventToConn returned true, so calling sendEvent
CONSOLE DEBUG:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 isAllowedChannel: got type:event_start resCode:127
CONSOLE INFO:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 Sending event_start notification over FCM
CONSOLE DEBUG:2020-02-18,19:34:33 |----> FORK:Terasa (2), eid:966 Final JSON being sent is: {"notification":{"sound":"default","body":"Motion All at 07:34 PM, 18-Feb","title":"Terasa Alarm (966)","badge":"48"},"to":"fz_lFU0xllY:APA91bGfE4n7vLXzg5jnUkq50yUgpm4YeCqho6SUiFCpFw0M1F7Kfmg9vRd60fRMOxbvcbMgJkK54Zwc-2Tm8WJqjBYodsQab7hVAfRJ8TiOylQd9g9PubeIWGBVsr9PYQw4M8bOGNjL","mutable_content":true,"data":{"myMessageId":1,"mid":"2","image_url_jpg":"zm/index.php?view=image&eid=966&fid=alarm&width=600&username=admin&password=xxx&username=admin&password=xxx
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 FCM push message returned a 200 with body {"multicast_id":1997091925883460812,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050873972359"}]}
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 child finished writing to parent
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Checking alarm rules for token ending in:...5sxQF8jrbQ
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Monitor 2 event: should send out as  19.8910100460052 is >= interval of 0
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 shouldSendEventToConn returned true, so calling sendEvent
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 isAllowedChannel: got type:event_start resCode:127
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Sending event_start notification over FCM
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Final JSON being sent is: {"notification":{"title":"Terasa Alarm (966)","badge":"48","sound":"default","body":"Motion All at 07:34 PM, 18-Feb"},"to":"c9pIYrUVwcM:APA91bHbp4UYwigO3B8HkH3KqjzGvv9Y3EfT_w3F9oKXFKstgVvZllVe2I6wLUAoJaZqeqFYRWBuhu6aYGucR5U75iuRbS9bKGoKXbUzOCdc3miqKEUd2HWu-CGEA8F2jv5sxQF8jrbQ","mutable_content":true,"data":{"image_url_jpg":"https:///zm/index.php?view=image&eid=966&fid=alarm&width=600&username=admin&password=xxx&username=admin&password=xxx
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 FCM push message returned a 200 with body {"multicast_id":3761071032499497145,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582050874287975"}]}
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 child finished writing to parent
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Checking alarm rules for MQTT 192.168.1.27
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Monitor 2 event: should send out as  19.4926800727844 is >= interval of 
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 shouldSendEventToConn returned true, so calling sendEvent
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 isAllowedChannel: got type:event_start resCode:127
CONSOLE INFO:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 Sending event_start notification for EID:966 over MQTT
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 requesting MQTT Publishing Job for EID:966
CONSOLE DEBUG:2020-02-18,19:34:34 |----> FORK:Terasa (2), eid:966 child finished writing to parent
CONSOLE DEBUG:2020-02-18,19:34:38 |----> FORK:Terasa (2), eid:966 For 2 (Terasa), SHM says: state=2, eid=967
CONSOLE INFO:2020-02-18,19:34:38 |----> FORK:Terasa (2), eid:966 Event 966 for Monitor 2 has finished
CONSOLE DEBUG:2020-02-18,19:34:38 |----> FORK:Terasa (2), eid:966 Event end object is: state=>pending with cause=>Motion: All
CONSOLE DEBUG:2020-02-18,19:34:40 |----> FORK:Terasa (2), eid:966 Adding event path:/var/cache/zoneminder/events/2/20/02/18/19/34/28 to hook for image storage
CONSOLE INFO:2020-02-18,19:34:40 |----> FORK:Terasa (2), eid:966 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 966 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/34/28"
sh: 1: /mnt/Zoneminder/hook/zm_event_end.sh: not found
zms_e895.log and zms_e887.log

Code: Select all

root@44353fa5d618:/var/log/zm# cat zms_e895.log
02/18/20 17:24:35.344861 zms_e895[27004].INF-zm_ffmpeg.cpp/84 [Not enabling ffmpeg logs, as LOG_FFMPEG and/or LOG_DEBUG is disabled in options, or this monitor is not part of your debug targets]
02/18/20 17:24:36.237353 zms_e895[27004].ERR-zm_eventstream.cpp/762 [Unable to send raw frame 9: Broken pipe]
root@44353fa5d618:/var/log/zm# cat zms_e887.log
02/18/20 17:19:44.601981 zms_e887[26711].INF-zm_ffmpeg.cpp/84 [Not enabling ffmpeg logs, as LOG_FFMPEG and/or LOG_DEBUG is disabled in options, or this monitor is not part of your debug targets]
02/18/20 17:19:44.632235 zms_e887[26711].ERR-zm_eventstream.cpp/762 [Unable to send raw frame 1: Broken pipe]
02/18/20 17:19:44.894501 zms_e887[26717].INF-zm_ffmpeg.cpp/84 [Not enabling ffmpeg logs, as LOG_FFMPEG and/or LOG_DEBUG is disabled in options, or this monitor is not part of your debug targets]
So this are dokcer log, now you need host logs?
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

Im still confused.

1. Make sure you don't do ANYTHING in the host. Everything inside docker.

Your docker logs for zmeventnotification show:

Code: Select all

02/18/20 19:34:58.559870 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 968 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/19/34/46"]
02/18/20 19:34:58.574703 zmeventnotification[743].INF [main:833] [|----> FORK:Terasa (2), eid:968 hook end returned with text:  json:[] exit:127]
But your docker logs for zmesdetect_m1.log, show a time of 17:59:09

I need logs for zmesdetect_m1.log INSIDE the docker at the same time as the zmeventnotification logs.
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

zmeventnotification logs:

Code: Select all

2/18/20 20:16:41.332263 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:16:41.343943 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:16:46.331619 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:16:46.337601 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:16:51.328642 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:16:51.340509 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:16:56.332639 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:16:56.340291 zmeventnotification[981].INF [main:833] [PARENT: New event 989 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 20:16:56.344457 zmeventnotification[981].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 20:16:56.354916 zmeventnotification[1588].INF [main:833] [PARENT: Forked process:1588 to handle alarm eid:989]
02/18/20 20:16:56.361501 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 989 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/20/16/52"]
02/18/20 20:16:56.370079 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 hook start returned with text: json:[] exit:127]
02/18/20 20:16:58.374846 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Matching alarm to connection rules...]
02/18/20 20:16:58.382296 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 20:16:58.386868 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Monitor 2 event: should send out as  139.621449947357 is >= interval of 0]
02/18/20 20:16:58.391937 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Sending event_start notification over FCM]
02/18/20 20:16:58.727450 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 FCM push message returned a 200 with body {"multicast_id":3961459407558596216,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053418549123"}]}]
02/18/20 20:16:58.733709 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 20:16:58.737016 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Monitor 2 event: should send out as  139.324970006943 is >= interval of 0]
02/18/20 20:16:58.740603 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Sending event_start notification over FCM]
02/18/20 20:16:59.025136 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 FCM push message returned a 200 with body {"multicast_id":5854900414099132024,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053418850960"}]}]
02/18/20 20:16:59.032053 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 20:16:59.036044 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Monitor 2 event: should send out as  139.933490037918 is >= interval of ]
02/18/20 20:16:59.040244 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Sending event_start notification for EID:989 over MQTT]
02/18/20 20:17:01.045332 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Event 989 for Monitor 2 has finished]
02/18/20 20:17:01.336201 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:17:01.343228 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:03.057394 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 989 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/20/16/52"]
02/18/20 20:17:03.072356 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 hook end returned with text:  json:[] exit:127]
02/18/20 20:17:05.077313 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 20:17:06.332207 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:17:06.344030 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:09.084163 zmeventnotification[1588].INF [main:833] [|----> FORK:Terasa (2), eid:989 Ending process:1588 to handle alarms]
02/18/20 20:17:11.332037 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:11.338284 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:16.332380 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:16.343802 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:21.332272 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:21.344081 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:26.332350 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:26.340304 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:31.332107 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:31.342887 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:36.331991 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:36.338618 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:41.331912 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:41.344154 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:46.332192 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:46.340236 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:51.332034 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:51.343931 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:17:56.332897 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:17:56.345637 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:01.331972 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:01.343688 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:06.332254 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:06.343664 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:11.332223 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:11.344121 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:16.332188 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:16.336289 zmeventnotification[981].INF [main:833] [PARENT: New event 990 reported for Monitor:1 (Name:vhod) Motion All]
02/18/20 20:18:16.340395 zmeventnotification[981].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 20:18:16.355306 zmeventnotification[1632].INF [main:833] [PARENT: Forked process:1632 to handle alarm eid:990]
02/18/20 20:18:16.359585 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 990 1 "vhod" "Motion All" "/var/cache/zoneminder/events/1/20/02/18/20/18/12"]
02/18/20 20:18:16.364665 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 hook start returned with text: json:[] exit:127]
02/18/20 20:18:18.368432 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Matching alarm to connection rules...]
02/18/20 20:18:18.376242 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 20:18:18.380837 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Monitor 1 event: should send out as  749.610300064087 is >= interval of 0]
02/18/20 20:18:18.385592 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Sending event_start notification over FCM]
02/18/20 20:18:18.681544 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 FCM push message returned a 200 with body {"multicast_id":8817028174329033054,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053498511687"}]}]
02/18/20 20:18:18.685971 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 20:18:18.689268 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Monitor 1 event: should send out as  749.24355006218 is >= interval of 0]
02/18/20 20:18:18.692641 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Sending event_start notification over FCM]
02/18/20 20:18:18.961258 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 FCM push message returned a 200 with body {"multicast_id":4282524046746236130,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053498787118"}]}]
02/18/20 20:18:18.964522 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 20:18:18.967593 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Monitor 1 event: should send out as  748.987689971924 is >= interval of ]
02/18/20 20:18:18.970036 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Sending event_start notification for EID:990 over MQTT]
02/18/20 20:18:20.971368 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Event 990 for Monitor 1 has finished]
02/18/20 20:18:21.336608 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:18:21.342644 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:22.975729 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 990 1 "vhod" "Motion: All" "/var/cache/zoneminder/events/1/20/02/18/20/18/12"]
02/18/20 20:18:22.990384 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 hook end returned with text:  json:[] exit:127]
02/18/20 20:18:24.995827 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 20:18:26.332169 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:18:26.344143 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:29.003862 zmeventnotification[1632].INF [main:833] [|----> FORK:vhod (1), eid:990 Ending process:1632 to handle alarms]
02/18/20 20:18:31.331994 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:31.339100 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:36.332413 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:36.340328 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:41.332098 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:41.343248 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:46.332019 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:46.339643 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:51.332084 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:51.344122 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:18:56.332230 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:18:56.343518 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:01.331614 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:01.341847 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:06.331873 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:06.343550 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:11.332081 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:11.343144 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:16.332455 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:16.344414 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:21.327560 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:21.334103 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:26.332256 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:26.343972 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:31.332283 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:31.344136 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:36.332633 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:36.338088 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:41.332027 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:41.343510 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:46.332337 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:46.344194 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:51.332220 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:51.339253 zmeventnotification[981].INF [main:833] [PARENT: Total event client connections: 3]
02/18/20 20:19:51.342897 zmeventnotification[981].INF [main:833] [PARENT: Reloading Monitors...]
02/18/20 20:19:51.347236 zmeventnotification[981].INF [main:833] [PARENT: Re-loading monitors, emptying needsReload() list]
02/18/20 20:19:51.354107 zmeventnotification[981].INF [main:833] [PARENT: ESCONTROL_INTERFACE is disabled. Not saving control data]
02/18/20 20:19:51.358667 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:19:56.332212 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:19:56.343535 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:01.331821 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:01.342143 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:06.332300 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:06.344202 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:11.332289 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:11.343835 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:16.331675 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:16.337713 zmeventnotification[981].INF [main:833] [PARENT: New event 991 reported for Monitor:2 (Name:Terasa) Motion All]
02/18/20 20:20:16.341091 zmeventnotification[981].INF [main:833] [PARENT: There are 1 new Events to process]
02/18/20 20:20:16.355455 zmeventnotification[1704].INF [main:833] [PARENT: Forked process:1704 to handle alarm eid:991]
02/18/20 20:20:16.359486 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Invoking hook on event start:/mnt/Zoneminder/hook/zm_event_start.sh 991 2 "Terasa" "Motion All" "/var/cache/zoneminder/events/2/20/02/18/20/20/13"]
02/18/20 20:20:16.364455 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 hook start returned with text: json:[] exit:127]
02/18/20 20:20:18.368556 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Matching alarm to connection rules...]
02/18/20 20:20:18.376238 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Checking alarm rules for token ending in:...w4M8bOGNjL]
02/18/20 20:20:18.380948 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Monitor 2 event: should send out as  199.608530044556 is >= interval of 0]
02/18/20 20:20:18.385527 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Sending event_start notification over FCM]
02/18/20 20:20:18.720608 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 FCM push message returned a 200 with body {"multicast_id":8908509456783419284,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053618528188"}]}]
02/18/20 20:20:18.727029 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Checking alarm rules for token ending in:...5sxQF8jrbQ]
02/18/20 20:20:18.729629 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Monitor 2 event: should send out as  199.259660005569 is >= interval of 0]
02/18/20 20:20:18.738496 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Sending event_start notification over FCM]
02/18/20 20:20:19.099237 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 FCM push message returned a 200 with body {"multicast_id":1052844996081304107,"success":1,"failure":0,"canonical_ids":0,"results":[{"message_id":"1582053618832765"}]}]
02/18/20 20:20:19.106933 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Checking alarm rules for MQTT 192.168.1.27]
02/18/20 20:20:19.111070 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Monitor 2 event: should send out as  199.96005988121 is >= interval of ]
02/18/20 20:20:19.115757 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Sending event_start notification for EID:991 over MQTT]
02/18/20 20:20:21.120804 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Event 991 for Monitor 2 has finished]
02/18/20 20:20:21.335760 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:20:21.342741 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:23.124499 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Invoking hook on event end:/mnt/Zoneminder/hook/zm_event_end.sh 991 2 "Terasa" "Motion: All" "/var/cache/zoneminder/events/2/20/02/18/20/20/13"]
02/18/20 20:20:23.132322 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 hook end returned with text:  json:[] exit:127]
02/18/20 20:20:25.135949 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
02/18/20 20:20:26.332083 zmeventnotification[981].INF [main:833] [PARENT: There are 1 active child forks...]
02/18/20 20:20:26.339982 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:29.144294 zmeventnotification[1704].INF [main:833] [|----> FORK:Terasa (2), eid:991 Ending process:1704 to handle alarms]
02/18/20 20:20:31.332933 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:31.340729 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:36.332165 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:36.337068 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:41.332114 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:41.336648 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:46.332102 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:46.344307 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:51.332276 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:51.344060 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:20:56.332444 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:20:56.337678 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:01.332291 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:01.344086 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:06.332253 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:06.343913 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:11.332033 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:11.343787 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:16.332378 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:16.349238 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:21.332513 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:21.344234 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:26.332034 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:26.344086 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:31.332288 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:31.340204 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:36.332619 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:36.345554 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:41.332224 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:41.343496 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:46.331807 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:46.335693 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:51.332214 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:51.340129 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:21:56.331615 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:21:56.342031 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:01.327038 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:01.336942 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:06.332311 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:06.344405 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:11.332089 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:11.339184 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:16.332216 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:16.343847 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:21.332218 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:21.343872 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:26.331744 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:26.345184 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:31.331819 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:31.338542 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:36.331606 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:36.335041 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:41.331828 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:41.338970 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:46.331641 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:46.342014 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:51.332291 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:51.340209 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:22:56.332042 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:22:56.338854 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:01.332277 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:01.343453 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:06.332126 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:06.340051 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:11.332026 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:11.343222 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:16.332375 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:16.343983 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:21.332298 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:21.344111 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:26.332058 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:26.343035 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:31.332173 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:31.344666 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:36.332532 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:36.340444 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:41.331603 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:41.335862 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:46.332323 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:46.340266 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:51.332138 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:51.343852 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:23:56.328937 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:23:56.336552 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
02/18/20 20:24:01.332091 zmeventnotification[981].INF [main:833] [PARENT: There are 0 active child forks...]
02/18/20 20:24:01.344056 zmeventnotification[981].INF [main:833] [PARENT: There are 0 new Events to process]
and no new logs in zmesdetect_m1.log, because this log created by my manual trigger the script! And no new log will be created, until I trigger manual the script! And as you can is in zmeventnotification log, there is all happenig just zm_detect wont trigger.
zmesdetect_m1.log

Code: Select all

02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:222 [User ALPR if vehicle found: False]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:293 [Using model: yolo with /var/lib/zmeventnotification/images/923.jpg]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:46 [|---------- YOLO (input image: 1200w*675h, resized to: 416w*416h) ----------|]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:57 [Initializing Yolo]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:58 [config:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.cfg, weights:/var/lib/zmeventnotification/models/tinyyolo/yolov3-tiny.weights]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:76 [Not using CUDA backend]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:79 [YOLO initialization (loading model from disk) took: 11.869 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:91 [YOLO detection took: 69.065 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG yolo.py:122 [YOLO NMS filtering took: 0.14 milliseconds]
02/18/20 17:59:11 zmesdetect_m1[29807] INF yolo.py:140 [object:person at [149, 230, 335, 640] has a acceptable confidence:0.7402644157409668 compared to min confidence of: 0.3, adding]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:127 [intersection: polygon in process=[(149, 230), (335, 230), (335, 640), (149, 640)]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:133 [full_image intersects object:person[[(149, 230), (335, 230), (335, 640), (149, 640)]]]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:476 [ALPR not in use, no need for look aheads in processing]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:493 [labels found: ['person']]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:494 [match found in /var/lib/zmeventnotification/images/923.jpg, breaking file loop...]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:543 [Writing out debug bounding box image to /var/lib/zmeventnotification/images/923-bbox.jpg...]
02/18/20 17:59:11 zmesdetect_m1[29807] ERR zm_detect.py:561 [Could not write image to ZoneMinder as eventpath not present]
02/18/20 17:59:11 zmesdetect_m1[29807] INF zm_detect.py:566 [Removing matches to past detections]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:27 [trying to load /var/lib/zmeventnotification/images/monitor-1-data.pkl]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG image_manip.py:92 [past detection person@[(149, 230), (335, 230), (335, 640), (149, 230)] approximately matches person@[(149, 230), (335, 230), (335, 640), (149, 230)] removing]
02/18/20 17:59:11 zmesdetect_m1[29807] DBG zm_detect.py:569 [Saving detections for monitor 1 for future match]
Something is blocking the script to be run automatic?!

I edited objectconfig.ini, secrets.ini, zmeventnoti....ini in host. Should I edit this in docker?
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

I edited objectconfig.ini, secrets.ini, zmeventnoti....ini in host. Should I edit this in docker?
1. If you have zone minder running in a docker, why are you running it in the host as well?
2. Docker is an isolated environment. EVERYTHING needs to be done inside the docker. NOTHING in host.
3. Please get your docker fundamentals right with tutorials.
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

But the files are the same in docker /etc/zm as in host, so it should be working?!
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

Dlandon's docker image does not map host files to docker files. It creates them on their own. The entire idea for his docker is to get ZM inside the docker and make it easy to install, so you don't need ZM in the host.
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

Install in docker withou this?:

Code: Select all

 -v "/mnt/Zoneminder":"/config":rw \
-v "/mnt/Zoneminder/data":"/var/cache/zoneminder":rw \
Installed without this volume, and detect is working automatic now!
THANK YOU FOR HELPING!

Do you have donation button?
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

No, that is not what I said. I think you have a fundamental misunderstanding of how docker works.
What you have done now will not keep any of your events persistent after the docker image stops/restarts

1. Don't run ZM in host and docker. You can, but in your case, given your unfamiliarity with docker, you are messing things up
2. /etc/zm in docker container is _not_ the same as /etc/zm/ in host, unless you specifically map it during docker run, which you have not
3. If you want to test dlandon's docker, don't remove the mount points. Just make sure all your edits in /etc/zm are done INSIDE the docker
warcanoid wrote: Tue Feb 18, 2020 9:20 pm Install in docker withou this?:

Code: Select all

 -v "/mnt/Zoneminder":"/config":rw \
-v "/mnt/Zoneminder/data":"/var/cache/zoneminder":rw \
Installed without this volume, and detect is working automatic now!
THANK YOU FOR HELPING!

Do you have donation button?
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

Reinstall with volumes, work perfect! So first time I messed up the host😃 Thank you again!
If I can ask here, object detection at night, any way to improve/make better at night?
At day light it is perfect, found even people in car. Much better than tensorflow in Home assistant
User avatar
asker
Posts: 1553
Joined: Sun Mar 01, 2015 12:12 pm

Re: Problem staring zm_detetct.py

Post by asker »

warcanoid wrote: Thu Feb 20, 2020 12:27 pm Reinstall with volumes, work perfect! So first time I messed up the host😃 Thank you again!
If I can ask here, object detection at night, any way to improve/make better at night?
At day light it is perfect, found even people in car. Much better than tensorflow in Home assistant
Yes, you can retrain your weights to include night images and label them.
I won't be able to help you further on this, but google for "yolov3 retrain" and there are several tutorials.
I no longer work on zmNinja, zmeventnotification, pyzm or mlapi. I may respond on occasion based on my available time/interest.

Please read before posting:
How to set up logging properly
How to troubleshoot and report - ES
How to troubleshoot and report - zmNinja
ES docs
zmNinja docs
warcanoid
Posts: 35
Joined: Mon Feb 17, 2020 3:22 pm

Re: Problem staring zm_detetct.py

Post by warcanoid »

Thanks
dlandon
Posts: 17
Joined: Fri Oct 12, 2018 12:49 am

Re: Problem staring zm_detetct.py

Post by dlandon »

The docker will copy /config/zm.conf to /etc/zm/zm/conf. This is how you make configuration changes. If you have a /config/zm.conf be sure it is correctly formatted. There is a default file in /config/conf/ and a read.me on how to make changes.

If that file is not found, the defaut zm.conf is left in the docker image. I can't imagine the file is not there.
Post Reply