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

End event not being send after Start event #421

Open
tibmeister opened this issue Sep 26, 2022 · 2 comments
Open

End event not being send after Start event #421

tibmeister opened this issue Sep 26, 2022 · 2 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.23

What is the nature of your issue
Bug

Details
Start event is sent, but End event not sent due to "Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed". I can see the start message in my MQTT. Note, I have set

use_fcm = no

It is still showing as enabled, which could be generating an error preventing the start script from running properly.
Also note, I have three camera, two monitors per camera. The one's suffixed with "Motion" are lower resolution and the one's I intend to have trigger, then trigger the higher resolution monitor via Link.

Debug Logs (if applicable)

sudo -u www-data /usr/bin/zmeventnotification.pl --debug
09/26/2022 12:25:19.725406 zmeventnotification[1340971].INF [main:329] [Running on WebSocket library version:0.004]
INF:2022-09-26,12:25:19 PARENT: using config file: /etc/zm/zmeventnotification.ini
09/26/2022 12:25:19.765859 zmeventnotification[1340971].INF [main:1006] [PARENT: using config file: /etc/zm/zmeventnotification.ini]
INF:2022-09-26,12:25:19 PARENT: using secrets file: /etc/zm/secrets.ini
09/26/2022 12:25:19.774250 zmeventnotification[1340971].INF [main:1006] [PARENT: using secrets file: /etc/zm/secrets.ini]
DBG-2:2022-09-26,12:25:19 PARENT: Got secret token !ZMES_PICTURE_URL
DBG-2:2022-09-26,12:25:19 PARENT: Got secret token !ZM_USER
DBG-2:2022-09-26,12:25:19 PARENT: Got secret token !ZM_PASSWORD
DBG-2:2022-09-26,12:25:19 PARENT: config string substitution: {{base_data_path}} is '/var/lib/zmeventnotification'
DBG-2:2022-09-26,12:25:19 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)............... 7200

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

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

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

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

Use FCM .............................. yes
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........................ 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)..... 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-26,12:25:19 PARENT: Push enabled via FCM
09/26/2022 12:25:19.915922 zmeventnotification[1340971].INF [main:1006] [PARENT: Push enabled via FCM]
DBG-2:2022-09-26,12:25:19 PARENT: fcmv1: --> FCM V1 APIs: 1
INF:2022-09-26,12:25:19 PARENT: MQTT Enabled
09/26/2022 12:25:19.918594 zmeventnotification[1340971].INF [main:1006] [PARENT: MQTT Enabled]
INF:2022-09-26,12:25:19 PARENT: |------- Starting ES version: 6.1.28 ---------|
09/26/2022 12:25:19.918647 zmeventnotification[1340971].INF [main:1006] [PARENT: |------- Starting ES version: 6.1.28 ---------|]
DBG-2:2022-09-26,12:25:19 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-26,12:25:20 PARENT: ES invoked manually. Will handle restarts ourselves
DBG-2:2022-09-26,12:25:20 PARENT: Parent<--Child pipe ready
INF:2022-09-26,12:25:20 PARENT: Event Notification daemon v 6.1.28 starting
09/26/2022 12:25:20.054647 zmeventnotification[1340971].INF [main:1006] [PARENT: Event Notification daemon v 6.1.28 starting]
DBG-1:2022-09-26,12:25:20 PARENT: Initializing FCM tokens...
Odd number of elements in anonymous hash at /usr/bin/zmeventnotification.pl line 2867.
Odd number of elements in anonymous hash at /usr/bin/zmeventnotification.pl line 2867.
INF:2022-09-26,12:25:20 PARENT: Initializing MQTT with auth connection...
09/26/2022 12:25:20.055113 zmeventnotification[1340971].INF [main:1006] [PARENT: Initializing MQTT with auth connection...]
DBG-1:2022-09-26,12:25:20 PARENT: Intialized MQTT with auth
DBG-1:2022-09-26,12:25:20 PARENT: Total event client connections: 3
DBG-1:2022-09-26,12:25:20 PARENT: -->checkNewEvents: Connection 1: ID->1664213120 IP->(none) Token->:... Plat: Push:
DBG-1:2022-09-26,12:25:20 PARENT: -->checkNewEvents: Connection 2: ID->1664213120 IP->(none) Token->:... Plat: Push:
DBG-1:2022-09-26,12:25:20 PARENT: -->checkNewEvents: Connection 3: ID-> IP->(none) Token->:... Plat: Push:
INF:2022-09-26,12:25:20 PARENT: Re-loading monitors
09/26/2022 12:25:20.055835 zmeventnotification[1340971].INF [main:1006] [PARENT: Re-loading monitors]
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-1
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-3
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-2
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-1 Motion
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-3 Motion
DBG-1:2022-09-26,12:25:20 PARENT: Loading Cam-2 Motion
DBG-2:2022-09-26,12:25:20 PARENT: ESCONTROL_INTERFACE: Saving admin interfaces to /var/lib/zmeventnotification/misc/escontrol_interface.dat
DBG-2:2022-09-26,12:25:20 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:20 PARENT: About to start listening to socket
INF:2022-09-26,12:25:20 PARENT: Secure WS(WSS) is enabled...
09/26/2022 12:25:20.064653 zmeventnotification[1340971].INF [main:1006] [PARENT: Secure WS(WSS) is enabled...]
INF:2022-09-26,12:25:20 PARENT: Web Socket Event Server listening on port 9000
09/26/2022 12:25:20.064705 zmeventnotification[1340971].INF [main:1006] [PARENT: Web Socket Event Server listening on port 9000]
DBG-2:2022-09-26,12:25:25 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:25 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:25 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:25:25 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:25 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:25 PARENT: ---------->Tick END (active forks:0, total forks:0, active hooks: 0)<--------------
DBG-2:2022-09-26,12:25:30 PARENT: ----------> Tick START (active forks:0, total forks:0, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:30 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:30 PARENT: There are 0 active child forks & 0 zm_detect processes running...
INF:2022-09-26,12:25:30 PARENT: New event 60258 reported for Monitor:1 (Name:Cam-1) Forced Web[last processed eid:]
09/26/2022 12:25:30.066949 zmeventnotification[1340971].INF [main:1006] [PARENT: New event 60258 reported for Monitor:1 (Name:Cam-1) Forced Web[last processed eid:]]
DBG-2:2022-09-26,12:25:30 PARENT: checkEvents() new events found=1
DBG-2:2022-09-26,12:25:30 PARENT: There are 1 new Events to process
DBG-2:2022-09-26,12:25:30 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-26,12:25:30 PARENT: Forked process:1340992 to handle alarm eid:60258
DBG-2:2022-09-26,12:25:30 |----> FORK:Cam-1 (1), eid:60258 Adding event path:/var/cache/zoneminder/events/1/2022-09-26/60258 to hook for image storage
DBG-1:2022-09-26,12:25:30 |----> FORK:Cam-1 (1), eid:60258 Invoking hook on event start:'/var/lib/zmeventnotification/bin/zm_event_start.sh' 60258 1 "Cam-1" "Forced Web" "/var/cache/zoneminder/events/1/2022-09-26/60258"
DBG-2:2022-09-26,12:25:35 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:35 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-26,12:25:35 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:35 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-26,12:25:35 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:25:35 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:25:35 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:35 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:35 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:25:40 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:40 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:40 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:25:40 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:25:40 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:40 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:40 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:25:45 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:45 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:45 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:25:45 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:25:45 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:45 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:45 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:25:50 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:50 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:50 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:25:50 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:25:50 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:50 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:50 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:25:55 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:25:55 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-26,12:25:55 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:25:55 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:25:55 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:25:55 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:25:55 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:25:55 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:26:00 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:26:00 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:00 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:26:00 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:00 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:00 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:00 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:26:05 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:26:05 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:05 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:26:05 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:05 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:05 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:05 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:26:10 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:26:10 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:10 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-09-26,12:26:10 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:10 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:10 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:10 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-09-26,12:26:14 |----> FORK:Cam-1 (1), eid:60258 parse of hook: and [] from
DBG-1:2022-09-26,12:26:14 |----> FORK:Cam-1 (1), eid:60258 hook start returned with text: json:[] exit:1
DBG-2:2022-09-26,12:26:15 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:1 min)<--------------
DBG-2:2022-09-26,12:26:15 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-26,12:26:15 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:15 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-26,12:26:15 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:15 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:15 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:15 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:15 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 rules: Checking rules for alarm caused by eid:60258, monitor:1, at: Mon Sep 26 12:26:16 2022 with cause:Forced Web
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 rules: No rules found for Monitor, allowing:1
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Matching alarm to connection rules...
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Checking alarm conditions for token ending in:...
ERR:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1
09/26/2022 12:26:16.659070 zmeventnotification[1340992].ERR [main:1022] [|----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1]
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Monitor 1 event: last time not found, so should send
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 isAllowedChannel: got type:event_start resCode:1
INF:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Sending event_start notification over FCM
09/26/2022 12:26:16.659390 zmeventnotification[1340992].INF [main:1006] [|----> FORK:Cam-1 (1), eid:60258 Sending event_start notification over FCM]
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 fcmv1: FCM called when hook failed, so making sure we do not use objdetect in url
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Unknown platform
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 fcmv1: Final JSON using FCMV1 being sent is: {"token":null,"title":"Cam-1 Alarm (60258)","log_message_id":"NONE","data":{"eid":"60258","notification_foreground":"true","mid":"1"},"badge":1,"sound":"default","body":"Forced Web at 12:26 PM, 26-Sep","image_url":"https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=60258&fid=snapshot&width=800&username=admin&password=xxx} to token: ...
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 fcmv1: FCM push message error {"Error":"No Token Found"}
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 child finished writing to parent
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Checking alarm conditions for token ending in:...
ERR:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1
09/26/2022 12:26:16.979947 zmeventnotification[1340992].ERR [main:1022] [|----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1]
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Monitor 1 event: last time not found, so should send
DBG-1:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 isAllowedChannel: got type:event_start resCode:1
INF:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Sending event_start notification over FCM
09/26/2022 12:26:16.980229 zmeventnotification[1340992].INF [main:1006] [|----> FORK:Cam-1 (1), eid:60258 Sending event_start notification over FCM]
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 fcmv1: FCM called when hook failed, so making sure we do not use objdetect in url
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 Unknown platform
DBG-2:2022-09-26,12:26:16 |----> FORK:Cam-1 (1), eid:60258 fcmv1: Final JSON using FCMV1 being sent is: {"data":{"eid":"60258","mid":"1","notification_foreground":"true"},"body":"Forced Web at 12:26 PM, 26-Sep","badge":1,"sound":"default","image_url":"https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=60258&fid=snapshot&width=800&username=admin&password=xxx} to token: ...
DBG-1:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 fcmv1: FCM push message error {"Error":"No Token Found"}
DBG-2:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 child finished writing to parent
DBG-1:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 Checking alarm conditions for MQTT 10.27.200.5
ERR:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1
09/26/2022 12:26:17.283292 zmeventnotification[1340992].ERR [main:1022] [|----> FORK:Cam-1 (1), eid:60258 interval not found for mid 1]
DBG-1:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 Monitor 1 event: last time not found, so should send
DBG-1:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 isAllowedChannel: got type:event_start resCode:1
INF:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 Sending event_start notification for EID:60258 over MQTT
09/26/2022 12:26:17.283524 zmeventnotification[1340992].INF [main:1006] [|----> FORK:Cam-1 (1), eid:60258 Sending event_start notification for EID:60258 over MQTT]
DBG-2:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 requesting MQTT Publishing Job for EID:60258
DBG-2:2022-09-26,12:26:17 |----> FORK:Cam-1 (1), eid:60258 child finished writing to parent
DBG-2:2022-09-26,12:26:20 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:20 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->fcm_notification--TYPE----SPLIT--1--SPLIT--0--SPLIT--8
DBG-2:2022-09-26,12:26:20 PARENT: GOT JOB==> update badge to 1, count to 0 for: , at: 8
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->timestamp--TYPE--1664213120--SPLIT--1--SPLIT--1664213176.65921
DBG-2:2022-09-26,12:26:20 PARENT: Job: Update last sent timestamp of monitor:1 to 1664213176.65921 for id:1664213120
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->fcm_notification--TYPE----SPLIT--1--SPLIT--0--SPLIT--8
DBG-2:2022-09-26,12:26:20 PARENT: GOT JOB==> update badge to 1, count to 0 for: , at: 8
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->timestamp--TYPE--1664213120--SPLIT--1--SPLIT--1664213176.98011
DBG-2:2022-09-26,12:26:20 PARENT: Job: Update last sent timestamp of monitor:1 to 1664213176.98011 for id:1664213120
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->mqtt_publish--TYPE----SPLIT--zoneminder/1--SPLIT--{"hookvalue":"1","state":"alarm","monitor":"1","eventtype":"event_start","detection":[],"name":"Cam-1:(60258) Forced Web","eventid":"60258"}
DBG-2:2022-09-26,12:26:20 PARENT: Job: MQTT Publish on topic: zoneminder/1
DBG-2:2022-09-26,12:26:20 PARENT: Job: MQTT Publish with retain
DBG-2:2022-09-26,12:26:20 PARENT: RAW TEXT-->timestamp--TYPE----SPLIT--1--SPLIT--1664213177.28341
DBG-2:2022-09-26,12:26:20 PARENT: Job: Update last sent timestamp of monitor:1 to 1664213177.28341 for id:
DBG-2:2022-09-26,12:26:20 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:20 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:20 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:20 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:20 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:25 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:25 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:25 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:25 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:25 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:25 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:25 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:30 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:30 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:30 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:30 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:30 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:30 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:30 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:35 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:35 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-26,12:26:35 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:35 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:35 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:35 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:35 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:35 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:40 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:40 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:40 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:40 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:40 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:40 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:40 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:45 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:45 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:45 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:45 PARENT: We've already worked on Monitor:1, Event:60258, not doing anything more
DBG-2:2022-09-26,12:26:45 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:45 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:45 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:47 |----> FORK:Cam-1 (1), eid:60258 For 1 (Cam-1), SHM says: state=1, eid=60258
INF:2022-09-26,12:26:47 |----> FORK:Cam-1 (1), eid:60258 Event 60258 for Monitor 1 has finished
09/26/2022 12:26:47.290141 zmeventnotification[1340992].INF [main:1006] [|----> FORK:Cam-1 (1), eid:60258 Event 60258 for Monitor 1 has finished]
DBG-2:2022-09-26,12:26:47 |----> FORK:Cam-1 (1), eid:60258 Event end object is: state=>pending with cause=>Forced Web:
DBG-2:2022-09-26,12:26:49 |----> FORK:Cam-1 (1), eid:60258 Adding event path:/var/cache/zoneminder/events/1/2022-09-26/60258 to hook for image storage
DBG-1:2022-09-26,12:26:49 |----> FORK:Cam-1 (1), eid:60258 Invoking hook on event end:'/var/lib/zmeventnotification/bin/zm_event_end.sh' 60258 1 "Cam-1" "Forced Web: " "/var/cache/zoneminder/events/1/2022-09-26/60258"
DBG-2:2022-09-26,12:26:49 |----> FORK:Cam-1 (1), eid:60258 parse of hook:Forced Web:  and [] from Forced Web:
DBG-1:2022-09-26,12:26:49 |----> FORK:Cam-1 (1), eid:60258 hook end returned with text:Forced Web:   json:[] exit:0
DBG-2:2022-09-26,12:26:50 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:50 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:50 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-09-26,12:26:50 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-09-26,12:26:50 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-09-26,12:26:50 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:50 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:50 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-09-26,12:26:51 |----> FORK:Cam-1 (1), eid:60258 rules: Checking rules for alarm caused by eid:60258, monitor:1, at: Mon Sep 26 12:26:51 2022 with cause:Forced Web
DBG-1:2022-09-26,12:26:51 |----> FORK:Cam-1 (1), eid:60258 rules: No rules found for Monitor, allowing:1
INF:2022-09-26,12:26:51 |----> FORK:Cam-1 (1), eid:60258 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed
09/26/2022 12:26:51.297847 zmeventnotification[1340992].INF [main:1006] [|----> FORK:Cam-1 (1), eid:60258 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
DBG-2:2022-09-26,12:26:55 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:2 min)<--------------
DBG-2:2022-09-26,12:26:55 PARENT: MQTT tick interval (15 sec) elapsed.
DBG-2:2022-09-26,12:26:55 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-09-26,12:26:55 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-1:2022-09-26,12:26:55 PARENT: Total event client connections: 3
DBG-1:2022-09-26,12:26:55 PARENT: -->checkNewEvents: Connection 1: ID->1664213120 IP->(none) Token->:... Plat: Push:
DBG-1:2022-09-26,12:26:55 PARENT: -->checkNewEvents: Connection 2: ID->1664213120 IP->(none) Token->:... Plat: Push:
DBG-1:2022-09-26,12:26:55 PARENT: -->checkNewEvents: Connection 3: ID-> IP->(none) Token->:... Plat: Push:
INF:2022-09-26,12:26:55 PARENT: Re-loading monitors
09/26/2022 12:26:55.071363 zmeventnotification[1340971].INF [main:1006] [PARENT: Re-loading monitors]
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-1
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-3
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-2
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-1 Motion
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-3 Motion
DBG-1:2022-09-26,12:26:55 PARENT: Loading Cam-2 Motion
DBG-2:2022-09-26,12:26:55 PARENT: ESCONTROL_INTERFACE: Saving admin interfaces to /var/lib/zmeventnotification/misc/escontrol_interface.dat
DBG-2:2022-09-26,12:26:55 PARENT: checkEvents() new events found=0
DBG-2:2022-09-26,12:26:55 PARENT: There are 0 new Events to process
DBG-2:2022-09-26,12:26:55 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-09-26,12:26:55 |----> FORK:Cam-1 (1), eid:60258 exiting
DBG-1:2022-09-26,12:26:55 |----> FORK:Cam-1 (1), eid:60258 Ending process:1340992 to handle alarms
^CDBG-1:2022-09-26,12:26:59 PARENT: Received request to shutdown, please wait
INF:2022-09-26,12:27:00 PARENT: Event Server Terminating
09/26/2022 12:27:00.065515 zmeventnotification[1340971].INF [main:1006] [PARENT: Event Server Terminating]
@connortechnology
Copy link
Member

My config doesn't have a use_fcm=... it only has use_fcmv1=. It looks like fcm is controlled with the enable = no setting in the [fcm] block.

weird. FCM disabling works here for me.

The logic around this message is unclear. I had put a todo on it and have just now commit a potential fix. Please update and report back.

@tibmeister
Copy link
Author

I have this in my zmeventnotification.ini file

# Use the new FCM V1 protocol (recommended)
use_fcmv1 = no

I did pull the latest change and re-ran the install.sh file. right off the bat, noticed this in the debug log

10/03/2022 11:51:59.613136 zmeventnotification[2704158].INF [main:1006] [PARENT: Re-loading monitors]
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera1
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera2
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera3
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera1 Motion
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera2 Motion
DBG-1:2022-10-03,11:51:59 PARENT: Loading Camera3 Motion
DBG-2:2022-10-03,11:51:59 PARENT: ESCONTROL_INTERFACE: Saving admin interfaces to /var/lib/zmeventnotification/misc/escontrol_interface.dat
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1449.
INF:2022-10-03,11:51:59 PARENT: New event 65537 reported for Monitor:3 (Name:Camera3) [last processed eid:]
10/03/2022 11:51:59.616068 zmeventnotification[2704158].INF [main:1006] [PARENT: New event 65537 reported for Monitor:3 (Name:Camera3) [last processed eid:]]
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1449.
INF:2022-10-03,11:51:59 PARENT: New event 65427 reported for Monitor:2 (Name:Camera2) [last processed eid:]
10/03/2022 11:51:59.616514 zmeventnotification[2704158].INF [main:1006] [PARENT: New event 65427 reported for Monitor:2 (Name:Camera2) [last processed eid:]]
DBG-2:2022-10-03,11:51:59 PARENT: checkEvents() new events found=2
DBG-2:2022-10-03,11:51:59 PARENT: About to start listening to socket
INF:2022-10-03,11:51:59 PARENT: Secure WS(WSS) is enabled...
10/03/2022 11:51:59.625508 zmeventnotification[2704158].INF [main:1006] [PARENT: Secure WS(WSS) is enabled...]
INF:2022-10-03,11:51:59 PARENT: Web Socket Event Server listening on port 9000
10/03/2022 11:51:59.625562 zmeventnotification[2704158].INF [main:1006] [PARENT: Web Socket Event Server listening on port 9000]
DBG-2:2022-10-03,11:52:04 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 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
DBG-2:2022-10-03,11:52:04 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:52:04 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:52:04 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:52:04 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more

Looking in ZM, these monitors are not in alarm, and I find those events for those monitors from 12 hours ago, so not sure what's going on there but that's new.

As for the original issue, I still get the start event

DBG-2:2022-10-03,11:58:08 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:58:08 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:58:08 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1449.
INF:2022-10-03,11:58:08 PARENT: New event 65582 reported for Monitor:1 (Name:Camera1) Forced Web[last processed eid:]
10/03/2022 11:58:08.635006 zmeventnotification[2705223].INF [main:1006] [PARENT: New event 65582 reported for Monitor:1 (Name:Camera1) Forced Web[last processed eid:]]
DBG-2:2022-10-03,11:58:08 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:58:08 PARENT: checkEvents() new events found=1
DBG-2:2022-10-03,11:58:08 PARENT: There are 1 new Events to process
DBG-2:2022-10-03,11:58:08 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-10-03,11:58:08 PARENT: Forked process:2705348 to handle alarm eid:65582
DBG-2:2022-10-03,11:58:08 |----> FORK:Camera1 (1), eid:65582 Adding event path:/var/cache/zoneminder/events/1/2022-10-03/65582 to hook for image storage
DBG-1:2022-10-03,11:58:08 |----> FORK:Camera1 (1), eid:65582 Invoking hook on event start:'/var/lib/zmeventnotification/bin/zm_event_start.sh' 65582 1 "Camera1" "Forced Web" "/var/cache/zoneminder/events/1/2022-10-03/65582"
DBG-2:2022-10-03,11:58:13 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 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
...
DBG-2:2022-10-03,11:58:53 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:58:53 PARENT: There are 1 active child forks & 1 zm_detect processes running...
DBG-2:2022-10-03,11:58:53 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
DBG-2:2022-10-03,11:58:53 PARENT: We've already worked on Monitor:1, Event:65582, not doing anything more
DBG-2:2022-10-03,11:58:53 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:58:53 PARENT: checkEvents() new events found=0
DBG-2:2022-10-03,11:58:53 PARENT: There are 0 new Events to process
DBG-2:2022-10-03,11:58:53 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 1)<--------------
DBG-2:2022-10-03,11:58:53 |----> FORK:Camera1 (1), eid:65582 parse of hook: and [] from
DBG-1:2022-10-03,11:58:53 |----> FORK:Camera1 (1), eid:65582 hook start returned with text: json:[] exit:1
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3195.
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3197.
DBG-2:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 rules: Checking rules for alarm caused by eid:65582, monitor:1, at: Mon Oct  3 11:58:55 2022 with cause:Forced Web
DBG-1:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 rules: No rules found for Monitor, allowing:1
DBG-1:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 Matching alarm to connection rules...
Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 3021.
DBG-1:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 Checking alarm conditions for token ending in:...
Use of uninitialized value $monlist in string eq at /usr/bin/zmeventnotification.pl line 3005.
Use of uninitialized value $monlist in string eq at /usr/bin/zmeventnotification.pl line 3005.
Use of uninitialized value $intlist in split at /usr/bin/zmeventnotification.pl line 2984.
Use of uninitialized value $monlist in split at /usr/bin/zmeventnotification.pl line 2985.
ERR:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 interval not found for mid 1
10/03/2022 11:58:55.772837 zmeventnotification[2705348].ERR [main:1022] [|----> FORK:Camera1 (1), eid:65582 interval not found for mid 1]
DBG-1:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 Monitor 1 event: last time not found, so should send
DBG-1:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 token is unique, shouldSendEventToConn returned true, so calling sendEvent
Use of uninitialized value in string ne at /usr/bin/zmeventnotification.pl line 3073.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 3073.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 3073.
DBG-2:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 isAllowedChannel: got type:event_start resCode:1
INF:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 Sending event_start notification over FCM
10/03/2022 11:58:55.773344 zmeventnotification[2705348].INF [main:1006] [|----> FORK:Camera1 (1), eid:65582 Sending event_start notification over FCM]
DBG-4:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 Using Legacy
DBG-2:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 FCM called when hook failed, so making sure we do not use objdetect in url
Use of uninitialized value in addition (+) at /usr/bin/zmeventnotification.pl line 1992.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1996.
DBG-2:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 legacy client, NOT setting channel to zmninja
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2071.
Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 2084.
DBG-2:2022-10-03,11:58:55 |----> FORK:Camera1 (1), eid:65582 legacy: Final JSON being sent is: {"to":null,"notification":{"image":"https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=65582&fid=objdetect&width=800&username=admin&password=xxx&eid=65582&fid=objdetect&width=800&username=admin&password=xxx to token: ...
ERR:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 FCM push message Error:400 Bad Request
10/03/2022 11:58:56.023206 zmeventnotification[2705348].ERR [main:1022] [|----> FORK:Camera1 (1), eid:65582 FCM push message Error:400 Bad Request]
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2124.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 child finished writing to parent
Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 3021.
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Checking alarm conditions for token ending in:...
Use of uninitialized value $monlist in string eq at /usr/bin/zmeventnotification.pl line 3005.
Use of uninitialized value $monlist in string eq at /usr/bin/zmeventnotification.pl line 3005.
Use of uninitialized value $intlist in split at /usr/bin/zmeventnotification.pl line 2984.
Use of uninitialized value $monlist in split at /usr/bin/zmeventnotification.pl line 2985.
ERR:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 interval not found for mid 1
10/03/2022 11:58:56.023540 zmeventnotification[2705348].ERR [main:1022] [|----> FORK:Camera1 (1), eid:65582 interval not found for mid 1]
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Monitor 1 event: last time not found, so should send
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 token is unique, shouldSendEventToConn returned true, so calling sendEvent
Use of uninitialized value in string ne at /usr/bin/zmeventnotification.pl line 3073.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 3073.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 3073.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 isAllowedChannel: got type:event_start resCode:1
INF:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Sending event_start notification over FCM
10/03/2022 11:58:56.023797 zmeventnotification[2705348].INF [main:1006] [|----> FORK:Camera1 (1), eid:65582 Sending event_start notification over FCM]
DBG-4:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Using Legacy
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 FCM called when hook failed, so making sure we do not use objdetect in url
Use of uninitialized value in addition (+) at /usr/bin/zmeventnotification.pl line 1992.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1996.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 legacy client, NOT setting channel to zmninja
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2071.
Use of uninitialized value in substr at /usr/bin/zmeventnotification.pl line 2084.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 legacy: Final JSON being sent is: {"to":null,"notification":{"body":"Forced Web at 11:58 AM, 03-Oct","icon":"ic_stat_notification","title":"Camera1 Alarm (65582)","image":"https://zm.tiberiansun.us:2417/zm/index.php?view=image&eid=65582&fid=objdetect&width=800&username=admin&password=xxx&eid=65582&fid=objdetect&width=800&username=admin&password=xxx to token: ...
ERR:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 FCM push message Error:400 Bad Request
10/03/2022 11:58:56.209734 zmeventnotification[2705348].ERR [main:1022] [|----> FORK:Camera1 (1), eid:65582 FCM push message Error:400 Bad Request]
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2124.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 child finished writing to parent
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Checking alarm conditions for MQTT 10.27.200.5
ERR:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 interval not found for mid 1
10/03/2022 11:58:56.210025 zmeventnotification[2705348].ERR [main:1022] [|----> FORK:Camera1 (1), eid:65582 interval not found for mid 1]
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Monitor 1 event: last time not found, so should send
DBG-1:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 token is unique, shouldSendEventToConn returned true, so calling sendEvent
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 isAllowedChannel: got type:event_start resCode:1
INF:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 Sending event_start notification for EID:65582 over MQTT
10/03/2022 11:58:56.210270 zmeventnotification[2705348].INF [main:1006] [|----> FORK:Camera1 (1), eid:65582 Sending event_start notification for EID:65582 over MQTT]
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 requesting MQTT Publishing Job for EID:65582
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 1680.
Use of uninitialized value in concatenation (.) or string at /usr/bin/zmeventnotification.pl line 3130.
DBG-2:2022-10-03,11:58:56 |----> FORK:Camera1 (1), eid:65582 child finished writing to parent
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3686.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3837.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 3938.
DBG-2:2022-10-03,11:58:58 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 1 running for:2 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
DBG-2:2022-10-03,11:58:58 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->fcm_notification--TYPE----SPLIT--1--SPLIT--0--SPLIT--9
DBG-2:2022-10-03,11:58:58 PARENT: GOT JOB==> update badge to 1, count to 0 for: , at: 9
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->timestamp--TYPE--1664816263--SPLIT--1--SPLIT--1664816335.77309
DBG-2:2022-10-03,11:58:58 PARENT: Job: Update last sent timestamp of monitor:1 to 1664816335.77309 for id:1664816263
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2194.
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->fcm_notification--TYPE----SPLIT--1--SPLIT--0--SPLIT--9
DBG-2:2022-10-03,11:58:58 PARENT: GOT JOB==> update badge to 1, count to 0 for: , at: 9
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2172.
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->timestamp--TYPE--1664816263--SPLIT--1--SPLIT--1664816336.02367
DBG-2:2022-10-03,11:58:58 PARENT: Job: Update last sent timestamp of monitor:1 to 1664816336.02367 for id:1664816263
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2194.
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->mqtt_publish--TYPE----SPLIT--zoneminder/1--SPLIT--{"name":"Camera1:(65582) Forced Web","monitor":"1","eventid":"65582","hookvalue":"1","state":"alarm","detection":[],"eventtype":"event_start"}
DBG-2:2022-10-03,11:58:58 PARENT: Job: MQTT Publish on topic: zoneminder/1
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2234.
DBG-2:2022-10-03,11:58:58 PARENT: Job: MQTT Publish with retain
DBG-2:2022-10-03,11:58:58 PARENT: RAW TEXT-->timestamp--TYPE----SPLIT--1--SPLIT--1664816336.21015
DBG-2:2022-10-03,11:58:58 PARENT: Job: Update last sent timestamp of monitor:1 to 1664816336.21015 for id:
Use of uninitialized value in string eq at /usr/bin/zmeventnotification.pl line 2194.
DBG-2:2022-10-03,11:58:58 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:58:58 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
DBG-2:2022-10-03,11:58:58 PARENT: We've already worked on Monitor:1, Event:65582, not doing anything more
DBG-2:2022-10-03,11:58:58 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:58:58 PARENT: checkEvents() new events found=0
DBG-2:2022-10-03,11:58:58 PARENT: There are 0 new Events to process
...
DBG-2:2022-10-03,11:59:43 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:59:43 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:59:43 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
DBG-2:2022-10-03,11:59:43 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:59:43 PARENT: checkEvents() new events found=0
DBG-2:2022-10-03,11:59:43 PARENT: There are 0 new Events to process
DBG-2:2022-10-03,11:59:43 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-2:2022-10-03,11:59:44 |----> FORK:Driveway (MUS) (1), eid:65582 Adding event path:/var/cache/zoneminder/events/1/2022-10-03/65582 to hook for image storage
DBG-1:2022-10-03,11:59:44 |----> FORK:Driveway (MUS) (1), eid:65582 Invoking hook on event end:'/var/lib/zmeventnotification/bin/zm_event_end.sh' 65582 1 "Driveway (MUS)" "Forced Web: " "/var/cache/zoneminder/events/1/2022-10-03/65582"
DBG-2:2022-10-03,11:59:44 |----> FORK:Driveway (MUS) (1), eid:65582 parse of hook:Forced Web:  and [] from Forced Web:
DBG-1:2022-10-03,11:59:44 |----> FORK:Driveway (MUS) (1), eid:65582 hook end returned with text:Forced Web:   json:[] exit:0
Use of uninitialized value in index at /usr/bin/zmeventnotification.pl line 3197.
DBG-2:2022-10-03,11:59:46 |----> FORK:Driveway (MUS) (1), eid:65582 rules: Checking rules for alarm caused by eid:65582, monitor:1, at: Mon Oct  3 11:59:46 2022 with cause:Forced Web
DBG-1:2022-10-03,11:59:46 |----> FORK:Driveway (MUS) (1), eid:65582 rules: No rules found for Monitor, allowing:1
INF:2022-10-03,11:59:46 |----> FORK:Driveway (MUS) (1), eid:65582 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed
10/03/2022 11:59:46.227716 zmeventnotification[2705348].INF [main:1006] [|----> FORK:Driveway (MUS) (1), eid:65582 Not sending event end alarm, as we did not send a start alarm for this, or start hook processing failed]
DBG-2:2022-10-03,11:59:48 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:3 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
DBG-2:2022-10-03,11:59:48 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:59:48 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--add
DBG-2:2022-10-03,11:59:48 PARENT: RAW TEXT-->update_parallel_hooks--TYPE--del
DBG-2:2022-10-03,11:59:48 PARENT: There are 1 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:59:48 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
DBG-2:2022-10-03,11:59:48 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:59:48 PARENT: checkEvents() new events found=0
DBG-2:2022-10-03,11:59:48 PARENT: There are 0 new Events to process
DBG-2:2022-10-03,11:59:48 PARENT: ---------->Tick END (active forks:1, total forks:1, active hooks: 0)<--------------
DBG-1:2022-10-03,11:59:50 |----> FORK:Driveway (MUS) (1), eid:65582 exiting
DBG-1:2022-10-03,11:59:50 |----> FORK:Driveway (MUS) (1), eid:65582 Ending process:2705348 to handle alarms
DBG-2:2022-10-03,11:59:53 PARENT: ----------> Tick START (active forks:1, total forks:1, active hooks: 0 running for:3 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
DBG-2:2022-10-03,11:59:53 PARENT: After tick: TOTAL: 3 ,  ES_CONTROL: 0, FCM+WEB: 0, FCM: 0, WEB: 0, MQTT:1, invalid WEB: 0, PENDING: 0
DBG-2:2022-10-03,11:59:53 PARENT: RAW TEXT-->active_event_delete--TYPE--1--SPLIT--65582
DBG-2:2022-10-03,11:59:53 PARENT: Job: Deleting active_event eid:65582, mid:1
DBG-2:2022-10-03,11:59:53 PARENT: There are 0 active child forks & 0 zm_detect processes running...
DBG-2:2022-10-03,11:59:53 PARENT: We've already worked on Monitor:2, Event:65427, not doing anything more
DBG-2:2022-10-03,11:59:53 PARENT: We've already worked on Monitor:3, Event:65537, not doing anything more
DBG-2:2022-10-03,11:59:53 PARENT: checkEvents() new events found=0
DBG-2:2022-10-03,11:59:53 PARENT: There are 0 new Events to process
DBG-2:2022-10-03,11:59:53 PARENT: ---------->Tick END (active forks:0, total forks:1, active hooks: 0)<--------------
DBG-2:2022-10-03,11:59:58 PARENT: ----------> Tick START (active forks:0, total forks:1, active hooks: 0 running for:3 min)<--------------
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2286.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric ne (!=) at /usr/bin/zmeventnotification.pl line 2315.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2318.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2321.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2324.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2327.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2330.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.
Use of uninitialized value in numeric eq (==) at /usr/bin/zmeventnotification.pl line 2336.

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

2 participants