[Solved] zmc: Unable to open RTSP input, invalid data found when processing input

Forum for questions and support relating to the 1.32.x releases only.
Post Reply
vitus
Posts: 12
Joined: Tue Jul 09, 2019 2:08 am

[Solved] zmc: Unable to open RTSP input, invalid data found when processing input

Post by vitus »

Zoneminder version: 1.32 (commit d1ac88d3018bb29221763effef489fca9f248690)
Camera: Generic RTSP Android App
Camera Configuration: RTSP, tcp, Camera passthrough, no framerate set. Full dump here: https://pastebin.com/aWiaciDt

Issue: Can't view stream via zms to the Monitor tab. A URL such as [1] produces an empty 200 response when normally it would stream the camera.

Logs: The primary error in the logs is

Code: Select all

+-------+-------------------+-----------+----------+------+-------+------+---------------------------------------------------------------------------------------------------------------------------------+----------------------+------+
| Id    | TimeKey           | Component | ServerId | Pid  | Level | Code | Message                                                                                                                         | File                 | Line |
+-------+-------------------+-----------+----------+------+-------+------+---------------------------------------------------------------------------------------------------------------------------------+----------------------+------+
| 16060 | 1568246086.344402 | zmc_m1    |        0 | 2711 |    -2 | ERR  | Unable to open input rtsp://10.1.2.2:5554 due to: Invalid data found when processing input                                      | zm_ffmpeg_camera.cpp |  372 |
This appears to come straight from ffmpeg, but here's the thing: VLC opens it just fine. I'm not doing anything special in VLC, just running "vlc rtsp://cam-front-gate:5554" works, which is the same string I give to zoneminder.

Complete Logs:

Code: Select all

| 22430 | 1568258393.670416 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Failed to prime capture of initial monitor                                                                                                                                         |
| 22429 | 1568258393.646139 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22428 | 1568258393.405024 | zmc_m1    |        0 | 8472 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22427 | 1568258383.374758 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Failed to prime capture of initial monitor                                                                                                                                         |
| 22426 | 1568258383.343053 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22425 | 1568258383.177687 | zmc_m1    |        0 | 8472 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22424 | 1568258373.117898 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Failed to prime capture of initial monitor                                                                                                                                         |
| 22423 | 1568258373.091632 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22422 | 1568258372.916103 | zmc_m1    |        0 | 8472 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22421 | 1568258362.886037 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Failed to prime capture of initial monitor                                                                                                                                         |
| 22420 | 1568258362.853391 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22419 | 1568258362.687057 | zmc_m1    |        0 | 8472 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22418 | 1568258355.175948 | zms_m1    |        0 | 8475 |    -1 | WAR  | no last_frame_sent.  Shouldn't happen. frame_mod was (1) frame_count (0)                                                                                                           |
| 22417 | 1568258354.501515 | web_js    |     NULL | 8133 |    -2 | ERR  | getStreamCmdResponse stream error: Socket /var/lib/zoneminder/sock/zms-128858s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not r|
| 22416 | 1568258354.303637 | web_php   |     NULL | 8133 |    -2 | ERR  | Socket /var/lib/zoneminder/sock/zms-128858s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not run, or zms exited early.  Please ch|
| 22415 | 1568258352.648101 | zmc_m1    |        0 | 8472 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22414 | 1568258352.506683 | zmc_m1    |        0 | 8472 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22413 | 1568258352.480939 | zmc_m1    |        0 | 8472 |     0 | INF  | Starting Capture version 1.33.14                                                                                                                                                   |
| 22412 | 1568258352.411359 | zmc_m1    |        0 | 8472 |     0 | INF  | Not enabling ffmpeg logs, as LOG_FFMPEG and/or LOG_DEBUG is disabled in options, or this monitor not part of your debug targets                                                    |
| 22411 | 1568258352.317420 | zmdc      |     NULL | 8472 |     0 | INF  | 'zmc -m 1' started at 19/09/11 20:19:12                                                                                                                                            |
| 22410 | 1568258352.315350 | zmdc      |     NULL | 8141 |     0 | INF  | 'zmc -m 1' starting at 19/09/11 20:19:12, pid = 8472                                                                                                                               |
| 22409 | 1568258352.279070 | zmdc      |     NULL | 8141 |     0 | INF  | Starting pending process, zmc -m 1                                                                                                                                                 |
| 22408 | 1568258351.426968 | zms_m1    |        0 | 8471 |    -2 | ERR  | Unable to connect to zmc process for monitor 1                                                                                                                                     |
| 22407 | 1568258351.398100 | zms_m1    |        0 | 8471 |    -2 | ERR  | Unable to connect to monitor id 1 for streaming                                                                                                                                    |
| 22406 | 1568258351.373665 | zms_m1    |        0 | 8471 |    -2 | ERR  | Got empty memory map file size 0, is the zmc process for this monitor running?                                                                                                     |
| 22405 | 1568258351.170637 | web_js    |     NULL | 8221 |    -2 | ERR  | getStreamCmdResponse stream error: Socket /var/lib/zoneminder/sock/zms-221625s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not r|
| 22404 | 1568258350.649053 | web_php   |     NULL | 8221 |    -2 | ERR  | Socket /var/lib/zoneminder/sock/zms-221625s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not run, or zms exited early.  Please ch|
| 22403 | 1568258348.753119 | zms_m1    |        0 | 8468 |    -2 | ERR  | Unable to connect to zmc process for monitor 1                                                                                                                                     |
| 22402 | 1568258348.723958 | zms_m1    |        0 | 8468 |    -2 | ERR  | Unable to connect to monitor id 1 for streaming                                                                                                                                    |
| 22401 | 1568258348.691320 | zms_m1    |        0 | 8468 |    -2 | ERR  | Got empty memory map file size 0, is the zmc process for this monitor running?                                                                                                     |
| 22400 | 1568258348.507319 | web_js    |     NULL | 8221 |    -2 | ERR  | getStreamCmdResponse stream error: Socket /var/lib/zoneminder/sock/zms-368376s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not r|
| 22399 | 1568258348.308356 | web_php   |     NULL | 8221 |    -2 | ERR  | Socket /var/lib/zoneminder/sock/zms-368376s.sock does not exist.  This file is created by zms, and since it does not exist, either zms did not run, or zms exited early.  Please ch|
| 22398 | 1568258346.146300 | zmdc      |     NULL | 8141 |     0 | INF  | 'zmc -m 1' exited normally                                                                                                                                                         |
| 22397 | 1568258346.013620 | zmdc      |     NULL | 8141 |     0 | INF  | 'zmc -m 1' sending stop to pid 8459 at 19/09/11 20:19:06                                                                                                                           |
| 22396 | 1568258345.823090 | zmwatch   |     NULL | 8182 |    -1 | WAR  | Restarting capture daemon for cam-front-gate, no image since startup. Startup time was 1568258337 - now 1568258345 > 5                                                             |
| 22395 | 1568258338.060615 | zms_m1    |        0 | 8462 |    -1 | WAR  | no last_frame_sent.  Shouldn't happen. frame_mod was (1) frame_count (0)                                                                                                           |
| 22394 | 1568258337.371021 | web_js    |     NULL | 8130 |    -2 | ERR  | getStreamCmdResponse stream error: No data to read from socket - checkStreamForErrors()                                                                                            |
| 22393 | 1568258337.304134 | zmc_m1    |        0 | 8459 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                                                                                   |
| 22392 | 1568258337.240353 | web_php   |     NULL | 8130 |    -2 | ERR  | No data to read from socket                                                                                                                                                        |
| 22391 | 1568258337.211544 | web_php   |     NULL | 8130 |    -2 | ERR  | Timed out waiting for msg /var/lib/zoneminder/sock/zms-638733s.sock                                                                                                                |
| 22390 | 1568258337.091068 | zmc_m1    |        0 | 8459 |     0 | INF  | Priming capture from rtsp://cam-front-gate:5554                                                                                                                                    |
| 22389 | 1568258337.058772 | zmc_m1    |        0 | 8459 |     0 | INF  | Starting Capture version 1.33.14                                                                                                                                                   |
| 22388 | 1568258336.986650 | zmc_m1    |        0 | 8459 |     0 | INF  | Not enabling ffmpeg logs, as LOG_FFMPEG and/or LOG_DEBUG is disabled in options, or this monitor not part of your debug targets                                                    |
| 22387 | 1568258336.895950 | zmdc      |     NULL | 8459 |     0 | INF  | 'zmc -m 1' started at 19/09/11 20:18:56                                                                                                                                            |
| 22386 | 1568258336.890650 | zmdc      |     NULL | 8141 |     0 | INF  | 'zmc -m 1' starting at 19/09/11 20:18:56, pid = 8459                                                                                                                               |
[1]

Code: Select all

......./cgi-bin/zms?scale=100&width=720px&height=1280px&mode=jpeg&maxfps=30&monitor=1&connkey=624804&rand=1568256999
Last edited by vitus on Thu Sep 12, 2019 3:36 am, edited 1 time in total.
vitus
Posts: 12
Joined: Tue Jul 09, 2019 2:08 am

Re: zmc: Unable to open RTSP input, invalid data found when processing input

Post by vitus »

After enabling FFMPEG logging, I discovered the source of the issue was that the generic Android RTSP server didn't support TCP as a transport option, but (annoyingly) it did continue to negotiate anyway but just sent a response with UDP in the response header :twisted:

Code: Select all

| 22828 | 1568258965.977601 | zmc_m1    |        0 | 8657 |    -2 | ERR  | Unable to open input rtsp://cam-front-gate:5554 due to: Invalid data found when processing input                       | zm_ffmpeg_camera.cpp |  372 |
| 22827 | 1568258965.944246 | zmc_m1    |        0 | 8657 |    -1 | WAR  | Nonmatching transport in server reply
                                                                                 | zm_ffmpeg.cpp        |   67 |

Code: Select all

MariaDB [zm]> select Name,Value from Config where name like '%LOG_FFMPEG%' or name like '%DEBUG%';
+---------------------+-----------------------------------+
| Name                | Value                             |
+---------------------+-----------------------------------+
| ZM_LOG_DEBUG        | 1                                 |
| ZM_LOG_DEBUG_FILE   | /var/log/zoneminder/zm_debug.log+ |
| ZM_LOG_DEBUG_LEVEL  | 1                                 |
| ZM_LOG_DEBUG_TARGET | _zmc                              |
| ZM_LOG_FFMPEG       | 1                                 |
| ZM_UPLOAD_DEBUG     | 0                                 |
| ZM_UPLOAD_FTP_DEBUG | 0                                 |
+---------------------+-----------------------------------+
Post Reply