Mobile unable to connect to the event server

[rajil]

Before you create an issue, please make sure you have read the FAQ at https://github.com/pliablepixels/zmNinja/wiki/FAQ. Common questions on API, no image etc are covered there.

Platform & OS Version
(example only, please edit this line) Android 6.0.1 Marshmallow

The version of the app you are reporting:
(example only, please edit this line) 1.2.31

Device details:
(example only, please edit this line) Samsung Note 4

What is the nature of your issue
Bug

Details
I have configured zmeventnotification.pl on the server and can see messages in the server log. However the client does not connect to the event server.

I have set my $useSecure = 0 on the server. On the client the connect strict is ws://mylanip:9000

Client log

Dec 3, 2016 09:49 PM INFO user profile encrypted, decoding...
Dec 3, 2016 09:49 PM INFO Setting up pause and resume handler AFTER language is loaded...
Dec 3, 2016 09:49 PM INFO Setting up POST LOGIN timer
Dec 3, 2016 09:49 PM INFO Checking for news updates
Dec 3, 2016 09:49 PM INFO Checking for new version updates...
Dec 3, 2016 09:49 PM INFO No Event Server present. Not initializing
Dec 3, 2016 09:49 PM INFO ZMData init: checking for stored variables & setting up log file
Dec 3, 2016 09:49 PM INFO Language file loaded, continuing with rest
Dec 3, 2016 09:49 PM INFO >>>>Language to be used:en
Dec 3, 2016 09:49 PM INFO Device Language is:en
Dec 3, 2016 09:49 PM INFO Language stored as:en
Dec 3, 2016 09:49 PM INFO App Version: 1.2.31
Dec 3, 2016 09:49 PM INFO Retrieving language before init is called...
Dec 3, 2016 09:49 PM INFO Enabling native transitions...
2016-12-04T03:49:19.619Z INFO Enabling insecure SSL
2016-12-04T03:49:19.608Z INFO Updating statusbar
2016-12-04T03:49:19.606Z INFO >>>>No data to import....
2016-12-04T03:49:19.356Z INFO localforage driver for storage:asyncStorage
2016-12-04T03:49:19.354Z DEBUG text zoom factor is 1
2016-12-04T03:49:18.947Z INFO You are running on android

Server log

2016-12-03 21:49:24.231430	zmeventnotification		69153	INF	Storing token ...LlYrkXDHbS,monlist:-1,intlist:-1,pushstate:enabled	zmeventnotification.pl	
2016-12-03 21:49:24.230720	zmeventnotification		69153	INF	Duplicate token found, marking for deletion	zmeventnotification.pl	
2016-12-03 21:49:24.162640	zmeventnotification		69153	INF	Correct authentication provided by mylanip	zmeventnotification.pl

Any idea why the client is not connecting to the server?

[pliablepixels]

Your zmNinja log says
Dec 3, 2016 09:49 PM INFO No Event Server present. Not initializing

That means you have not configured or saved event server settings at zmNinja

[rajil]

I do have the event server defined as the screen shot shows.

[pliablepixels]

try hitting save a few times and see debug logs in zmNinja and event server -- see if the registration goes through

[rajil]

On pressing 'Save' on the client i do get a flurry of messages on the server

2016-12-04 08:27:44.615750	zmeventnotification		69153	INF	Pushproxy registration success 	zmeventnotification.pl	
2016-12-04 08:27:44.063590	zmeventnotification		69153	INF	Storing token ...LHbfgrS,monlist:-1,intlist:-1,pushstate:enabled	zmeventnotification.pl

But the client still says No Event Server present.

[pliablepixels]

what happens if you quit the client (kill it) and start again ? can you share debug logs when you start ?

[rajil]

Here is the debug log on client startup

Logs for version:1.2.31 (android)

Dec 4, 2016 08:34 AM INFO Force calling resize
Dec 4, 2016 08:34 AM DEBUG All images loaded
Dec 4, 2016 08:34 AM DEBUG ~~loaded image is loaded for http://<server>/cgi-bin/nph-zms?mode=single&monitor=1&scale=100&auth=d56c6968ab4b07df4954d5728c902b42&rand=25925
Dec 4, 2016 08:34 AM INFO Whoops!! Monitors have changed. I'm resetting layouts, sorry!
Dec 4, 2016 08:34 AM INFO Monitors that are active and not DOM hidden: 1 while grid has undefined
Dec 4, 2016 08:34 AM INFO Did NOT find a packery layout
Dec 4, 2016 08:34 AM INFO Stream authentication construction: &auth=d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM INFO DataModel: Extracted a stream authentication key of: d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM DEBUG DataModel: Getting auth from http://<server>/index.php?view=watch&mid=1&connkey=502972 with mid=1
Dec 4, 2016 08:34 AM INFO Inside Montage Ctrl:We found 1 monitors
Dec 4, 2016 08:34 AM DEBUG skipping image refresh, packery is still loading
Dec 4, 2016 08:34 AM DEBUG bandwidth: highbw montage refresh set to: 2
Dec 4, 2016 08:34 AM DEBUG Setting image mode to snapshot, will change to image when packery is all done
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM DEBUG API 1.30.0: Monitor 1 will use fid for direct image access
Dec 4, 2016 08:34 AM INFO multi server list loaded{"servers":[]}
Dec 4, 2016 08:34 AM INFO Monitor load was successful, loaded 1 monitors
Dec 4, 2016 08:34 AM DEBUG Now trying to get multi-server data, if present
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM INFO Real-time event: {"reason":"","event":"auth","version":"0.91","type":"","status":"Success"}
Dec 4, 2016 08:34 AM DEBUG Push Notification registration ID received: {"registrationId":"cBiHY5ZEkig:APA91bGhLF4yHCSNRpCldD-U2fsdfsd4pl9F2m_yKUuRA8TfzXmLAihljz_BkQ9-t6jDvxiL4KPFKJv74V35hmIOzjas7yLRVooKMtUyL6dS-Nkbw_6MjQVz9gfdgdfg"}
Dec 4, 2016 08:34 AM INFO openHandshake: Websocket open
Dec 4, 2016 08:34 AM INFO getMonitors:Loading all monitors
Dec 4, 2016 08:34 AM DEBUG Transitioning state to: montage with param undefined
Dec 4, 2016 08:34 AM DEBUG logging state transition
Dec 4, 2016 08:34 AM INFO Websocket was closed, trying to re-open
Dec 4, 2016 08:34 AM DEBUG Setting up websocket error handler
Dec 4, 2016 08:34 AM INFO Initializing Websocket with URL ws://<server>:9000 , will connect later...
Dec 4, 2016 08:34 AM INFO >>>Setting up push registration
Dec 4, 2016 08:34 AM DEBUG Calling websocket init
Dec 4, 2016 08:34 AM INFO Got API version: 1.30.0
Dec 4, 2016 08:34 AM DEBUG Timezone API error handler, guessing local:America/Chicago
Dec 4, 2016 08:34 AM INFO ZM_EVENT_IMAGE_DIGITS is 5
Dec 4, 2016 08:34 AM DEBUG getAPIversion called
Dec 4, 2016 08:34 AM INFO First invocation of TimeZone, asking server
Dec 4, 2016 08:34 AM DEBUG Config URL for digits is:http://<server>/api/configs/viewByName/ZM_EVENT_IMAGE_DIGITS.json
Dec 4, 2016 08:34 AM DEBUG PortalLogin: auth success
Dec 4, 2016 08:34 AM INFO Stream authentication construction: 
Dec 4, 2016 08:34 AM DEBUG Storing login time as Sun Dec 04 2016 08:34:44 GMT-0600
Dec 4, 2016 08:34 AM INFO Deferring auth key, as monitorId unknown
Dec 4, 2016 08:34 AM DEBUG auth-success emit:Successful
Dec 4, 2016 08:34 AM INFO zmAutologin successfully logged into Zoneminder
Dec 4, 2016 08:34 AM INFO ZM has recaptcha disabled - good
Dec 4, 2016 08:34 AM DEBUG New post dated 2016-12-02 18:33:04 found
Dec 4, 2016 08:34 AM INFO Checking if reCaptcha is enabled in ZM...
Dec 4, 2016 08:34 AM INFO zmAutologin called
Dec 4, 2016 08:34 AM DEBUG Resetting zmCookie...
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM DEBUG PIN code entered is correct, or there is no PIN set
Dec 4, 2016 08:34 AM DEBUG unlock called with check PIN=true
Dec 4, 2016 08:34 AM INFO not checking for touchID
Dec 4, 2016 08:34 AM INFO User credentials are provided
Dec 4, 2016 08:34 AM DEBUG Inside Portal login Enter handler
Dec 4, 2016 08:34 AM DEBUG current version: 1.2.31 & available version 1.2.31
Dec 4, 2016 08:34 AM INFO Entering Portal Main
Dec 4, 2016 08:34 AM INFO >>>>>>>>>>>>>>> All init over, going to portal login
Dec 4, 2016 08:34 AM INFO DataModel init recovered this loginData as {"serverName":"Home","username":"admin",<password removed>,"fallbackConfiguration":"","url":"http://<server>","apiurl":"http://<server>/api","eventServer":"ws://<server>:9000","maxMontage":"100","streamingurl":"http://<server>/cgi-bin","maxFPS":"3","montageQuality":100,"singleImageQuality":"100","montageHistoryQuality":"50","useSSL":false,"keepAwake":true,"isUseAuth":true,"isUseEventServer":true,"disablePush":false,"eventServerMonitors":"1","eventServerInterval":"0","refreshSec":"2","enableLogs":true,"enableDebug":true,"usePin":false,"pinCode":"","canSwipeMonitors":true,"persistMontageOrder":true,"onTapScreen":"Montage","enableh264":true,"gapless":false,"montageOrder":"","montageHiddenOrder":"","montageArraySize":"0","graphSize":2000,"enableAlarmCount":true,"minAlarmCount":1,"montageSize":"3","useNphZms":true,"useNphZmsForEvents":true,"packMontage":false,"exitOnSleep":false,"forceNetworkStop":false,"defaultPushSound":false,"enableBlog":true,"use24hr":false,"packeryPositions":"","packerySizes":"","timelineModalGraphType":"all","resumeDelay":0,"language":"en","reachability":true,"forceImageModePath":false,"disableNative":false,"vibrateOnPush":true,"soundOnPush":true,"cycleMonitors":false,"cycleMonitorsInterval":10,"enableLowBandwidth":false,"autoSwitchBandwidth":false,"refreshSecLowBW":8,"disableAlarmCheckMontage":false,"monSingleImageQuality":100,"EHpackeryPositions":"","useLocalTimeZone":true,"fastLogin":true,"followTimeLine":false,"timelineScale":-1}
Dec 4, 2016 08:34 AM INFO Setting DataModel init bandwidth to: highbw
Dec 4, 2016 08:34 AM DEBUG timeline graph type not set. Setting to all
Dec 4, 2016 08:34 AM INFO user profile encrypted, decoding...
Dec 4, 2016 08:34 AM INFO Setting up pause and resume handler AFTER language is loaded...
Dec 4, 2016 08:34 AM INFO Setting up POST LOGIN timer
Dec 4, 2016 08:34 AM INFO Checking for news updates
Dec 4, 2016 08:34 AM INFO Checking for new version updates...
Dec 4, 2016 08:34 AM INFO No Event Server present. Not initializing
Dec 4, 2016 08:34 AM INFO ZMData init: checking for stored variables & setting up log file
Dec 4, 2016 08:34 AM INFO Language file loaded, continuing with rest
Dec 4, 2016 08:34 AM INFO >>>>Language to be used:en
Dec 4, 2016 08:34 AM INFO Device Language is:en
Dec 4, 2016 08:34 AM INFO Language stored as:en
Dec 4, 2016 08:34 AM INFO App Version: 1.2.31
Dec 4, 2016 08:34 AM INFO Retrieving language before init is called...
Dec 4, 2016 08:34 AM INFO Enabling native transitions...
2016-12-04T14:34:43.308Z INFO Enabling insecure SSL
2016-12-04T14:34:43.303Z INFO Updating statusbar
2016-12-04T14:34:43.297Z INFO >>>>No data to import....
2016-12-04T14:34:42.991Z INFO localforage driver for storage:asyncStorage
2016-12-04T14:34:42.989Z DEBUG text zoom factor is 1
2016-12-04T14:34:42.440Z INFO You are running on android
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM INFO ROOT APP: Stopping network pull...
Dec 4, 2016 08:34 AM INFO ROOT APP:App is going into background
Dec 4, 2016 08:34 AM INFO user exited app
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM DEBUG API 1.30.0: Monitor 1 will use fid for direct image access
Dec 4, 2016 08:34 AM INFO multi server list loaded{"servers":[]}
Dec 4, 2016 08:34 AM INFO Monitor load was successful, loaded 1 monitors
Dec 4, 2016 08:34 AM DEBUG Now trying to get multi-server data, if present
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM INFO getMonitors:Force reloading all monitors
Dec 4, 2016 08:26 AM INFO user profile encrypted, decoding...
Dec 4, 2016 08:26 AM INFO Setting up pause and resume handler AFTER language is loaded...
Dec 4, 2016 08:26 AM INFO Setting up POST LOGIN timer
Dec 4, 2016 08:26 AM INFO Checking for news updates
Dec 4, 2016 08:26 AM INFO Checking for new version updates...
Dec 4, 2016 08:26 AM INFO No Event Server present. Not initializing
Dec 4, 2016 08:26 AM INFO ZMData init: checking for stored variables & setting up log file
Dec 4, 2016 08:26 AM INFO Language file loaded, continuing with rest
Dec 4, 2016 08:26 AM INFO >>>>Language to be used:en
Dec 4, 2016 08:26 AM INFO Device Language is:en
Dec 4, 2016 08:26 AM INFO Language stored as:en
Dec 4, 2016 08:26 AM INFO App Version: 1.2.31
Dec 4, 2016 08:26 AM INFO Retrieving language before init is called...
Dec 4, 2016 08:26 AM INFO Enabling native transitions...
2016-12-04T14:26:46.748Z INFO Enabling insecure SSL
2016-12-04T14:26:46.740Z INFO Updating statusbar
2016-12-04T14:26:46.737Z INFO >>>>No data to import....
2016-12-04T14:26:46.433Z INFO localforage driver for storage:asyncStorage
2016-12-04T14:26:46.431Z DEBUG text zoom factor is 1
2016-12-04T14:26:45.967Z INFO You are running on android

[pliablepixels]

looks like it worked

Dec 4, 2016 08:34 AM INFO Real-time event: {"reason":"","event":"auth","version":"0.91","type":"","status":"Success"}
Dec 4, 2016 08:34 AM DEBUG Push Notification registration ID received: {"registrationId":"cBiHY5ZEkig:APA91bGhLF4yHCSNRpCldD-U2fsdfsd4pl9F2m_yKUuRA8TfzXmLAihljz_BkQ9-t6jDvxiL4KPFKJv74V35hmIOzjas7yLRVooKMtUyL6dS-Nkbw_6MjQVz9gfdgdfg"}
Dec 4, 2016 08:34 AM INFO openHandshake: Websocket open
Dec 4, 2016 08:34 AM INFO getMonitors:Loading all monitors
Dec 4, 2016 08:34 AM DEBUG Transitioning state to: montage with param undefined
Dec 4, 2016 08:34 AM DEBUG logging state transition
Dec 4, 2016 08:34 AM INFO Websocket was closed, trying to re-open
Dec 4, 2016 08:34 AM DEBUG Setting up websocket error handler
Dec 4, 2016 08:34 AM INFO Initializing Websocket with URL ws://<server>:9000 , will connect later...
Dec 4, 2016 08:34 AM INFO >>>Setting up push registration
Dec 4, 2016 08:34 AM DEBUG Calling websocket init

[rajil]

I forced an alarm to generate but did not get any notification. Client log is

Logs for version:1.2.31 (android)

Dec 4, 2016 08:46 AM DEBUG EventCtrl: success, got 76 events
Dec 4, 2016 08:46 AM DEBUG {"results":{"1":"108"}}
Dec 4, 2016 08:46 AM DEBUG {"results":{"1":"51"}}
Dec 4, 2016 08:46 AM DEBUG {"results":{"1":"10"}}
Dec 4, 2016 08:46 AM DEBUG {"results":{"1":"3"}}
Dec 4, 2016 08:46 AM DEBUG EventCtrl: grabbing events for: id=0 Date/Time:-
Dec 4, 2016 08:46 AM DEBUG EventCtrl: found 28 pages of events
Dec 4, 2016 08:46 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20month/AlarmFrames >=:1.json
Dec 4, 2016 08:46 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20week/AlarmFrames >=:1.json
Dec 4, 2016 08:46 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20day/AlarmFrames >=:1.json
Dec 4, 2016 08:46 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20hour/AlarmFrames >=:1.json
Dec 4, 2016 08:46 AM DEBUG Setting up carousel watchers
Dec 4, 2016 08:46 AM DEBUG EventCtrl: grabbing # of event pages
Dec 4, 2016 08:46 AM DEBUG getInitialEvents called
Dec 4, 2016 08:46 AM DEBUG text zoom factor is 1
Dec 4, 2016 08:46 AM INFO Image padding digits reported as 5
Dec 4, 2016 08:46 AM INFO ZM_EVENT_IMAGE_DIGITS is already configured for 5
Dec 4, 2016 08:46 AM DEBUG >>>height of list/scrub set to 230 and 330
Dec 4, 2016 08:46 AM INFO EventCtrl called with: EID=0 playEvent =  false
Dec 4, 2016 08:46 AM INFO Returning pre-loaded list of 1 monitors
Dec 4, 2016 08:46 AM INFO Force calling resize
Dec 4, 2016 08:46 AM DEBUG All images loaded
Dec 4, 2016 08:46 AM DEBUG ~~loaded image is loaded for http://<server>/cgi-bin/nph-zms?mode=single&monitor=1&scale=100&auth=d56c6968ab4b07df4954d5728c902b42&rand=14129
Dec 4, 2016 08:46 AM INFO Whoops!! Monitors have changed. I'm resetting layouts, sorry!
Dec 4, 2016 08:46 AM INFO Monitors that are active and not DOM hidden: 1 while grid has undefined
Dec 4, 2016 08:46 AM INFO Did NOT find a packery layout
Dec 4, 2016 08:46 AM INFO Stream authentication construction: &auth=d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:46 AM INFO DataModel: Extracted a stream authentication key of: d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:46 AM DEBUG DataModel: Getting auth from http://<server>/index.php?view=watch&mid=1&connkey=817697 with mid=1
Dec 4, 2016 08:46 AM INFO Inside Montage Ctrl:We found 1 monitors
Dec 4, 2016 08:46 AM DEBUG skipping image refresh, packery is still loading
Dec 4, 2016 08:46 AM DEBUG bandwidth: highbw montage refresh set to: 2
Dec 4, 2016 08:46 AM DEBUG Setting image mode to snapshot, will change to image when packery is all done
Dec 4, 2016 08:46 AM INFO Returning pre-loaded list of 1 monitors
Dec 4, 2016 08:45 AM DEBUG EventCtrl: success, got 73 events
Dec 4, 2016 08:45 AM DEBUG {"results":{"1":"105"}}
Dec 4, 2016 08:45 AM DEBUG {"results":{"1":"48"}}
Dec 4, 2016 08:45 AM DEBUG {"results":{"1":"7"}}
Dec 4, 2016 08:45 AM DEBUG {"results":[]}
Dec 4, 2016 08:45 AM DEBUG EventCtrl: grabbing events for: id=0 Date/Time:-
Dec 4, 2016 08:45 AM DEBUG EventCtrl: found 28 pages of events
Dec 4, 2016 08:45 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20month/AlarmFrames >=:1.json
Dec 4, 2016 08:45 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20week/AlarmFrames >=:1.json
Dec 4, 2016 08:45 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20day/AlarmFrames >=:1.json
Dec 4, 2016 08:45 AM DEBUG consoleEvents API:http://<server>/api/events/consoleEvents/1%20hour/AlarmFrames >=:1.json
Dec 4, 2016 08:45 AM DEBUG Setting up carousel watchers
Dec 4, 2016 08:45 AM DEBUG EventCtrl: grabbing # of event pages
Dec 4, 2016 08:45 AM DEBUG getInitialEvents called
Dec 4, 2016 08:45 AM DEBUG text zoom factor is 1
Dec 4, 2016 08:45 AM INFO Image padding digits reported as 5
Dec 4, 2016 08:45 AM INFO ZM_EVENT_IMAGE_DIGITS is already configured for 5
Dec 4, 2016 08:45 AM DEBUG >>>height of list/scrub set to 230 and 330
Dec 4, 2016 08:45 AM INFO EventCtrl called with: EID=0 playEvent =  false
Dec 4, 2016 08:45 AM INFO Returning pre-loaded list of 1 monitors
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl: monitor check state returned: {"status":true,"statustext":"'zmc -m 1' running since 16/12/04 08:45:46, pid = 93218"}
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl:monitorStateCheck: http://<server>/api/monitors/daemonStatus/id:1/daemon:zmc.json
Dec 4, 2016 08:45 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:45 AM DEBUG API 1.30.0: Monitor 1 will use fid for direct image access
Dec 4, 2016 08:45 AM INFO multi server list loaded{"servers":[]}
Dec 4, 2016 08:45 AM INFO Monitor load was successful, loaded 1 monitors
Dec 4, 2016 08:45 AM DEBUG Now trying to get multi-server data, if present
Dec 4, 2016 08:45 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:45 AM INFO getMonitors:Force reloading all monitors
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl: Not restarting ZM - Make sure you have the patch installed in MonitorsController.php or this won't work
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl: parmeters constructed: Monitor%5BFunction%5D=Modect&Monitor%5BEnabled%5D=1
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl: URLs for changeConfig save:http://<server>/api/monitors/1.json
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl:changeConfig selection:trueModect
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl: monitor check state returned: {"status":true,"statustext":"'zmc -m 1' running since 16/12/03 20:33:46, pid = 69288"}
Dec 4, 2016 08:45 AM DEBUG MonitorCtrl:monitorStateCheck: http://<server>/api/monitors/daemonStatus/id:1/daemon:zmc.json
Dec 4, 2016 08:45 AM INFO Returning pre-loaded list of 1 monitors
Dec 4, 2016 08:45 AM DEBUG Transitioning state to: log with param undefined
Dec 4, 2016 08:45 AM DEBUG logging state transition
Dec 4, 2016 08:45 AM INFO Got API version: 1.30.0
Dec 4, 2016 08:45 AM INFO ZM_EVENT_IMAGE_DIGITS is 5
Dec 4, 2016 08:45 AM INFO Stream authentication construction: &auth=d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:45 AM INFO DataModel: Extracted a stream authentication key of: d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:45 AM INFO ZM has recaptcha disabled - good
Dec 4, 2016 08:45 AM DEBUG getAPIversion called
Dec 4, 2016 08:45 AM DEBUG Config URL for digits is:http://<server>/api/configs/viewByName/ZM_EVENT_IMAGE_DIGITS.json
Dec 4, 2016 08:45 AM DEBUG PortalLogin: auth success
Dec 4, 2016 08:45 AM DEBUG Storing login time as Sun Dec 04 2016 08:45:35 GMT-0600
Dec 4, 2016 08:45 AM DEBUG DataModel: Getting auth from http://<server>/index.php?view=watch&mid=1&connkey=undefined with mid=1
Dec 4, 2016 08:45 AM DEBUG auth-success emit:Successful
Dec 4, 2016 08:45 AM INFO zmAutologin successfully logged into Zoneminder
Dec 4, 2016 08:45 AM INFO Checking if reCaptcha is enabled in ZM...
Dec 4, 2016 08:45 AM INFO zmAutologin called
Dec 4, 2016 08:45 AM DEBUG Resetting zmCookie...
Dec 4, 2016 08:45 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:45 AM DEBUG PIN code entered is correct, or there is no PIN set
Dec 4, 2016 08:45 AM DEBUG unlock called with check PIN=true
Dec 4, 2016 08:45 AM INFO not checking for touchID
Dec 4, 2016 08:45 AM INFO User credentials are provided
Dec 4, 2016 08:45 AM DEBUG Inside Portal login Enter handler
Dec 4, 2016 08:45 AM INFO Entering Portal Main
Dec 4, 2016 08:45 AM DEBUG going to portal login
Dec 4, 2016 08:45 AM DEBUG Last State recorded:{"viewId":"ion23","index":2,"historyId":"ion3","backViewId":null,"forwardViewId":null,"stateId":"log","stateName":"log","url":"/log","canSwipeBack":true,"title":"Logs"}
Dec 4, 2016 08:45 AM DEBUG LogCtrl: resume called, starting log timer
Dec 4, 2016 08:45 AM DEBUG LogCtrl: resume called, starting log timer
Dec 4, 2016 08:45 AM INFO >>> Resume delayed for 0 ms, to wait for network stack...
Dec 4, 2016 08:45 AM INFO App is resuming from background
Dec 4, 2016 08:35 AM DEBUG LogCtrl: pause called, killing log timer
Dec 4, 2016 08:35 AM DEBUG LogCtrl: pause called, killing log timer
Dec 4, 2016 08:35 AM DEBUG MontageCtrl: onpause called
Dec 4, 2016 08:35 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:35 AM INFO ROOT APP: Stopping network pull...
Dec 4, 2016 08:35 AM INFO ROOT APP:App is going into background
Dec 4, 2016 08:34 AM INFO ZM_EVENT_IMAGE_DIGITS is 5
Dec 4, 2016 08:34 AM DEBUG Transitioning state to: log with param undefined
Dec 4, 2016 08:34 AM DEBUG logging state transition
Dec 4, 2016 08:34 AM INFO Got API version: 1.30.0
Dec 4, 2016 08:34 AM INFO Stream authentication construction: &auth=d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM INFO DataModel: Extracted a stream authentication key of: d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM INFO ZM has recaptcha disabled - good
Dec 4, 2016 08:34 AM DEBUG getAPIversion called
Dec 4, 2016 08:34 AM DEBUG Config URL for digits is:http://<server>/api/configs/viewByName/ZM_EVENT_IMAGE_DIGITS.json
Dec 4, 2016 08:34 AM DEBUG PortalLogin: auth success
Dec 4, 2016 08:34 AM DEBUG Storing login time as Sun Dec 04 2016 08:34:58 GMT-0600
Dec 4, 2016 08:34 AM DEBUG DataModel: Getting auth from http://<server>/index.php?view=watch&mid=1&connkey=undefined with mid=1
Dec 4, 2016 08:34 AM DEBUG auth-success emit:Successful
Dec 4, 2016 08:34 AM INFO zmAutologin successfully logged into Zoneminder
Dec 4, 2016 08:34 AM INFO Checking if reCaptcha is enabled in ZM...
Dec 4, 2016 08:34 AM INFO zmAutologin called
Dec 4, 2016 08:34 AM DEBUG Resetting zmCookie...
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM DEBUG PIN code entered is correct, or there is no PIN set
Dec 4, 2016 08:34 AM DEBUG unlock called with check PIN=true
Dec 4, 2016 08:34 AM INFO not checking for touchID
Dec 4, 2016 08:34 AM INFO User credentials are provided
Dec 4, 2016 08:34 AM DEBUG Inside Portal login Enter handler
Dec 4, 2016 08:34 AM INFO Entering Portal Main
Dec 4, 2016 08:34 AM DEBUG going to portal login
Dec 4, 2016 08:34 AM DEBUG Last State recorded:{"viewId":"ion17","index":0,"historyId":"ion3","backViewId":null,"forwardViewId":null,"stateId":"log","stateName":"log","url":"/log","canSwipeBack":true,"title":"Logs"}
Dec 4, 2016 08:34 AM DEBUG LogCtrl: resume called, starting log timer
Dec 4, 2016 08:34 AM INFO >>> Resume delayed for 0 ms, to wait for network stack...
Dec 4, 2016 08:34 AM INFO App is resuming from background
Dec 4, 2016 08:34 AM DEBUG LogCtrl: pause called, killing log timer
Dec 4, 2016 08:34 AM DEBUG MontageCtrl: onpause called
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM INFO ROOT APP: Stopping network pull...
Dec 4, 2016 08:34 AM INFO ROOT APP:App is going into background
Dec 4, 2016 08:34 AM INFO Force calling resize
Dec 4, 2016 08:34 AM DEBUG All images loaded
Dec 4, 2016 08:34 AM DEBUG ~~loaded image is loaded for http://<server>/cgi-bin/nph-zms?mode=single&monitor=1&scale=100&auth=d56c6968ab4b07df4954d5728c902b42&rand=25925
Dec 4, 2016 08:34 AM INFO Whoops!! Monitors have changed. I'm resetting layouts, sorry!
Dec 4, 2016 08:34 AM INFO Monitors that are active and not DOM hidden: 1 while grid has undefined
Dec 4, 2016 08:34 AM INFO Did NOT find a packery layout
Dec 4, 2016 08:34 AM INFO Stream authentication construction: &auth=d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM INFO DataModel: Extracted a stream authentication key of: d56c6968ab4b07df4954d5728c902b42
Dec 4, 2016 08:34 AM DEBUG DataModel: Getting auth from http://<server>/index.php?view=watch&mid=1&connkey=502972 with mid=1
Dec 4, 2016 08:34 AM INFO Inside Montage Ctrl:We found 1 monitors
Dec 4, 2016 08:34 AM DEBUG skipping image refresh, packery is still loading
Dec 4, 2016 08:34 AM DEBUG bandwidth: highbw montage refresh set to: 2
Dec 4, 2016 08:34 AM DEBUG Setting image mode to snapshot, will change to image when packery is all done
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM DEBUG API 1.30.0: Monitor 1 will use fid for direct image access
Dec 4, 2016 08:34 AM INFO multi server list loaded{"servers":[]}
Dec 4, 2016 08:34 AM INFO Monitor load was successful, loaded 1 monitors
Dec 4, 2016 08:34 AM DEBUG Now trying to get multi-server data, if present
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM INFO Real-time event: {"reason":"","event":"auth","version":"0.91","type":"","status":"Success"}
Dec 4, 2016 08:34 AM DEBUG Push Notification registration ID received: {"registrationId":"cBiHY5ZEkig:APA91bGhLF4yHCSNRpCldD-U25MbXjMrXQH97xm4pl9F2m_yKUuRA8TfzXmLAihljz_BkQ9-t6jDvxiL4KfddfgfdgyL6dS-Nkbw_6MjQVz9BLlYrkXDHbS"}
Dec 4, 2016 08:34 AM INFO openHandshake: Websocket open
Dec 4, 2016 08:34 AM INFO getMonitors:Loading all monitors
Dec 4, 2016 08:34 AM DEBUG Transitioning state to: montage with param undefined
Dec 4, 2016 08:34 AM DEBUG logging state transition
Dec 4, 2016 08:34 AM INFO Websocket was closed, trying to re-open
Dec 4, 2016 08:34 AM DEBUG Setting up websocket error handler
Dec 4, 2016 08:34 AM INFO Initializing Websocket with URL ws://<server>:9000 , will connect later...
Dec 4, 2016 08:34 AM INFO >>>Setting up push registration
Dec 4, 2016 08:34 AM DEBUG Calling websocket init
Dec 4, 2016 08:34 AM INFO Got API version: 1.30.0
Dec 4, 2016 08:34 AM DEBUG Timezone API error handler, guessing local:America/Chicago
Dec 4, 2016 08:34 AM INFO ZM_EVENT_IMAGE_DIGITS is 5
Dec 4, 2016 08:34 AM DEBUG getAPIversion called
Dec 4, 2016 08:34 AM INFO First invocation of TimeZone, asking server
Dec 4, 2016 08:34 AM DEBUG Config URL for digits is:http://<server>/api/configs/viewByName/ZM_EVENT_IMAGE_DIGITS.json
Dec 4, 2016 08:34 AM DEBUG PortalLogin: auth success
Dec 4, 2016 08:34 AM INFO Stream authentication construction: 
Dec 4, 2016 08:34 AM DEBUG Storing login time as Sun Dec 04 2016 08:34:44 GMT-0600
Dec 4, 2016 08:34 AM INFO Deferring auth key, as monitorId unknown
Dec 4, 2016 08:34 AM DEBUG auth-success emit:Successful
Dec 4, 2016 08:34 AM INFO zmAutologin successfully logged into Zoneminder
Dec 4, 2016 08:34 AM INFO ZM has recaptcha disabled - good
Dec 4, 2016 08:34 AM DEBUG New post dated 2016-12-02 18:33:04 found
Dec 4, 2016 08:34 AM INFO Checking if reCaptcha is enabled in ZM...
Dec 4, 2016 08:34 AM INFO zmAutologin called
Dec 4, 2016 08:34 AM DEBUG Resetting zmCookie...
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM DEBUG PIN code entered is correct, or there is no PIN set
Dec 4, 2016 08:34 AM DEBUG unlock called with check PIN=true
Dec 4, 2016 08:34 AM INFO not checking for touchID
Dec 4, 2016 08:34 AM INFO User credentials are provided
Dec 4, 2016 08:34 AM DEBUG Inside Portal login Enter handler
Dec 4, 2016 08:34 AM DEBUG current version: 1.2.31 & available version 1.2.31
Dec 4, 2016 08:34 AM INFO Entering Portal Main
Dec 4, 2016 08:34 AM INFO >>>>>>>>>>>>>>> All init over, going to portal login
Dec 4, 2016 08:34 AM INFO DataModel init recovered this loginData as {"serverName":"Home","username":"admin",<password removed>,"fallbackConfiguration":"","url":"http://<server>","apiurl":"http://<server>/api","eventServer":"ws://<server>:9000","maxMontage":"100","streamingurl":"http://<server>/cgi-bin","maxFPS":"3","montageQuality":100,"singleImageQuality":"100","montageHistoryQuality":"50","useSSL":false,"keepAwake":true,"isUseAuth":true,"isUseEventServer":true,"disablePush":false,"eventServerMonitors":"1","eventServerInterval":"0","refreshSec":"2","enableLogs":true,"enableDebug":true,"usePin":false,"pinCode":"","canSwipeMonitors":true,"persistMontageOrder":true,"onTapScreen":"Montage","enableh264":true,"gapless":false,"montageOrder":"","montageHiddenOrder":"","montageArraySize":"0","graphSize":2000,"enableAlarmCount":true,"minAlarmCount":1,"montageSize":"3","useNphZms":true,"useNphZmsForEvents":true,"packMontage":false,"exitOnSleep":false,"forceNetworkStop":false,"defaultPushSound":false,"enableBlog":true,"use24hr":false,"packeryPositions":"","packerySizes":"","timelineModalGraphType":"all","resumeDelay":0,"language":"en","reachability":true,"forceImageModePath":false,"disableNative":false,"vibrateOnPush":true,"soundOnPush":true,"cycleMonitors":false,"cycleMonitorsInterval":10,"enableLowBandwidth":false,"autoSwitchBandwidth":false,"refreshSecLowBW":8,"disableAlarmCheckMontage":false,"monSingleImageQuality":100,"EHpackeryPositions":"","useLocalTimeZone":true,"fastLogin":true,"followTimeLine":false,"timelineScale":-1}
Dec 4, 2016 08:34 AM INFO Setting DataModel init bandwidth to: highbw
Dec 4, 2016 08:34 AM DEBUG timeline graph type not set. Setting to all
Dec 4, 2016 08:34 AM INFO user profile encrypted, decoding...
Dec 4, 2016 08:34 AM INFO Setting up pause and resume handler AFTER language is loaded...
Dec 4, 2016 08:34 AM INFO Setting up POST LOGIN timer
Dec 4, 2016 08:34 AM INFO Checking for news updates
Dec 4, 2016 08:34 AM INFO Checking for new version updates...
Dec 4, 2016 08:34 AM INFO No Event Server present. Not initializing
Dec 4, 2016 08:34 AM INFO ZMData init: checking for stored variables & setting up log file
Dec 4, 2016 08:34 AM INFO Language file loaded, continuing with rest
Dec 4, 2016 08:34 AM INFO >>>>Language to be used:en
Dec 4, 2016 08:34 AM INFO Device Language is:en
Dec 4, 2016 08:34 AM INFO Language stored as:en
Dec 4, 2016 08:34 AM INFO App Version: 1.2.31
Dec 4, 2016 08:34 AM INFO Retrieving language before init is called...
Dec 4, 2016 08:34 AM INFO Enabling native transitions...
2016-12-04T14:34:43.308Z INFO Enabling insecure SSL
2016-12-04T14:34:43.303Z INFO Updating statusbar
2016-12-04T14:34:43.297Z INFO >>>>No data to import....
2016-12-04T14:34:42.991Z INFO localforage driver for storage:asyncStorage
2016-12-04T14:34:42.989Z DEBUG text zoom factor is 1
2016-12-04T14:34:42.440Z INFO You are running on android
Dec 4, 2016 08:34 AM INFO Cancelling zmAutologin timer
Dec 4, 2016 08:34 AM INFO ROOT APP: Stopping network pull...
Dec 4, 2016 08:34 AM INFO ROOT APP:App is going into background
Dec 4, 2016 08:34 AM INFO user exited app
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM DEBUG API 1.30.0: Monitor 1 will use fid for direct image access
Dec 4, 2016 08:34 AM INFO multi server list loaded{"servers":[]}
Dec 4, 2016 08:34 AM INFO Monitor load was successful, loaded 1 monitors
Dec 4, 2016 08:34 AM DEBUG Now trying to get multi-server data, if present
Dec 4, 2016 08:34 AM DEBUG Loading hidden/unhidden status...
Dec 4, 2016 08:34 AM INFO getMonitors:Force reloading all monitors
Dec 4, 2016 08:26 AM INFO user profile encrypted, decoding...
Dec 4, 2016 08:26 AM INFO Setting up pause and resume handler AFTER language is loaded...
Dec 4, 2016 08:26 AM INFO Setting up POST LOGIN timer
Dec 4, 2016 08:26 AM INFO Checking for news updates
Dec 4, 2016 08:26 AM INFO Checking for new version updates...
Dec 4, 2016 08:26 AM INFO No Event Server present. Not initializing
Dec 4, 2016 08:26 AM INFO ZMData init: checking for stored variables & setting up log file
Dec 4, 2016 08:26 AM INFO Language file loaded, continuing with rest
Dec 4, 2016 08:26 AM INFO >>>>Language to be used:en
Dec 4, 2016 08:26 AM INFO Device Language is:en
Dec 4, 2016 08:26 AM INFO Language stored as:en
Dec 4, 2016 08:26 AM INFO App Version: 1.2.31
Dec 4, 2016 08:26 AM INFO Retrieving language before init is called...
Dec 4, 2016 08:26 AM INFO Enabling native transitions...
2016-12-04T14:26:46.748Z INFO Enabling insecure SSL
2016-12-04T14:26:46.740Z INFO Updating statusbar
2016-12-04T14:26:46.737Z INFO >>>>No data to import....
2016-12-04T14:26:46.433Z INFO localforage driver for storage:asyncStorage
2016-12-04T14:26:46.431Z DEBUG text zoom factor is 1
2016-12-04T14:26:45.967Z INFO You are running on android

[rajil]

Should there be any indication on the server regarding push notification when an event occurs. I dont see any.

2016-12-04 09:09:56.341418	zma_m1		103811	INF	Closet-1: 3000 - Analysing at 14.93 fps	zm_monitor.cpp	1287
2016-12-04 09:09:54.448007	zmc_m1		104333	INF	Closet-1: 3000 - Capturing at 14.93 fps	zm_monitor.cpp	3116
2016-12-04 09:09:25.975511	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-960608s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:09:20.952489	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-960608s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:09:15.939464	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-960608s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:09:10.918436	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-960608s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:09:05.975519	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-960608s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:09:00.440261	web_php		70694	ERR	socket_sendto( /var/run/zm/zms-736176s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:08:58.272966	web_php		69003	INF	Login successful for user "admin"	includes/functions.php	53
2016-12-04 09:08:49.760962	zma_m1		103811	INF	Closet-1: 2000 - Analysing at 15.15 fps	zm_monitor.cpp	1287
2016-12-04 09:08:47.859263	zmc_m1		104333	INF	Closet-1: 2000 - Capturing at 15.15 fps	zm_monitor.cpp	3116
2016-12-04 09:08:05.592575	web_php		69004	ERR	socket_sendto( /var/run/zm/zms-736176s.sock ) failed: No such file or directory	includes/functions.php	1993
2016-12-04 09:08:00.788800	zms		104079	ERR	Unable to send stream frame: Broken pipe	zm_event.cpp	1348
2016-12-04 09:08:00.505553	zms		103267	ERR	Unable to send stream frame: Broken pipe	zm_event.cpp	1348
2016-12-04 09:07:52.825213	zma_m1		103811	INF	Closet-1: 1145 - Closing event 2778, alarm end	zm_monitor.cpp	1707
2016-12-04 09:07:52.824657	zma_m1		103811	INF	Closet-1: 1145 - Left alarm state (2778) - 100(29) images	zm_monitor.cpp	1702
2016-12-04 09:07:51.133633	zma_m1		103811	INF	Closet-1: 1120 - Gone into alert state	zm_monitor.cpp

[pliablepixels]

Yes there should be. grep your logs for zmeventserver -- generate an event and see if the server gets any notifications - if it does not, you have a problem between zmeventserver and ZM.

Make sure

1. zmeventserver is running and it did not exit
2. when you force an alarm, make sure the monitor is in modect or mocord

[pliablepixels]

is this resolved?

[rajil]

Yes it is. After sometime it connected.

[tecufanujacu]

Same problem here, in the log I have always:
"No Event Server present. Not initializing.

And for most of time I do not receive notifications.