Mobile unable to connect to the event server

rajil commented 7 years ago

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 commented 7 years ago

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 commented 7 years ago

I do have the event server defined as the screen shot shows. 2016-12-04 08 08 23

pliablepixels commented 7 years ago

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

rajil commented 7 years ago

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 commented 7 years ago

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

rajil commented 7 years ago

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 commented 7 years ago

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 commented 7 years ago

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 commented 7 years ago

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 commented 7 years ago

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

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

pliablepixels commented 7 years ago

is this resolved?

rajil commented 7 years ago

Yes it is. After sometime it connected.

tecufanujacu commented 7 years ago

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.

ZoneMinder / zmNinja

Mobile unable to connect to the event server #403