Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

Eventserver only triggering for Monitor #1 #420

Closed
tibmeister opened this issue Sep 8, 2022 · 12 comments
Closed

Eventserver only triggering for Monitor #1 #420

tibmeister opened this issue Sep 8, 2022 · 12 comments

Comments

@tibmeister
Copy link

Event Server version
6.1.28

Hooks version (if you are using Object Detection)
app:6.1.28, pyzm:0.3.56

Are you using MLAPI? (Y/N)
No

The version of ZoneMinder you are using:
1.37.21

What is the nature of your issue
Only alarms on Monitor 1 are being seen by zmeventserver

Details
I have 6 cameras and using MQTT in ES to get notified when a monitor is in alarm. I can only seem to get events for Monitor #1, either when forcing the alarm or otherwise. I've reviewed the ini's and there's no skipped monitors anywhere enabled, so really confused. The logs (in debug mode) just don't show any event for anything but Monitor #1.
Default rules and tokens, only modification is the enablement of the MQTT using the secrets.ini and appropriate changes in the main ini. Also disabled FCM, since it's not working at the moment anyway.

Debug Logs (if applicable)

9/07/2022 23:09:17.045316 zmeventnotification[30620].INF [main:338] [Running on WebSocket library version:0.004]
INF:2022-09-07,23:09:18 PARENT: using config file: /etc/zm/zmeventnotification.ini
09/07/2022 23:09:18.273846 zmeventnotification[30620].INF [main:1048] [PARENT: using config file: /etc/zm/zmeventnotification.ini]
INF:2022-09-07,23:09:18 PARENT: using secrets file: /etc/zm/secrets.ini
09/07/2022 23:09:18.280652 zmeventnotification[30620].INF [main:1048] [PARENT: using secrets file: /etc/zm/secrets.ini]
DBG-2:2022-09-07,23:09:18 PARENT: Got secret token !ZMES_PICTURE_URL
DBG-2:2022-09-07,23:09:18 PARENT: Got secret token !ZM_USER
DBG-2:2022-09-07,23:09:18 PARENT: Got secret token !ZM_PASSWORD
DBG-2:2022-09-07,23:09:18 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
DBG-2:2022-09-07,23:09:18 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'

Configuration (read /etc/zm/zmeventnotification.ini):

Secrets file.......................... /etc/zm/secrets.ini
Base data path........................ /var/lib/zmeventnotification
Restart interval (secs)............... 0

Use admin interface .................. no
Admin interface password.............. (undefined)
Admin interface persistence file ..... /var/lib/zmeventnotification/misc/escontrol_interface.dat

Port ................................. 9000
Address .............................. [::]
Event check interval ................. 1
Monitor reload interval .............. 300
Skipped monitors...................... (undefined)

Auth enabled ......................... yes
Auth timeout ......................... 20

Use API Push.......................... no
API Push Script....................... (undefined)

Use FCM .............................. no
Use FCM V1 APIs....................... yes
FCM Date Format....................... %I:%M %p, %d-%b
Only show latest FCMv1 message........ no
Android FCM push priority............. high
Android FCM push ttl.................. (undefined)
Log FCM message ID.................... NONE
Log RAW FCM Messages...................no
FCM V1 URL............................ https://us-central1-ninja-1105.cloudfunctions.net/send_push
FCM V1 Key.............................default
 
Token file ........................... /var/lib/zmeventnotification/push/tokens.txt

Use MQTT ............................. yes
MQTT Server .......................... 10.27.200.5
MQTT Topic ........................... zoneminder
MQTT Username ........................ jody
MQTT Password ........................ (defined)
MQTT Retain .......................... no
MQTT Tick Interval ................... 15
MQTT TLS CA ........................ (undefined)
MQTT TLS Cert ........................ (undefined)
MQTT TLS Key ........................ (undefined)
MQTT TLS Insecure ........................ no

SSL enabled .......................... yes
SSL cert file ........................ /etc/letsencrypt/live/tiberiansun.us/cert.pem
SSL key file ......................... /etc/letsencrypt/live/tiberiansun.us/privkey.pem

Verbose .............................. yes
ES Debug level.........................4
Read alarm cause ..................... yes
Tag alarm event id ................... yes
Use custom notification sound ........ no
Send event start notification..........yes
Send event end notification............yes
Monitor rules JSON file................(undefined)

Use Hooks............................. yes
Max Parallel Hooks.................... 0
Hook Script on Event Start ........... '/var/lib/zmeventnotification/bin/zm_event_start.sh'
User Script on Event Start.............(undefined)
Hook Script on Event End.............. '/var/lib/zmeventnotification/bin/zm_event_end.sh'
User Script on Event End...............(undefined)
Hook Skipped monitors................. (undefined)

Notify on Event Start (hook success).. all
Notify on Event Start (hook fail)..... none
Notify on Event End (hook success).... fcm,web,api
Notify on Event End (hook fail)....... none
Notify End only if Start success...... yes

Use Hook Description.................. yes
Keep frame match type................. yes
Store Frame in ZM......................yes

Picture URL .......................... https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=EVENTID&fid=objdetect&width=800
Include picture....................... yes
Picture username ..................... admin
Picture password ..................... (defined)

INF:2022-09-07,23:09:25 PARENT: FCM disabled.
09/07/2022 23:09:25.836118 zmeventnotification[30620].INF [main:1048] [PARENT: FCM disabled.]
INF:2022-09-07,23:09:26 PARENT: MQTT Enabled
09/07/2022 23:09:26.029358 zmeventnotification[30620].INF [main:1048] [PARENT: MQTT Enabled]
INF:2022-09-07,23:09:26 PARENT: |------- Starting ES version: 6.1.28 ---------|
09/07/2022 23:09:26.133310 zmeventnotification[30620].INF [main:1048] [PARENT: |------- Starting ES version: 6.1.28 ---------|]
DBG-2:2022-09-07,23:09:26 PARENT: Started with: perl:/usr/bin/perl and command:/usr/bin/zmeventnotification.pl
Can't ignore signal CHLD, forcing to default.
DBG-1:2022-09-07,23:09:26 PARENT: ES invoked manually. Will handle restarts ourselves
DBG-2:2022-09-07,23:09:26 PARENT: Parent<--Child pipe ready
INF:2022-09-07,23:09:26 PARENT: Event Notification daemon v 6.1.28 starting

09/07/2022 23:09:26.279857 zmeventnotification[30620].INF [main:1048] [PARENT: Event Notification daemon v 6.1.28 starting]
INF:2022-09-07,23:09:26 PARENT: Initializing MQTT with auth connection...
09/07/2022 23:09:26.280033 zmeventnotification[30620].INF [main:1048] [PARENT: Initializing MQTT with auth connection...]
DBG-1:2022-09-07,23:09:26 PARENT: Intialized MQTT with auth
DBG-1:2022-09-07,23:09:26 PARENT: Total event client connections: 1

DBG-1:2022-09-07,23:09:26 PARENT: -->checkNewEvents: Connection 1: ID-> IP->(none) Token->:... Plat: Push:
INF:2022-09-07,23:09:26 PARENT: Re-loading monitors
09/07/2022 23:09:26.280508 zmeventnotification[30620].INF [main:1048] [PARENT: Re-loading monitors]
DBG-1:2022-09-07,23:09:26 PARENT: Loading Driveway (MUS)
DBG-2:2022-09-07,23:09:26 PARENT: ESCONTROL_INTERFACE is disabled. Not saving control data
DBG-2:2022-09-07,23:09:26 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:26 PARENT: About to start listening to socket
INF:2022-09-07,23:09:26 PARENT: Secure WS(WSS) is enabled...
09/07/2022 23:09:26.507884 zmeventnotification[30620].INF [main:1048] [PARENT: Secure WS(WSS) is enabled...]
INF:2022-09-07,23:09:26 PARENT: Web Socket Event Server listening on port 9000
09/07/2022 23:09:26.508017 zmeventnotification[30620].INF [main:1048] [PARENT: Web Socket Event Server listening on port 9000]
DBG-2:2022-09-07,23:09:27 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:27 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:27 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:27 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:27 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:27 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:28 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:28 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:28 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:28 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:28 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:28 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:29 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:29 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:29 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:29 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:29 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:29 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:30 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:30 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:30 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:30 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:30 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:30 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:31 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:31 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-07,23:09:31 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:31 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:31 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:31 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:31 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:32 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:32 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:32 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:32 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:32 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:32 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:33 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:33 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:33 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:33 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:33 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:33 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:34 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:34 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:34 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:34 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:34 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:34 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:35 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:35 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:35 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:35 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:35 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:35 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:36 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:36 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:36 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:36 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:36 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:36 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:37 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:37 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:37 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:37 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:37 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:37 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:38 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:38 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:38 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:38 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:38 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:38 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:39 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:39 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:39 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:39 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:39 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:39 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:40 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:40 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:40 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:40 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:40 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:40 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:41 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:41 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:41 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:41 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:41 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:41 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:42 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:42 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:42 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:42 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:42 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:42 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:43 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:43 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:43 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:43 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:43 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:43 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:44 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:44 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:44 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:44 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:44 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:44 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:45 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:45 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:45 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:45 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:45 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:45 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:46 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:46 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:46 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:46 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:46 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:46 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:47 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:47 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-07,23:09:47 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:47 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:47 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:47 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:47 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:48 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:48 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:48 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:48 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:48 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:48 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:49 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:49 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:49 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:49 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:49 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:49 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:50 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:50 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:50 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:09:50 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:50 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:50 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-07,23:09:51 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:51 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:51 PARENT: There are 0 active child forks & 0 zm_detect processes running...
INF:2022-09-07,23:09:51 PARENT: New event 44404 reported for Monitor:1 (Name:Driveway (MUS)) Forced Web[last processed eid:]
09/07/2022 23:09:51.509943 zmeventnotification[30620].INF [main:1048] [PARENT: New event 44404 reported for Monitor:1 (Name:Driveway (MUS)) Forced Web[last processed eid:]]
DBG-2:2022-09-07,23:09:51 PARENT: checkEvents() new events found=1
DBG-2:2022-09-07,23:09:51 PARENT: There are 1 new Events to process
DBG-2:2022-09-07,23:09:51 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-07,23:09:51 PARENT: Forked process:30785 to handle alarm eid:44404
DBG-2:2022-09-07,23:09:51 |----> FORK:Driveway (MUS) (1), eid:44404 Adding event path:/nfs/events/heimdallr/1/2022-09-07/44404 to hook for image storage
DBG-1:2022-09-07,23:09:51 |----> FORK:Driveway (MUS) (1), eid:44404 Invoking hook on event start:'/var/lib/zmeventnotification/bin/zm_event_start.sh' 44404 1 "Driveway (MUS)" "Forced Web" "/nfs/events/heimdallr/1/2022-09-07/44404"
DBG-2:2022-09-07,23:09:52 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:52 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:52 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-07,23:09:52 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:52 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:52 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:52 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:52 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:53 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:53 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:53 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:53 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:53 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:53 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:53 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:54 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:54 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:54 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:54 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:54 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:54 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:54 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:55 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:55 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:55 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:55 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:55 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:55 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:55 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
[ WARN:0] global /tmp/pip-req-build-afu9cjzs/opencv/modules/dnn/src/dnn.cpp (1451) setUpNet DNN module was not built with CUDA backend; switching to CPU
DBG-2:2022-09-07,23:09:56 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:56 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:56 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:56 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:56 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:56 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:56 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:57 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:57 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:57 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:57 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:57 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:57 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:57 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:58 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:58 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:58 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:58 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:58 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:58 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:58 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:09:59 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:09:59 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:09:59 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:09:59 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:09:59 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:09:59 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:09:59 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:10:00 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:00 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:00 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:10:00 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:00 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:00 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:00 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:10:01 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:01 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:01 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:10:01 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:01 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:01 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:01 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:10:02 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:02 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:02 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-07,23:10:02 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:02 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:02 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:02 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-07,23:10:02 |----> FORK:Driveway (MUS) (1), eid:44404 parse of hook:[s] detected:car:73% bus:60%  and {"labels": ["car", "bus"], "boxes": [[446, 76, 606, 174], [426, 114, 742, 448]], "frame_id": "snapshot", "confidences": [0.7339377403259277, 0.5999669432640076], "image_dimensions": {"original": [2160, 3840], "resized": [450, 800]}}
DBG-1:2022-09-07,23:10:02 |----> FORK:Driveway (MUS) (1), eid:44404 hook start returned with text:[s] detected:car:73% bus:60%  json:{"labels": ["car", "bus"], "boxes": [[446, 76, 606, 174], [426, 114, 742, 448]], "frame_id": "snapshot", "confidences": [0.7339377403259277, 0.5999669432640076], "image_dimensions": {"original": [2160, 3840], "resized": [450, 800]}} exit:0
DBG-2:2022-09-07,23:10:03 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:03 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-07,23:10:03 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:03 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-07,23:10:03 PARENT: RAW TEXT-->active_event_update--TYPE--1--SPLIT--44404--SPLIT--Start--SPLIT--Cause--SPLIT--[s] detected:car:73% bus:60%  Forced Web--JSON--{"labels": ["car", "bus"], "boxes": [[446, 76, 606, 174], [426, 114, 742, 448]], "frame_id": "snapshot", "confidences": [0.7339377403259277, 0.5999669432640076], "image_dimensions": {"original": [2160, 3840], "resized": [450, 800]}}
DBG-2:2022-09-07,23:10:03 PARENT: Job: Update active_event eid:44404, mid:1, type:Start, field:Cause to: [s] detected:car:73% bus:60%  Forced Web--JSON--{"labels": ["car", "bus"], "boxes": [[446, 76, 606, 174], [426, 114, 742, 448]], "frame_id": "snapshot", "confidences": [0.7339377403259277, 0.5999669432640076], "image_dimensions": {"original": [2160, 3840], "resized": [450, 800]}}
DBG-2:2022-09-07,23:10:03 PARENT: RAW TEXT-->event_description--TYPE--1--SPLIT--44404--SPLIT--[s] detected:car:73% bus:60% 
DBG-2:2022-09-07,23:10:03 PARENT: Job: Update monitor 1 description:[s] detected:car:73% bus:60% 
DBG-1:2022-09-07,23:10:03 PARENT: updating Notes clause for Event:44404 with:[s] detected:car:73% bus:60%  
DBG-2:2022-09-07,23:10:03 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:03 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:03 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:03 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:03 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:04 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:04 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:04 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:04 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:04 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:04 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:04 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 rules: Checking rules for alarm caused by eid:44404, monitor:1, at: Wed Sep  7 23:10:04 2022 with cause:[s] detected:car:73% bus:60%  Forced Web
DBG-1:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 rules: No rules found for Monitor, allowing:1
DBG-1:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 Matching alarm to connection rules...
DBG-1:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 Checking alarm conditions for MQTT 10.27.200.5
DBG-1:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 Monitor 1 event: last time not found, so should send
DBG-1:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 isAllowedChannel: got type:event_start resCode:0
INF:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 Sending event_start notification for EID:44404 over MQTT
09/07/2022 23:10:04.725808 zmeventnotification[30785].INF [main:1048] [|----> FORK:Driveway (MUS) (1), eid:44404 Sending event_start notification for EID:44404 over MQTT]
DBG-2:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 requesting MQTT Publishing Job for EID:44404
DBG-2:2022-09-07,23:10:04 |----> FORK:Driveway (MUS) (1), eid:44404 child finished writing to parent
DBG-2:2022-09-07,23:10:05 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:05 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:05 PARENT: RAW TEXT-->mqtt_publish--TYPE----SPLIT--zoneminder/1--SPLIT--{"name":"Driveway (MUS):(44404) [s] detected:car:73% bus:60%  Forced Web","detection":{"confidences":[0.733937740325928,0.599966943264008],"boxes":[[446,76,606,174],[426,114,742,448]],"labels":["car","bus"],"frame_id":"snapshot","image_dimensions":{"resized":[450,800],"original":[2160,3840]}},"eventid":"44404","eventtype":"event_start","state":"alarm","hookvalue":"0","monitor":"1"}
DBG-2:2022-09-07,23:10:05 PARENT: Job: MQTT Publish on topic: zoneminder/1
DBG-2:2022-09-07,23:10:05 PARENT: Job: MQTT Publish
DBG-2:2022-09-07,23:10:05 PARENT: RAW TEXT-->timestamp--TYPE----SPLIT--1--SPLIT--1662610204.72562
DBG-2:2022-09-07,23:10:05 PARENT: Job: Update last sent timestamp of monitor:1 to 1662610204.72562 for id:
DBG-2:2022-09-07,23:10:05 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:05 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:05 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:05 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:05 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:06 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:06 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:06 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:06 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:06 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:06 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:06 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:07 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:07 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:07 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:07 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:07 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:07 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:07 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:08 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:08 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:08 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:08 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:08 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:08 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:08 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:09 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:09 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:09 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:09 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:09 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:09 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:09 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:10 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:10 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:10 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:10 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:10 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:10 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:10 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:11 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:11 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:11 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:11 PARENT: We've already worked on Monitor:1, Event:44404, not doing anything more
DBG-2:2022-09-07,23:10:11 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:11 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:11 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:12 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:12 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:12 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:12 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:12 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:12 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:12 |----> FORK:Driveway (MUS) (1), eid:44404 For 1 (Driveway (MUS)), SHM says: state=1, eid=44404
INF:2022-09-07,23:10:12 |----> FORK:Driveway (MUS) (1), eid:44404 Event 44404 for Monitor 1 has finished
09/07/2022 23:10:12.727370 zmeventnotification[30785].INF [main:1048] [|----> FORK:Driveway (MUS) (1), eid:44404 Event 44404 for Monitor 1 has finished]
DBG-2:2022-09-07,23:10:12 |----> FORK:Driveway (MUS) (1), eid:44404 Event end object is: state=>pending with cause=>[s] detected:car:73% bus:60%  Forced Web: 
DBG-2:2022-09-07,23:10:13 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:13 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:13 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:13 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:13 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:13 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:14 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:14 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:14 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:14 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:14 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:14 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 DB Event notes contain detection text, all good
DBG-2:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 Adding event path:/nfs/events/heimdallr/1/2022-09-07/44404 to hook for image storage
DBG-1:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 Invoking hook on event end:'/var/lib/zmeventnotification/bin/zm_event_end.sh' 44404 1 "Driveway (MUS)" "[s] detected:car:73% bus:60%  Forced Web: " "/nfs/events/heimdallr/1/2022-09-07/44404"
DBG-2:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 parse of hook:[s] detected:car:73% bus:60%  Forced Web:  and []
DBG-1:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 hook end returned with text:[s] detected:car:73% bus:60%  Forced Web:   json:[] exit:0
DBG-1:2022-09-07,23:10:14 |----> FORK:Driveway (MUS) (1), eid:44404 Event end: overwriting notes with [s] detected:car:73% bus:60%  Forced Web: 
DBG-2:2022-09-07,23:10:15 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-07,23:10:15 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:15 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-07,23:10:15 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-07,23:10:15 PARENT: RAW TEXT-->active_event_update--TYPE--1--SPLIT--44404--SPLIT--Start--SPLIT--Cause--SPLIT--[s] detected:car:73% bus:60%  Forced Web:  [s] detected:car:73% bus:60%  Forced Web: --JSON--[]
DBG-2:2022-09-07,23:10:15 PARENT: Job: Update active_event eid:44404, mid:1, type:Start, field:Cause to: [s] detected:car:73% bus:60%  Forced Web:  [s] detected:car:73% bus:60%  Forced Web: --JSON--[]
DBG-2:2022-09-07,23:10:15 PARENT: RAW TEXT-->event_description--TYPE--1--SPLIT--44404--SPLIT--[s] detected:car:73% bus:60%  Forced Web: 
DBG-2:2022-09-07,23:10:15 PARENT: Job: Update monitor 1 description:[s] detected:car:73% bus:60%  Forced Web: 
DBG-1:2022-09-07,23:10:15 PARENT: updating Notes clause for Event:44404 with:[s] detected:car:73% bus:60%  Forced Web:  
DBG-2:2022-09-07,23:10:15 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:15 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:15 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:15 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:16 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:16 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:16 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:16 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:16 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:16 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 rules: Checking rules for alarm caused by eid:44404, monitor:1, at: Wed Sep  7 23:10:16 2022 with cause:[s] detected:car:73% bus:60%  Forced Web
DBG-1:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 rules: No rules found for Monitor, allowing:1
DBG-1:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 Matching alarm to connection rules...
DBG-2:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 isAllowedChannel: got type:event_end resCode:0
INF:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 Not sending over MQTT as notify filters are on_success:all and on_fail:none
09/07/2022 23:10:16.735825 zmeventnotification[30785].INF [main:1048] [|----> FORK:Driveway (MUS) (1), eid:44404 Not sending over MQTT as notify filters are on_success:all and on_fail:none]
DBG-2:2022-09-07,23:10:16 |----> FORK:Driveway (MUS) (1), eid:44404 child finished writing to parent
DBG-2:2022-09-07,23:10:17 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:17 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:17 PARENT: RAW TEXT-->timestamp--TYPE----SPLIT--1--SPLIT--1662610216.73569
DBG-2:2022-09-07,23:10:17 PARENT: Job: Update last sent timestamp of monitor:1 to 1662610216.73569 for id:
DBG-2:2022-09-07,23:10:17 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:17 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:17 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:17 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:18 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:18 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:18 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:18 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:18 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:18 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:19 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:19 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-07,23:10:19 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:19 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:19 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:19 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:19 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:20 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:20 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:20 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:20 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:20 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:20 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-07,23:10:20 |----> FORK:Driveway (MUS) (1), eid:44404 exiting
DBG-1:2022-09-07,23:10:20 |----> FORK:Driveway (MUS) (1), eid:44404 Ending process:30785 to handle alarms
DBG-2:2022-09-07,23:10:21 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:21 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:21 PARENT: RAW TEXT-->active_event_delete--TYPE--1--SPLIT--44404
DBG-2:2022-09-07,23:10:21 PARENT: Job: Deleting active_event eid:44404, mid:1
DBG-2:2022-09-07,23:10:21 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:21 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:21 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:21 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:22 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:22 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:22 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:22 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:22 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:22 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:23 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:23 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:23 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:23 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:23 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:23 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:24 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:24 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:24 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:24 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:24 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:24 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:25 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:25 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:25 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:25 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:25 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:25 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:26 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:26 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:26 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:26 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:26 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:26 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:27 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:27 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:27 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:27 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:27 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:27 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:28 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:28 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:28 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:28 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:28 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:28 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:29 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:29 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:29 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:29 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:29 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:29 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:30 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:30 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:30 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:30 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:30 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:30 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:31 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:31 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:31 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:31 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:31 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:31 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:32 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:32 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:32 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:32 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:32 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:32 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:33 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:33 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:33 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:33 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:33 PARENT: There are 0 new Events to process
DBG-2:2022-09-07,23:10:33 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-07,23:10:34 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-07,23:10:34 PARENT: After tick: TOTAL: 1,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-07,23:10:34 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-07,23:10:34 PARENT: checkEvents() new events found=0
DBG-2:2022-09-07,23:10:34 PARENT: There are 0 new Events to process

Thanks!

@baudneo
Copy link

baudneo commented Sep 8, 2022

How long are the events on the other monitors? The ES Perl daemon scans SHM every 5 seconds. If an event is less than 5 seconds it may not be caught.

@tibmeister
Copy link
Author

I did think of that and dropped the check interval to 1 second to compensate, but with these being forced alarms I was making sure they stayed for a minute or more.
After looking through some code, I did notice that the event server looks for monitors that are in the 'Modect','Record','Mocord' function, which in the UI they show as being in the Mocord state. Apparently, with the new version of ZoneMinder this isn't stored in the Monitors table, because only that first monitor that I created some time back was in Mocord, the rest were in Monitor. I manually changed these to Mocord, and restarted things. They still show as Mocord in the UI, so that leads me to believe the table structures are different or something odd is going on.
Either way, ES now sees that there's an alarm, but, now I'm getting this for the other two monitors,

Not sending over MQTT as notify filters are on_success:all and on_fail:none]

I am getting this for Monitor #1

Sending event_start notification for EID:44602 over MQTT]

Not sure what I'm missing at this point.

@tibmeister
Copy link
Author

Ok, so, wanted to upgrade things anyway, so setup a fresh install on a beefy physical server using Ubuntu 22.04. Same version of Zoneminder, and installed the event server. Now, not getting any triggers from monitors and getting this in the logs:

sudo -u www-data /usr/bin/zmeventnotification.pl --debug
09/10/2022 20:38:57.747495 zmeventnotification[131882].INF [main:329] [Running on WebSocket library version:0.004]
INF:2022-09-10,20:38:57 PARENT: using config file: /etc/zm/zmeventnotification.ini
09/10/2022 20:38:57.808475 zmeventnotification[131882].INF [main:1006] [PARENT: using config file: /etc/zm/zmeventnotification.ini]
INF:2022-09-10,20:38:57 PARENT: using secrets file: /etc/zm/secrets.ini
09/10/2022 20:38:57.816940 zmeventnotification[131882].INF [main:1006] [PARENT: using secrets file: /etc/zm/secrets.ini]
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $skip_monitors in split at /usr/bin/zmeventnotification.pl line 539.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
DBG-2:2022-09-10,20:38:57 PARENT: Got secret token !ZMES_PICTURE_URL
DBG-2:2022-09-10,20:38:57 PARENT: Got secret token !ZM_USER
DBG-2:2022-09-10,20:38:57 PARENT: Got secret token !ZM_PASSWORD
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
DBG-2:2022-09-10,20:38:57 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
DBG-2:2022-09-10,20:38:57 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $hook_skip_monitors in split at /usr/bin/zmeventnotification.pl line 728.

Configuration (read /etc/zm/zmeventnotification.ini):

Secrets file.......................... /etc/zm/secrets.ini
Base data path........................ /var/lib/zmeventnotification
Restart interval (secs)............... 7200

Use admin interface .................. no
Admin interface password.............. (undefined)
Admin interface persistence file ..... /var/lib/zmeventnotification/misc/escontrol_interface.dat

Port ................................. 9000
Address .............................. [::]
Event check interval ................. 5
Monitor reload interval .............. 300
Skipped monitors...................... (undefined)

Auth enabled ......................... yes
Auth timeout ......................... 20

Use API Push.......................... no
API Push Script....................... (undefined)

Use FCM .............................. no
Use FCM V1 APIs....................... yes
FCM Date Format....................... %I:%M %p, %d-%b
Only show latest FCMv1 message........ no
Android FCM push priority............. high
Android FCM push ttl.................. (undefined)
Log FCM message ID.................... NONE
Log RAW FCM Messages.................. no
FCM V1 URL............................ https://us-central1-zoneminder-ninja.cloudfunctions.net/send_push
FCM V1 Key............................ default
 
Token file ........................... /var/lib/zmeventnotification/push/tokens.txt

Use MQTT ............................. yes
MQTT Server .......................... 10.27.200.5
MQTT Topic ........................... zoneminder
MQTT Username ........................ jody
MQTT Password ........................ (defined)
MQTT Retain .......................... yes
MQTT Tick Interval ................... 15
MQTT TLS CA .......................... (undefined)
MQTT TLS Cert ........................ (undefined)
MQTT TLS Key ......................... (undefined)
MQTT TLS Insecure .................... no

SSL enabled .......................... yes
SSL cert file ........................ /etc/letsencrypt/live/tiberiansun.us/cert.pem
SSL key file ......................... /etc/letsencrypt/live/tiberiansun.us/privkey.pem

Verbose .............................. yes
ES Debug level........................ 6
Read alarm cause ..................... yes
Tag alarm event id ................... yes
Use custom notification sound ........ no
Send event start notification......... yes
Send event end notification........... yes
Monitor rules JSON file............... (undefined)

Use Hooks............................. yes
Max Parallel Hooks.................... 0
Hook Script on Event Start ........... '/var/lib/zmeventnotification/bin/zm_event_start.sh'
User Script on Event Start............ (undefined)
Hook Script on Event End.............. '/var/lib/zmeventnotification/bin/zm_event_end.sh'
User Script on Event End.............. (undefined)
Hook Skipped monitors................. (undefined)

Notify on Event Start (hook success).. all
Notify on Event Start (hook fail)..... all
Notify on Event End (hook success).... all
Notify on Event End (hook fail)....... all
Notify End only if Start success...... yes

Use Hook Description.................. yes
Keep frame match type................. yes
Store Frame in ZM..................... yes

Picture URL .......................... https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=EVENTID&fid=objdetect&width=800
Include picture....................... yes
Picture username ..................... admin
Picture password ..................... (defined)

INF:2022-09-10,20:38:57 PARENT: FCM disabled.
09/10/2022 20:38:57.924735 zmeventnotification[131882].INF [main:1006] [PARENT: FCM disabled.]
INF:2022-09-10,20:38:57 PARENT: MQTT Enabled
09/10/2022 20:38:57.927423 zmeventnotification[131882].INF [main:1006] [PARENT: MQTT Enabled]
INF:2022-09-10,20:38:57 PARENT: |------- Starting ES version: 6.1.28 ---------|
09/10/2022 20:38:57.927474 zmeventnotification[131882].INF [main:1006] [PARENT: |------- Starting ES version: 6.1.28 ---------|]
DBG-2:2022-09-10,20:38:57 PARENT: Started with: perl:/usr/bin/perl and command:/usr/bin/zmeventnotification.pl
Can't ignore signal CHLD, forcing to default.
DBG-1:2022-09-10,20:38:58 PARENT: ES invoked manually. Will handle restarts ourselves
DBG-2:2022-09-10,20:38:58 PARENT: Parent<--Child pipe ready
INF:2022-09-10,20:38:58 PARENT: Event Notification daemon v 6.1.28 starting
09/10/2022 20:38:58.059864 zmeventnotification[131882].INF [main:1006] [PARENT: Event Notification daemon v 6.1.28 starting]
INF:2022-09-10,20:38:58 PARENT: Initializing MQTT with auth connection...
09/10/2022 20:38:58.059959 zmeventnotification[131882].INF [main:1006] [PARENT: Initializing MQTT with auth connection...]
DBG-1:2022-09-10,20:38:58 PARENT: Intialized MQTT with auth
DBG-1:2022-09-10,20:38:58 PARENT: Total event client connections: 1

Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
DBG-1:2022-09-10,20:38:58 PARENT: -->checkNewEvents: Connection 1: ID-> IP->(none) Token->:... Plat: Push:
INF:2022-09-10,20:38:58 PARENT: Re-loading monitors
09/10/2022 20:38:58.060282 zmeventnotification[131882].INF [main:1006] [PARENT: Re-loading monitors]
DBG-2:2022-09-10,20:38:58 PARENT: ESCONTROL_INTERFACE is disabled. Not saving control data
DBG-2:2022-09-10,20:38:58 PARENT: checkEvents() new events found=0
DBG-2:2022-09-10,20:38:58 PARENT: About to start listening to socket
INF:2022-09-10,20:38:58 PARENT: Secure WS(WSS) is enabled...
09/10/2022 20:38:58.082002 zmeventnotification[131882].INF [main:1006] [PARENT: Secure WS(WSS) is enabled...]
INF:2022-09-10,20:38:58 PARENT: Web Socket Event Server listening on port 9000
09/10/2022 20:38:58.082068 zmeventnotification[131882].INF [main:1006] [PARENT: Web Socket Event Server listening on port 9000]
DBG-2:2022-09-10,20:39:03 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
Argument "escontrol" isn't numeric in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
DBG-2:2022-09-10,20:39:03 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-10,20:39:03 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-10,20:39:03 PARENT: checkEvents() new events found=0
DBG-2:2022-09-10,20:39:03 PARENT: There are 0 new Events to process
DBG-2:2022-09-10,20:39:03 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
^CDBG-1:2022-09-10,20:39:06 PARENT: Received request to shutdown, please wait
INF:2022-09-10,20:39:08 PARENT: Event Server Terminating
09/10/2022 20:39:08.084108 zmeventnotification[131882].INF [main:1006] [PARENT: Event Server Terminating]

It is a slightly newer version of Perl5 in Ubuntu 22.04, 5.34.0 vice 5.30.0 under Ubuntu 20.04. I'm assuming the change in Perl5 is the cause because there was some breaking changes, I don't have to use Perl anymore so don't keep track of all that.

@baudneo
Copy link

baudneo commented Sep 11, 2022

Open zmeventnotification.pl in a text editor and the top line has a -T

Remove the -T and try running it again.

@tibmeister
Copy link
Author

Looks the same to me

09/11/2022 17:13:23.686589 zmeventnotification[233576].INF [main:329] [Running on WebSocket library version:0.004]
INF:2022-09-11,17:13:23 PARENT: using config file: /etc/zm/zmeventnotification.ini
09/11/2022 17:13:23.731626 zmeventnotification[233576].INF [main:1006] [PARENT: using config file: /etc/zm/zmeventnotification.ini]
INF:2022-09-11,17:13:23 PARENT: using secrets file: /etc/zm/secrets.ini
09/11/2022 17:13:23.738757 zmeventnotification[233576].INF [main:1006] [PARENT: using secrets file: /etc/zm/secrets.ini]
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $skip_monitors in split at /usr/bin/zmeventnotification.pl line 539.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
Use of uninitialized value $es_debug_level in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 995.
DBG-2:2022-09-11,17:13:23 PARENT: Got secret token !ZMES_PICTURE_URL
DBG-2:2022-09-11,17:13:23 PARENT: Got secret token !ZM_USER
DBG-2:2022-09-11,17:13:23 PARENT: Got secret token !ZM_PASSWORD
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
DBG-2:2022-09-11,17:13:23 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
DBG-2:2022-09-11,17:13:23 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
Use of uninitialized value $final_val in substr at /usr/bin/zmeventnotification.pl line 484.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 510.
Use of uninitialized value $final_val in lc at /usr/bin/zmeventnotification.pl line 511.
Use of uninitialized value $final_val in pattern match (m//) at /usr/bin/zmeventnotification.pl line 514.
Use of uninitialized value $s in substitution (s///) at /usr/bin/zmeventnotification.pl line 976.
Use of uninitialized value $hook_skip_monitors in split at /usr/bin/zmeventnotification.pl line 728.

Configuration (read /etc/zm/zmeventnotification.ini):

Secrets file.......................... /etc/zm/secrets.ini
Base data path........................ /var/lib/zmeventnotification
Restart interval (secs)............... 7200

Use admin interface .................. no
Admin interface password.............. (undefined)
Admin interface persistence file ..... /var/lib/zmeventnotification/misc/escontrol_interface.dat

Port ................................. 9000
Address .............................. [::]
Event check interval ................. 5
Monitor reload interval .............. 300
Skipped monitors...................... (undefined)

Auth enabled ......................... yes
Auth timeout ......................... 20

Use API Push.......................... no
API Push Script....................... (undefined)

Use FCM .............................. no
Use FCM V1 APIs....................... yes
FCM Date Format....................... %I:%M %p, %d-%b
Only show latest FCMv1 message........ no
Android FCM push priority............. high
Android FCM push ttl.................. (undefined)
Log FCM message ID.................... NONE
Log RAW FCM Messages.................. no
FCM V1 URL............................ https://us-central1-zoneminder-ninja.cloudfunctions.net/send_push
FCM V1 Key............................ default
 
Token file ........................... /var/lib/zmeventnotification/push/tokens.txt

Use MQTT ............................. yes
MQTT Server .......................... 10.27.200.5
MQTT Topic ........................... zoneminder
MQTT Username ........................ jody
MQTT Password ........................ (defined)
MQTT Retain .......................... yes
MQTT Tick Interval ................... 15
MQTT TLS CA .......................... (undefined)
MQTT TLS Cert ........................ (undefined)
MQTT TLS Key ......................... (undefined)
MQTT TLS Insecure .................... no

SSL enabled .......................... yes
SSL cert file ........................ /etc/letsencrypt/live/tiberiansun.us/cert.pem
SSL key file ......................... /etc/letsencrypt/live/tiberiansun.us/privkey.pem

Verbose .............................. yes
ES Debug level........................ 6
Read alarm cause ..................... yes
Tag alarm event id ................... yes
Use custom notification sound ........ no
Send event start notification......... yes
Send event end notification........... yes
Monitor rules JSON file............... (undefined)

Use Hooks............................. yes
Max Parallel Hooks.................... 0
Hook Script on Event Start ........... '/var/lib/zmeventnotification/bin/zm_event_start.sh'
User Script on Event Start............ (undefined)
Hook Script on Event End.............. '/var/lib/zmeventnotification/bin/zm_event_end.sh'
User Script on Event End.............. (undefined)
Hook Skipped monitors................. (undefined)

Notify on Event Start (hook success).. all
Notify on Event Start (hook fail)..... all
Notify on Event End (hook success).... all
Notify on Event End (hook fail)....... all
Notify End only if Start success...... yes

Use Hook Description.................. yes
Keep frame match type................. yes
Store Frame in ZM..................... yes

Picture URL .......................... https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=EVENTID&fid=objdetect&width=800
Include picture....................... yes
Picture username ..................... admin
Picture password ..................... (defined)

INF:2022-09-11,17:13:23 PARENT: FCM disabled.
09/11/2022 17:13:23.846413 zmeventnotification[233576].INF [main:1006] [PARENT: FCM disabled.]
INF:2022-09-11,17:13:23 PARENT: MQTT Enabled
09/11/2022 17:13:23.849127 zmeventnotification[233576].INF [main:1006] [PARENT: MQTT Enabled]
INF:2022-09-11,17:13:23 PARENT: |------- Starting ES version: 6.1.28 ---------|
09/11/2022 17:13:23.849203 zmeventnotification[233576].INF [main:1006] [PARENT: |------- Starting ES version: 6.1.28 ---------|]
DBG-2:2022-09-11,17:13:23 PARENT: Started with: perl:/usr/bin/perl and command:/usr/bin/zmeventnotification.pl
Can't ignore signal CHLD, forcing to default.
DBG-1:2022-09-11,17:13:23 PARENT: ES invoked via ZMDC. Will exit when needed and have zmdc restart it
DBG-2:2022-09-11,17:13:23 PARENT: Parent<--Child pipe ready
INF:2022-09-11,17:13:23 PARENT: Event Notification daemon v 6.1.28 starting
09/11/2022 17:13:23.983132 zmeventnotification[233576].INF [main:1006] [PARENT: Event Notification daemon v 6.1.28 starting]
INF:2022-09-11,17:13:23 PARENT: Initializing MQTT with auth connection...
09/11/2022 17:13:23.983221 zmeventnotification[233576].INF [main:1006] [PARENT: Initializing MQTT with auth connection...]
DBG-1:2022-09-11,17:13:23 PARENT: Intialized MQTT with auth
DBG-1:2022-09-11,17:13:23 PARENT: Total event client connections: 1

Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
DBG-1:2022-09-11,17:13:23 PARENT: -->checkNewEvents: Connection 1: ID-> IP->(none) Token->:... Plat: Push:
INF:2022-09-11,17:13:23 PARENT: Re-loading monitors
09/11/2022 17:13:23.983525 zmeventnotification[233576].INF [main:1006] [PARENT: Re-loading monitors]
DBG-2:2022-09-11,17:13:23 PARENT: ESCONTROL_INTERFACE is disabled. Not saving control data
DBG-2:2022-09-11,17:13:23 PARENT: checkEvents() new events found=0
DBG-2:2022-09-11,17:13:23 PARENT: About to start listening to socket
INF:2022-09-11,17:13:23 PARENT: Secure WS(WSS) is enabled...
09/11/2022 17:13:23.995972 zmeventnotification[233576].INF [main:1006] [PARENT: Secure WS(WSS) is enabled...]
INF:2022-09-11,17:13:23 PARENT: Web Socket Event Server listening on port 9000
09/11/2022 17:13:23.996042 zmeventnotification[233576].INF [main:1006] [PARENT: Web Socket Event Server listening on port 9000]
DBG-2:2022-09-11,17:13:28 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
Argument "escontrol" isn't numeric in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
DBG-2:2022-09-11,17:13:28 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-11,17:13:28 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-11,17:13:28 PARENT: checkEvents() new events found=0
DBG-2:2022-09-11,17:13:28 PARENT: There are 0 new Events to process
DBG-2:2022-09-11,17:13:28 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-11,17:13:33 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
DBG-2:2022-09-11,17:13:33 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-11,17:13:33 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-11,17:13:33 PARENT: checkEvents() new events found=0
DBG-2:2022-09-11,17:13:33 PARENT: There are 0 new Events to process
DBG-2:2022-09-11,17:13:33 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-11,17:13:38 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
DBG-2:2022-09-11,17:13:38 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-11,17:13:38 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-11,17:13:38 PARENT: checkEvents() new events found=0
DBG-2:2022-09-11,17:13:38 PARENT: There are 0 new Events to process
DBG-2:2022-09-11,17:13:38 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
^CDBG-1:2022-09-11,17:13:39 PARENT: Received request to shutdown, please wait
INF:2022-09-11,17:13:43 PARENT: Event Server Terminating
09/11/2022 17:13:43.997364 zmeventnotification[233576].INF [main:1006] [PARENT: Event Server Terminating]

@baudneo
Copy link

baudneo commented Sep 12, 2022

I don't use the Perl daemon myself anymore so it's a bit hard for me to debug. Remove the "strict" line as well, I think that may help.

https://github.com/ZoneMinder/zmeventnotification/blob/master/zmeventnotification.pl#L37

@tibmeister
Copy link
Author

So that didn't do much, but removing "use warnings" got rid of all the extra messages. Unfortunately, it's still not detecting the monitors that are in alarms, I had a forced alarm on Monitor 1 the entire time and nothing.

DBG-2:2022-09-12,16:33:34 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-12,16:33:34 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-12,16:33:34 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:34 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:34 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:34 PARENT: There are 0 new Events to process
DBG-2:2022-09-12,16:33:34 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-12,16:33:39 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-12,16:33:39 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:39 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:39 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:39 PARENT: There are 0 new Events to process
DBG-2:2022-09-12,16:33:39 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-12,16:33:44 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-12,16:33:44 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:44 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:44 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:44 PARENT: There are 0 new Events to process
DBG-2:2022-09-12,16:33:44 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-12,16:33:49 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-12,16:33:49 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:49 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:49 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:49 PARENT: There are 0 new Events to process
DBG-2:2022-09-12,16:33:49 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-12,16:33:54 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-12,16:33:54 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-12,16:33:54 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:54 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:54 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:54 PARENT: There are 0 new Events to process
DBG-2:2022-09-12,16:33:54 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-12,16:33:59 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-12,16:33:59 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-12,16:33:59 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-12,16:33:59 PARENT: checkEvents() new events found=0
DBG-2:2022-09-12,16:33:59 PARENT: There are 0 new Events to process

@baudneo
Copy link

baudneo commented Sep 12, 2022

Can you print out your monitor table from DB? It's weird that you're the only person so far with the problem but it may have to do with some of the c structs being changed that the Perl daemon scans in SHM.

I'm wondering what's different about monitor 1 than all the others and the 'Monitor' table in the db should be able to show the differences.

@connortechnology
Copy link
Member

Please test latest master. I never pushed my change to use Capturing instead of Function for ZM > 1.37.13. So now I have. Give it a try see if it fixes this.

@tibmeister
Copy link
Author

I was noticing in the database that all my monitors are set to Monitor instead of MoCord and Record, then I saw the feature list in 1.37 and this one stood out to me,

 Remove Function concept, replacing with Capturing, Analysing, Record options. Allows for Motion detection without recording. Allows for OnDemand capturing.

So it appears that the Function column in the Monitors table no longer is updated or looked at. I had set them manually to the appropriate function and things started working.
@connortechnology I just updated to the latest master and set my table entries back to Monitor and things seem to be working much better. Still getting a lot of junk when running in debug mode, but not in the log.

Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 1394.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1446.
Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1342.

So as of right now, things seem to be working. I will test a bunch more over the weekend, but it looks like that change in 1.37 was the breaking item.

@tibmeister
Copy link
Author

I am still getting this though

[Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed](eid:23918 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed)

Here's the entire run debug info. I do see the publish in my MQTT server, so not sure if there's a callback missing somewhere...

Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:02 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:02 PARENT: There are 0 active child forks & 0 zm_detect processes running...
Use of uninitialized value in numeric ge (>=) at /usr/bin/zmeventnotification.pl line 1394.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1446.
INF:2022-09-16,14:59:02 PARENT: New event 23918 reported for Monitor:4 (Name:Driveway (MUS) Motion) Continuous[last processed eid:]
09/16/2022 14:59:02.386504 zmeventnotification[1102111].INF [main:1006] [PARENT: New event 23918 reported for Monitor:4 (Name:Driveway (MUS) Motion) Continuous[last processed eid:]]
DBG-2:2022-09-16,14:59:02 PARENT: checkEvents() new events found=1
DBG-2:2022-09-16,14:59:02 PARENT: There are 1 new Events to process
DBG-2:2022-09-16,14:59:02 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-16,14:59:02 PARENT: Forked process:1102145 to handle alarm eid:23918
DBG-2:2022-09-16,14:59:02 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Adding event path:/var/cache/zoneminder/events/4/2022-09-16/23918 to hook for image storage
DBG-1:2022-09-16,14:59:02 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Invoking hook on event start:'/var/lib/zmeventnotification/bin/zm_event_start.sh' 23918 4 "Driveway (MUS) Motion" "Continuous" "/var/cache/zoneminder/events/4/2022-09-16/23918"
Use of uninitialized value $txt in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1031.
DBG-2:2022-09-16,14:59:04 |----> FORK:Driveway (MUS) Motion (4), eid:23918 parse of hook: and []
Use of uninitialized value $resTxt in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 3523.
DBG-1:2022-09-16,14:59:04 |----> FORK:Driveway (MUS) Motion (4), eid:23918 hook start returned with text: json:[] exit:1
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3191.
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3193.
DBG-2:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 rules: Checking rules for alarm caused by eid:23918, monitor:4, at: Fri Sep 16 14:59:06 2022 with cause:Continuous
DBG-1:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 rules: No rules found for Monitor, allowing:4
DBG-1:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Matching alarm to connection rules...
DBG-1:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Checking alarm conditions for MQTT 10.27.200.5
ERR:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 interval not found for mid 4
09/16/2022 14:59:06.796279 zmeventnotification[1102145].ERR [main:1022] [|----> FORK:Driveway (MUS) Motion (4), eid:23918 interval not found for mid 4]
DBG-1:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Monitor 4 event: last time not found, so should send
DBG-1:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 isAllowedChannel: got type:event_start resCode:1
INF:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Sending event_start notification for EID:23918 over MQTT
09/16/2022 14:59:06.796550 zmeventnotification[1102145].INF [main:1006] [|----> FORK:Driveway (MUS) Motion (4), eid:23918 Sending event_start notification for EID:23918 over MQTT]
DBG-2:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 requesting MQTT Publishing Job for EID:23918
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1677.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 3126.
DBG-2:2022-09-16,14:59:06 |----> FORK:Driveway (MUS) Motion (4), eid:23918 child finished writing to parent
DBG-2:2022-09-16,14:59:07 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-16,14:59:07 PARENT: MQTT tick interval (15 sec) elapsed.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:07 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:07 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-16,14:59:07 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-16,14:59:07 PARENT: RAW TEXT-->mqtt_publish--TYPE----SPLIT--zoneminder/4--SPLIT--{"eventid":"23918","monitor":"4","detection":[],"eventtype":"event_start","state":"alarm","name":"Driveway (MUS) Motion:(23918) Continuous","hookvalue":"1"}
DBG-2:2022-09-16,14:59:07 PARENT: Job: MQTT Publish on topic: zoneminder/4
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2231.
DBG-2:2022-09-16,14:59:07 PARENT: Job: MQTT Publish
DBG-2:2022-09-16,14:59:07 PARENT: RAW TEXT-->timestamp--TYPE----SPLIT--4--SPLIT--1663358346.79641
DBG-2:2022-09-16,14:59:07 PARENT: Job: Update last sent timestamp of monitor:4 to 1663358346.79641 for id:
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2191.
DBG-2:2022-09-16,14:59:07 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:07 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:07 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:07 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:07 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:12 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:12 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:12 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:12 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:12 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:12 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:12 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:17 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:17 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:17 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:17 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:17 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:17 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:17 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:22 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:22 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:22 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:22 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:22 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:22 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:22 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:27 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-16,14:59:27 PARENT: MQTT tick interval (15 sec) elapsed.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:27 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:27 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:27 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:27 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:27 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:27 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:32 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:32 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:32 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:32 PARENT: We've already worked on Monitor:4, Event:23918, not doing anything more
DBG-2:2022-09-16,14:59:32 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:32 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:32 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3682.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3833.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3935.
DBG-2:2022-09-16,14:59:34 |----> FORK:Driveway (MUS) Motion (4), eid:23918 For 4 (Driveway (MUS) Motion), SHM says: state=5, eid=23925
INF:2022-09-16,14:59:34 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Event 23918 for Monitor 4 has finished
09/16/2022 14:59:34.802938 zmeventnotification[1102145].INF [main:1006] [|----> FORK:Driveway (MUS) Motion (4), eid:23918 Event 23918 for Monitor 4 has finished]
DBG-2:2022-09-16,14:59:34 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Event end object is: state=>pending with cause=>Forced Web:
DBG-2:2022-09-16,14:59:36 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Adding event path:/var/cache/zoneminder/events/4/2022-09-16/23918 to hook for image storage
DBG-1:2022-09-16,14:59:36 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Invoking hook on event end:'/var/lib/zmeventnotification/bin/zm_event_end.sh' 23918 4 "Driveway (MUS) Motion" "Forced Web: " "/var/cache/zoneminder/events/4/2022-09-16/23918"
DBG-2:2022-09-16,14:59:36 |----> FORK:Driveway (MUS) Motion (4), eid:23918 parse of hook:Forced Web:  and []
DBG-1:2022-09-16,14:59:36 |----> FORK:Driveway (MUS) Motion (4), eid:23918 hook end returned with text:Forced Web:   json:[] exit:0
DBG-2:2022-09-16,14:59:37 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:37 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:37 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-16,14:59:37 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-16,14:59:37 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:37 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:37 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:37 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3193.
DBG-2:2022-09-16,14:59:38 |----> FORK:Driveway (MUS) Motion (4), eid:23918 rules: Checking rules for alarm caused by eid:23918, monitor:4, at: Fri Sep 16 14:59:38 2022 with cause:Continuous
DBG-1:2022-09-16,14:59:38 |----> FORK:Driveway (MUS) Motion (4), eid:23918 rules: No rules found for Monitor, allowing:4
INF:2022-09-16,14:59:38 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed
09/16/2022 14:59:38.809128 zmeventnotification[1102145].INF [main:1006] [|----> FORK:Driveway (MUS) Motion (4), eid:23918 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
DBG-2:2022-09-16,14:59:42 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2332.
DBG-2:2022-09-16,14:59:42 PARENT: After tick: TOTAL: 1 ,  ES_CONTROL: 1, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-16,14:59:42 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-16,14:59:42 PARENT: checkEvents() new events found=0
DBG-2:2022-09-16,14:59:42 PARENT: There are 0 new Events to process
DBG-2:2022-09-16,14:59:42 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-16,14:59:42 |----> FORK:Driveway (MUS) Motion (4), eid:23918 exiting
DBG-1:2022-09-16,14:59:42 |----> FORK:Driveway (MUS) Motion (4), eid:23918 Ending process:1102145 to handle alarms
^CDBG-1:2022-09-16,14:59:45 PARENT: Received request to shutdown, please wait
^CDBG-1:2022-09-16,14:59:46 PARENT: Received request to shutdown, please wait
INF:2022-09-16,14:59:47 PARENT: Event Server Terminating
09/16/2022 14:59:47.382328 zmeventnotification[1102111].INF [main:1006] [PARENT: Event Server Terminating]

@tibmeister
Copy link
Author

I'm going to close this out because really, the issues I'm facing are not the same and the original issue is resolved by @connortechnology with his push,

Please test latest master. I never pushed my change to use Capturing instead of Function for ZM > 1.37.13. So now I have. Give it a try see if it fixes this.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants