Floating Point Exception

[Pedulla]

UB20.04LTS LEMP
ZM1.36.5

Never seen this before...

Code: Select all

2021-07-31 14:48:07	web_php		27055	ERR	No data to read from socket	includes/functions.php	1902
2021-07-31 14:48:07	web_php		27055	ERR	Timed out waiting for msg /run/zm/zms-959624s.sock	ajax/stream.php	95
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 7: /usr/lib/zoneminder/cgi-bin/nph-zms(+0x42f8e) [0x560b96c83f8e]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 6: /lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xf3) [0x7f75bbb940b3]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 5: /usr/lib/zoneminder/cgi-bin/nph-zms(+0x419bd) [0x560b96c829bd]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 4: /usr/lib/zoneminder/cgi-bin/nph-zms(+0x90960) [0x560b96cd1960]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 3: /usr/lib/zoneminder/cgi-bin/nph-zms(+0xec82f) [0x560b96d2d82f]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 2: /usr/lib/zoneminder/cgi-bin/nph-zms(+0x8c74f) [0x560b96ccd74f]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 1: /lib/x86_64-linux-gnu/libpthread.so.0(+0x153c0) [0x7f75be3673c0]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Backtrace 0: /usr/lib/zoneminder/cgi-bin/nph-zms(+0xe64ca) [0x560b96d274ca]	zm_signal.cpp	100
2021-07-31 14:48:06	zms_m2		29835	ERR	Signal address is 0x560b96ccd74f, from 0x560b96ccd74f	zm_signal.cpp	79
2021-07-31 14:48:06	zms_m2		29835	ERR	Got signal 8 (Floating point exception), crashing

Hardware failing?
-or-
Misconfigured buffers?

[SBCDave]

Following.

I'm having the same problem. My recordings work, but I can't watch the stream live and my zms logs for this camera are full of these nph-zms floating point exceptions.

[iconnor]

It's likely a div by zero. We just gotta figure out where. Unfortunately backtraces are almost entirely useless these days. We are going to need a debug level 3 logs for zms.

[SteveGilvarry]

Logging instructions have information on how to do debug logs, on component _zms in this case I believe.
https://zoneminder.readthedocs.io/en/la ... gging.html

[SBCDave]

@SteveGilvarry thanks for the direction

@iconnor Thanks for everything you do

Here is my log level 3 output from that zms instance

I'm just now looking through it too. My son was born a few days ago and I'm a bit out of it, but I could tell that I needed to at least change my username and my salt now so I don't have to go through and redact all of this haha.

Code: Select all

dave@server:/var/log/zm$ tail -f zms_m3.log
08/10/21 23:09:14.358945 zms_m3[612959].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:14.359060 zms_m3[612959].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:14.359130 zms_m3[612959].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:14.359143 zms_m3[612959].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:14.359151 zms_m3[612959].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:14.360631 zms_m3[612959].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:14.360648 zms_m3[612959].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:14.360960 zms_m3[612959].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:14.372719 zms_m3[612959].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:14.396233 zms_m3[612959].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:14.396283 zms_m3[612959].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:14.396291 zms_m3[612959].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:14.396306 zms_m3[612959].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:14.396312 zms_m3[612959].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:14.396319 zms_m3[612959].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:14.396328 zms_m3[612959].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:14.396337 zms_m3[612959].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:14.396359 zms_m3[612959].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:14.396369 zms_m3[612959].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:14.396382 zms_m3[612959].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:14.396396 zms_m3[612959].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:14.396404 zms_m3[612959].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:14.396416 zms_m3[612959].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:14.396430 zms_m3[612959].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:14.396445 zms_m3[612959].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:14.396454 zms_m3[612959].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662154 - 1628662154 = 0  < 45.000000]
08/10/21 23:09:14.396468 zms_m3[612959].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:14.396474 zms_m3[612959].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 10 text frame 'Unable to send image']08/10/21 23:09:14.402048 zms_m3[612959].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:14.402101 zms_m3[612959].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:14.402112 zms_m3[612959].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:14.402144 zms_m3[612959].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:14.402153 zms_m3[612959].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:14.402158 zms_m3[612959].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:14.402279 zms_m3[612959].DB1-zms.cpp/342 [Terminating]
08/10/21 23:09:14.784368 zms_m3[612975].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:14.784494 zms_m3[612975].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:14.784574 zms_m3[612975].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:14.784592 zms_m3[612975].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:14.784604 zms_m3[612975].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:14.784906 zms_m3[612975].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:14.784927 zms_m3[612975].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:14.785303 zms_m3[612975].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:14.785807 zms_m3[612975].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:14.810026 zms_m3[612975].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:14.810072 zms_m3[612975].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:14.810080 zms_m3[612975].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:14.810092 zms_m3[612975].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:14.810098 zms_m3[612975].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:14.810105 zms_m3[612975].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:14.810113 zms_m3[612975].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:14.810121 zms_m3[612975].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:14.810144 zms_m3[612975].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:14.810152 zms_m3[612975].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:14.810165 zms_m3[612975].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:14.810179 zms_m3[612975].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:14.810187 zms_m3[612975].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:14.810199 zms_m3[612975].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:14.810212 zms_m3[612975].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:14.810226 zms_m3[612975].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:14.810240 zms_m3[612975].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662154 - 1628662154 = 0  < 45.000000]
08/10/21 23:09:14.810259 zms_m3[612975].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:14.810265 zms_m3[612975].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 10 text frame 'Unable to send image']08/10/21 23:09:14.815557 zms_m3[612975].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:14.815592 zms_m3[612975].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:14.815599 zms_m3[612975].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:14.815626 zms_m3[612975].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:14.815634 zms_m3[612975].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:14.815639 zms_m3[612975].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:14.815748 zms_m3[612975].DB1-zms.cpp/342 [Terminating]
08/10/21 23:09:15.595030 zms_m3[612994].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:15.595145 zms_m3[612994].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:15.595238 zms_m3[612994].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:15.595253 zms_m3[612994].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:15.595262 zms_m3[612994].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:15.653838 zms_m3[613015].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:15.653913 zms_m3[613015].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:15.653978 zms_m3[613015].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:15.653991 zms_m3[613015].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:15.654000 zms_m3[613015].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:15.658546 zms_m3[613015].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:15.658571 zms_m3[613015].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:15.658870 zms_m3[613015].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:15.659346 zms_m3[613015].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:15.659533 zms_m3[613015].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:15.659549 zms_m3[613015].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:15.659557 zms_m3[613015].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:15.659572 zms_m3[613015].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:15.659590 zms_m3[613015].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:15.659597 zms_m3[613015].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:15.659605 zms_m3[613015].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:15.659613 zms_m3[613015].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:15.659631 zms_m3[613015].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:15.659645 zms_m3[613015].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:15.659664 zms_m3[613015].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:15.659679 zms_m3[613015].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:15.659687 zms_m3[613015].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:15.659700 zms_m3[613015].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:15.659718 zms_m3[613015].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:15.659737 zms_m3[613015].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:15.659749 zms_m3[613015].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662155 - 1628662155 = 0  < 45.000000]
08/10/21 23:09:15.659767 zms_m3[613015].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:15.659774 zms_m3[613015].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 50 text frame 'Unable to send image']08/10/21 23:09:15.673226 zms_m3[612994].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:15.673973 zms_m3[612994].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:15.674320 zms_m3[612994].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:15.675293 zms_m3[613015].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.675332 zms_m3[613015].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.675341 zms_m3[613015].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.675377 zms_m3[613015].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.675389 zms_m3[613015].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.675395 zms_m3[613015].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.675510 zms_m3[613015].DB1-zms.cpp/342 [Terminating]
08/10/21 23:09:15.839194 zms_m3[612994].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:15.839604 zms_m3[612994].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:15.839640 zms_m3[612994].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:15.839649 zms_m3[612994].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:15.839661 zms_m3[612994].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:15.839669 zms_m3[612994].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:15.839676 zms_m3[612994].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:15.839684 zms_m3[612994].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:15.839692 zms_m3[612994].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:15.839718 zms_m3[612994].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:15.839728 zms_m3[612994].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:15.839744 zms_m3[612994].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:15.839759 zms_m3[612994].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:15.839767 zms_m3[612994].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:15.839779 zms_m3[612994].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:15.839800 zms_m3[612994].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:15.839821 zms_m3[612994].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:15.839831 zms_m3[612994].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662155 - 1628662155 = 0  < 45.000000]
08/10/21 23:09:15.839849 zms_m3[612994].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:15.839856 zms_m3[612994].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 10 text frame 'Unable to send image']08/10/21 23:09:15.845251 zms_m3[612994].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.845284 zms_m3[612994].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.845291 zms_m3[612994].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.845316 zms_m3[612994].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.845324 zms_m3[612994].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.845329 zms_m3[612994].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.845452 zms_m3[612994].DB1-zms.cpp/342 [Terminating]
08/10/21 23:09:15.957454 zms_m3[613033].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:15.957533 zms_m3[613033].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:15.957605 zms_m3[613033].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:15.957618 zms_m3[613033].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:15.957640 zms_m3[613033].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:15.957959 zms_m3[613033].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:15.957973 zms_m3[613033].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:15.958260 zms_m3[613033].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:15.958663 zms_m3[613033].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:15.977140 zms_m3[613033].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:15.977201 zms_m3[613033].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:15.977209 zms_m3[613033].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:15.977225 zms_m3[613033].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:15.977232 zms_m3[613033].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:15.977239 zms_m3[613033].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:15.977249 zms_m3[613033].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:15.977263 zms_m3[613033].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:15.977298 zms_m3[613033].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:15.977309 zms_m3[613033].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:15.977328 zms_m3[613033].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:15.977344 zms_m3[613033].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:15.977354 zms_m3[613033].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:15.977371 zms_m3[613033].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:15.977390 zms_m3[613033].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:15.977410 zms_m3[613033].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:15.977422 zms_m3[613033].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662155 - 1628662155 = 0  < 45.000000]
08/10/21 23:09:15.977442 zms_m3[613033].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:15.977461 zms_m3[613033].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 50 text frame 'Unable to send image']08/10/21 23:09:15.997253 zms_m3[613033].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.997298 zms_m3[613033].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.997309 zms_m3[613033].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.997339 zms_m3[613033].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:15.997350 zms_m3[613033].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:15.997357 zms_m3[613033].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:15.997514 zms_m3[613033].DB1-zms.cpp/342 [Terminating]
08/10/21 23:09:16.981425 zms_m3[613059].DB1-zm_logger.cpp/232 [LogOpts: level=DB3 effective=DB3, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zms_m3.log, syslog=ERR]
08/10/21 23:09:16.981503 zms_m3[613059].DB1-zm_user.cpp/139 [Inside zmLoadTokenUser, formed key=ThisIsOurSuperiorSaltValueForTheHashSecret]
08/10/21 23:09:16.981564 zms_m3[613059].DB1-zm_crypt.cpp/105 [Got watcher as user claim from token]
08/10/21 23:09:16.981577 zms_m3[613059].DB1-zm_crypt.cpp/113 [Got IAT token=1628662148]
08/10/21 23:09:16.981585 zms_m3[613059].DB1-zm_user.cpp/144 [retrieved user 'watcher' from token]
08/10/21 23:09:16.981894 zms_m3[613059].DB1-zm_user.cpp/176 [Authenticated user 'watcher' via token with last revoke time: 0]
08/10/21 23:09:16.981909 zms_m3[613059].DB1-zm_utils.cpp/218 [Detected a x86\x86-64 processor with AVX]
08/10/21 23:09:16.982170 zms_m3[613059].DB2-zm_stream.h/209 [Setting replay_rate 100]
08/10/21 23:09:16.998128 zms_m3[613059].DB2-zm_storage.cpp/62 [Loading Storage for 1 using SELECT `Id`, `Name`, `Path`, `Type`, `Scheme` FROM `Storage` WHERE `Id`=1]
08/10/21 23:09:17.005647 zms_m3[613059].DB1-zm_storage.cpp/80 [Loaded Storage area 1 'Default']
08/10/21 23:09:17.005712 zms_m3[613059].DB1-zm_monitor.cpp/482 [Have camera type Ffmpeg]
08/10/21 23:09:17.005721 zms_m3[613059].DB1-zm_monitor.cpp/2322 [Reloading linked monitors for monitor NW-SW Front Yard, '(null)']
08/10/21 23:09:17.005736 zms_m3[613059].DB1-zm_packetqueue.cpp/660 [Setting pre_event_video_packet_count to 20]
08/10/21 23:09:17.005743 zms_m3[613059].DB1-zm_packetqueue.cpp/654 [Setting max_video_packet_count to 0]
08/10/21 23:09:17.005750 zms_m3[613059].DB1-zm_monitor.cpp/644 [mem.size(8) SharedData=760 TriggerData=560 VideoStoreData=4128 timestamps=48 images=3x8294400 = 24883200 total=24888760]
08/10/21 23:09:17.005759 zms_m3[613059].DB1-zm_monitor.cpp/682 [Loaded monitor 3(NW-SW Front Yard), 0 zones]
08/10/21 23:09:17.005767 zms_m3[613059].DB3-zm_monitor.cpp/906 [Connecting to monitor.  Purpose is 0]
08/10/21 23:09:17.005797 zms_m3[613059].DB3-zm_monitor.cpp/919 [Success opening mmap file at (/dev/shm/zm.mmap.3)]
08/10/21 23:09:17.005812 zms_m3[613059].DB3-zm_monitor.cpp/952 [MMap file size is 24888760]
08/10/21 23:09:17.005829 zms_m3[613059].DB1-zm_monitor.cpp/957 [Unable to map file /dev/shm/zm.mmap.3 (24888760 bytes) to locked memory, trying unlocked]
08/10/21 23:09:17.005844 zms_m3[613059].DB1-zm_monitor.cpp/960 [Mapped file /dev/shm/zm.mmap.3 (24888760 bytes) to unlocked memory]
08/10/21 23:09:17.005853 zms_m3[613059].DB3-zm_monitor.cpp/993 [Aligning shared memory images to the next 64 byte boundary]
08/10/21 23:09:17.005868 zms_m3[613059].DB2-zm_camera.cpp/69 [New camera id: 3 width: 1920 line size: 7680 height: 1080 colours: 4 subpixelorder: 8 capture: 0]
08/10/21 23:09:17.005882 zms_m3[613059].DB3-zm_monitor.cpp/1003 [Allocated 3 3 image buffers]
08/10/21 23:09:17.005896 zms_m3[613059].DB3-zm_monitor.cpp/1056 [Success connecting]
08/10/21 23:09:17.005905 zms_m3[613059].DB3-zm_monitor.h/446 [Shared data is valid, checking heartbeat 1628662157 - 1628662156 = 1  < 45.000000]
08/10/21 23:09:17.005922 zms_m3[613059].DB1-zm_stream.cpp/80 [Monitor is not decoding.]
08/10/21 23:09:17.005929 zms_m3[613059].DB2-zm_stream.cpp/257 [Sending 1920x1080 * 50 text frame 'Unable to send image']08/10/21 23:09:17.026160 zms_m3[613059].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:17.026198 zms_m3[613059].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:17.026209 zms_m3[613059].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:17.026240 zms_m3[613059].DB1-zm_packetqueue.cpp/328 [Clearing packetqueue]
08/10/21 23:09:17.026252 zms_m3[613059].DB1-zm_packetqueue.cpp/348 [Packetqueue is clear, deleting iterators]
08/10/21 23:09:17.026260 zms_m3[613059].DB1-zm_packetqueue.cpp/363 [Packetqueue is clear, notifying]
08/10/21 23:09:17.026390 zms_m3[613059].DB1-zms.cpp/342 [Terminating]

[SBCDave]

Hi again, looking through the logs and googling brought me full circle to this thread viewtopic.php?t=30654
Where I asked how to enable decoding, which apparently works for others. Can anyone here explain what they mean when they say switch from ffmpeg to libvlc then back to ffmpeg (with decoding)?

[dougmccrary]

Some how you're just not seeing it.
On the other thread you list what you have, which includes ffmpeg. And libvlc just below it. "Source Type"

Congrats on the kid - I think you're still a little tapped out

[SBCDave]

All my other cameras use ffmpeg and work. This camera uses source type: ffmpeg also but doesn't work. It seemed like the other people were saying there was a differnce between ffmpeg and "ffmpeg with decoding" and I think I may just be missing the "with decoding". I assumed it meant I needed to add encoding options in storage, but I'm reaching.

My log level 9 logs on zms show "Monitor not decoding" followed by clearing packets and then terminating, so I'm hoping I just need to figure out how to turn on decoding.

When I used ffmpeg -i rtps:.... output.mp4 from the same server that hosts my zm, the output.mp4 file works and is viewable. I don't know if zms even uses ffmpeg though. Sorry for my ignorance. Any ideas on how I can get this camera to live stream are greatly appreciated, and yes I am still very tired haha thanks for the congrats, he's pretty cool.

[dougmccrary]

You may have already said, but what "Function" are you using? That's where the decoding is.
Not sure what it means, but this looks suspicious:
08/10/21 23:09:14.396337 zms_m3[612959].DB3-zm_monitor.cpp/906 [Connecting to monitor. Purpose is 0]

Have you checked that against one of your working monitors?

[SBCDave]

My function on all cameras is mocord. I also set up a duplicate of this camera with the monitor function and it does the same thing. Good call on the "Purpose is 0" seeming odd. I'll look through the zm_monitor.cpp code and see if I can find any leads there, thanks.

[SBCDave]

I can't make sense enough of zm_monitor to figure out what purpose is, but its a type and gets compared to CAPTURE, so it does seem pretty odd that it would be zero. When it's not CAPTURE though it ends up going into the memory mapping, so maybe 0 is just a way of using it

[dougmccrary]

Dave, does this camera "look" the same as your other ones when viewed with VLC, the program? Ditto ZM's ONVIF scan?

[SBCDave]

I don't know what you mean by "Ditto ZM's ONVIF scan?"

This camera has a much wider angle than the others and higher pixel resolution.

[dougmccrary]

"Ditto ZM's ONVIF scan?"

Means does zm onvif scan detect the camera, and do those suggested settings work.

So this camera is sending a lot more data than the others. Does it make any difference if you set the frame rate of the camera lower? Assuming you can, that is. Also, is there a lower resolution stream you can use, at least for testing?

[SBCDave]

I'll do some googling on the ONVIF scan. I've never used that. I thought ONVIF was only for cameras with PTZ. None of mine have PTZ

I tried reducing fps and resolution as low as I could on main and sub streams and they still say Unable to stream.

I'll try to get log level 3 on a working zms for comparison, and recheck my log level 3 on zms after reducing the fps and resolution again. Thanks for the ideas