Irregular behaviour. Lots of 255 errors. Shmmax correct size

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 7:31 pm

Hmm changed debug to remove the "+", and restarted zoneminder

Now the debug log file is empty save one line

11/10/08 14:28:17.467057 zmfix[9884].INF-zm_debug.c/304 [New Debug Level = 5, New Debug Log = /tmp/zm_debug.log]

camera feed is still broken

11/10/2008 14:28:17.640051 zmdc[9886].INF [Server starting at 08/11/10 14:28:17]
11/10/2008 14:28:20.859048 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:28:20, pid = 9912]
11/10/2008 14:28:21.072575 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:28:21.073132 zmdc[9886].INF [Starting pending process, zmc -m 5]
11/10/2008 14:28:21.098827 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:28:21, pid = 9914]
11/10/2008 14:28:21.222757 zmdc[9886].INF ['zmfilter.pl' starting at 08/11/10 14:28:21, pid = 9915]
11/10/2008 14:28:21.452471 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:28:21.650734 zmdc[9886].INF ['zmaudit.pl -c' starting at 08/11/10 14:28:21, pid = 9917]
11/10/2008 14:28:22.078051 zmdc[9886].INF ['zmwatch.pl' starting at 08/11/10 14:28:22, pid = 9919]
11/10/2008 14:28:26.078501 zmdc[9886].INF [Starting pending process, zmc -m 5]
11/10/2008 14:28:26.106843 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:28:26, pid = 9924]
11/10/2008 14:28:26.219714 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:28:36.018481 zmdc[9886].INF [Starting pending process, zmc -m 5]
11/10/2008 14:28:36.047331 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:28:36, pid = 9926]
11/10/2008 14:28:36.159739 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:28:51.638848 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:28:51, pid = 9940]
11/10/2008 14:28:51.752537 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:08.798844 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:08, pid = 9947]
11/10/2008 14:29:08.966888 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:14.646863 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:14, pid = 9950]
11/10/2008 14:29:14.755581 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:21.850854 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:21, pid = 9953]
11/10/2008 14:29:21.960368 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:29.002910 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:29, pid = 9957]
11/10/2008 14:29:29.122066 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:39.207317 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:39, pid = 9959]
11/10/2008 14:29:39.322759 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:49.406846 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:49, pid = 9961]
11/10/2008 14:29:49.574272 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:29:59.614861 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:29:59, pid = 9963]
11/10/2008 14:29:59.727754 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:30:09.819217 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:30:09, pid = 9966]
11/10/2008 14:30:09.984238 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:30:20.030852 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:30:20, pid = 9968]
11/10/2008 14:30:20.265656 zmdc[9886].ERR ['zmc -m 5' exited abnormally, exit status 255]
11/10/2008 14:30:30.238859 zmdc[9886].INF ['zmc -m 5' starting at 08/11/10 14:30:30, pid =

but no debug.

Grrrr

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 7:32 pm

almost forgot zmwatch

11/10/08 14:28:38.582618 zmwatch[9928].INF [Watchdog starting]
11/10/08 14:28:38.598497 zmwatch[9928].INF [Watchdog pausing for 30 seconds]
11/10/08 14:29:08.601136 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 8 seconds (1226345348-1226345340)]
11/10/08 14:29:08.803096 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:08, pid = 9947]
11/10/08 14:29:14.439896 zmwatch[9921].INF [Restarting capture daemon for Back, time since last capture 6 seconds (1226345354-1226345348)]
11/10/08 14:29:14.651253 zmwatch[9921].INF ['zmc -m 5' starting at 08/11/10 14:29:14, pid = 9950]
11/10/08 14:29:28.805948 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 7 seconds (1226345368-1226345361)]
11/10/08 14:29:29.007182 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:29, pid = 9957]
11/10/08 14:29:39.008896 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345379-1226345369)]
11/10/08 14:29:39.211499 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:39, pid = 9959]
11/10/08 14:29:49.213284 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345389-1226345379)]
11/10/08 14:29:49.411149 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:49, pid = 9961]
11/10/08 14:29:59.412827 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345399-1226345389)]
11/10/08 14:29:59.619136 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:59, pid = 9963]
11/10/08 14:30:09.620822 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345409-1226345399)]
11/10/08 14:30:09.826335 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:09, pid = 9966]
11/10/08 14:30:19.828063 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345419-1226345409)]
11/10/08 14:30:20.035111 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:20, pid = 9968]
11/10/08 14:30:30.036790 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345430-1226345420)]
11/10/08 14:30:30.243192 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:30, pid = 9972]
11/10/08 14:30:40.244835 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345440-1226345430)]
11/10/08 14:30:40.439333 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:40, pid = 9975]
11/10/08 14:30:50.441052 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345450-1226345440)]
11/10/08 14:30:50.639136 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:50, pid = 9977]
11/10/08 14:31:00.640852 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345460-1226345450)]
11/10/08 14:31:00.851128 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:00, pid = 9979]
11/10/08 14:31:10.852846 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345470-1226345460)]
11/10/08 14:31:11.063242 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:11, pid = 9981]
11/10/08 14:31:21.064937 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345481-1226345471)]
11/10/08 14:31:21.266518 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:21, pid = 9983]
11/10/08 14:31:31.268165 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345491-1226345481)]
11/10/08 14:31:31.479181 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:31, pid = 9985]
11/10/08 14:31:41.480911 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345501-1226345491)]
11/10/08 14:31:41.679194 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:41, pid = 9987]
11/10/08 14:31:51.680888 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345511-1226345501)]
11/10/08 14:31:51.871226 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:51, pid = 9991]

Post by **cordel** » Mon Nov 10, 2008 7:44 pm

Okay so I can see them completing for a bit then error out after a while, my hunch would be that you might be requesting images faster than the cam can keep up.
If you want a high frame rate from that cam, might search the forum and see if anyone has posted a path for mjpeg push which the cam should have no problem with.

Otherwise you could try to lower the rate of requested jpeg snapshots on ZM and see if it stabilizes.

Just for info:
You can limit fps for jpeg snapshots in zm but not mjpeg push. Mjpeg push has to be limited by the camera and ZM should have null values for fps or you will get errors.
Just so we don't go here

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 7:49 pm

Setting max frame rate to 5 in the config made it work. Thanks Cordel.

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 8:04 pm

Maybe spoke to soon. Pursuing recording and event issues now. Seems to be still crashing

11/10/08 14:28:38.582618 zmwatch[9928].INF [Watchdog starting]
11/10/08 14:28:38.598497 zmwatch[9928].INF [Watchdog pausing for 30 seconds]
11/10/08 14:29:08.601136 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 8 seconds (1226345348-1226345340)]
11/10/08 14:29:08.803096 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:08, pid = 9947]
11/10/08 14:29:14.439896 zmwatch[9921].INF [Restarting capture daemon for Back, time since last capture 6 seconds (1226345354-1226345348)]
11/10/08 14:29:14.651253 zmwatch[9921].INF ['zmc -m 5' starting at 08/11/10 14:29:14, pid = 9950]
11/10/08 14:29:28.805948 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 7 seconds (1226345368-1226345361)]
11/10/08 14:29:29.007182 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:29, pid = 9957]
11/10/08 14:29:39.008896 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345379-1226345369)]
11/10/08 14:29:39.211499 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:39, pid = 9959]
11/10/08 14:29:49.213284 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345389-1226345379)]
11/10/08 14:29:49.411149 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:49, pid = 9961]
11/10/08 14:29:59.412827 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345399-1226345389)]
11/10/08 14:29:59.619136 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:29:59, pid = 9963]
11/10/08 14:30:09.620822 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345409-1226345399)]
11/10/08 14:30:09.826335 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:09, pid = 9966]
11/10/08 14:30:19.828063 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345419-1226345409)]
11/10/08 14:30:20.035111 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:20, pid = 9968]
11/10/08 14:30:30.036790 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345430-1226345420)]
11/10/08 14:30:30.243192 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:30, pid = 9972]
11/10/08 14:30:40.244835 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345440-1226345430)]
11/10/08 14:30:40.439333 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:40, pid = 9975]
11/10/08 14:30:50.441052 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345450-1226345440)]
11/10/08 14:30:50.639136 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:30:50, pid = 9977]
11/10/08 14:31:00.640852 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345460-1226345450)]
11/10/08 14:31:00.851128 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:00, pid = 9979]
11/10/08 14:31:10.852846 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345470-1226345460)]
11/10/08 14:31:11.063242 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:11, pid = 9981]
11/10/08 14:31:21.064937 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345481-1226345471)]
11/10/08 14:31:21.266518 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:21, pid = 9983]
11/10/08 14:31:31.268165 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345491-1226345481)]
11/10/08 14:31:31.479181 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:31, pid = 9985]
11/10/08 14:31:41.480911 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345501-1226345491)]
11/10/08 14:31:41.679194 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:41, pid = 9987]
11/10/08 14:31:51.680888 zmwatch[9928].INF [Restarting capture daemon for Back, time since last capture 10 seconds (1226345511-1226345501)]
11/10/08 14:31:51.871226 zmwatch[9928].INF ['zmc -m 5' starting at 08/11/10 14:31:51, pid = 9991]

I would say this is a resource issue but top says no

top - 15:04:04 up 2:52, 1 user, load average: 0.84, 0.56, 0.38
Tasks: 109 total, 2 running, 107 sleeping, 0 stopped, 0 zombie
Cpu(s): 33.3%us, 0.7%sy, 0.0%ni, 65.7%id, 0.0%wa, 0.0%hi, 0.3%si, 0.0%st
Mem: 773760k total, 538692k used, 235068k free, 25392k buffers
Swap: 2265124k total, 0k used, 2265124k free, 323628k cached

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 8:05 pm

Scaling back to 1 FPS on the big camera to see if it helps

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 8:10 pm

zmdc is quiet now, but not getting any images from any of the cameras...

I set them to record and now no images

I set them back to monitor and no images still

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 8:13 pm

disregard. Switfweasel being buggy. Tried in normal Firefox and behaving. Will be testing until tomorrow. I'll update more.

paperstreet · Post by **paperstreet** » Mon Nov 10, 2008 8:16 pm

Going to see why two cameras events keep ending prematurely

297 New Event 11/10 15:15:55 0.00 / 0/0
296 Event-296 11/10 15:15:54 2.25 13/0 0/0
294 Event-294 11/10 15:15:53 1.44 12/0 0/0
293 Event-293 11/10 15:15:51 2.29 15/0 0/0
292 Event-292 11/10 15:15:46 6.33 22/0 0/0
291 Event-291 11/10 15:15:44 3.09 13/0 0/0
290 Event-290 11/10 15:15:40 6.07 18/0 0/0
289 Event-289 11/10 15:15:36 5.28 12/0 0/0
288 Event-288 11/10 15:15:12 28.80 54/0 0/0
287 Event-287 11/10 15:15:09 4.54 15/0 0/0

zmdc reports all is normal

11/10/2008 15:11:48.094523 zmdc[11629].INF [Starting pending process, zmc -m 3]
11/10/2008 15:11:48.150243 zmdc[11629].INF ['zmc -m 3' starting at 08/11/10 15:11:48, pid = 11733]
11/10/2008 15:12:41.446295 zmdc[11629].INF ['zmc -m 1' already running at 08/11/10 15:11:10, pid = 11651]
11/10/2008 15:12:41.671821 zmdc[11629].INF ['zma -m 1' starting at 08/11/10 15:12:41, pid = 11945]
11/10/2008 15:12:48.452929 zmdc[11629].INF ['zmc -m 2' already running at 08/11/10 15:11:10, pid = 11653]
11/10/2008 15:12:48.740936 zmdc[11629].INF ['zma -m 2' starting at 08/11/10 15:12:48, pid = 11965]
11/10/2008 15:12:55.648790 zmdc[11629].INF ['zmc -m 3' already running at 08/11/10 15:11:48, pid = 11733]
11/10/2008 15:12:55.855281 zmdc[11629].INF ['zma -m 3' starting at 08/11/10 15:12:55, pid = 11985]
11/10/2008 15:13:43.632189 zmdc[11629].INF ['zmc -m 5' already running at 08/11/10 15:11:12, pid = 11669]
11/10/2008 15:13:43.909629 zmdc[11629].INF ['zma -m 5' starting at 08/11/10 15:13:43, pid = 12009]
11/10/2008 15:17:09.955615 zmdc[11629].INF ['zmc -m 1' already running at 08/11/10 15:11:10, pid = 11651]
11/10/2008 15:17:10.185266 zmdc[11629].WAR [Can't find process with command of 'zmtrack.pl -m 1']
11/10/2008 15:17:10.459113 zmdc[11629].INF ['zma -m 1' stopping at 08/11/10 15:17:10]
11/10/2008 15:17:10.461455 zmdc[11629].INF ['zma -m 1' exited normally]

Post by **cordel** » Mon Nov 10, 2008 9:29 pm

I got to get back to work but really quick, you will find the best detail for IPcams in the debug logs if your dealing with errors from the cams. Also if it's a zones thing, maybe your post buffer is not long enough, or the reference image is being updated to fast, or even to slow. There are a ton of variables when dealing with the zones. you might need to reduce your filtered pixels width too, hard to say with out knowing the zone config, and seeing stats.

If it's a zones deal, open a new thread for it, separate topics are better to follow in a separate thread. Otherwise if you camera is still throwing errors, keep it here.