Unexpected response code 400 with ABUS 720P camera

Forum for questions and support relating to the 1.32.x releases only.
Forum rules
Post Reply
raymondpapaux
Posts: 4
Joined: Mon Jan 21, 2019 6:31 pm

Unexpected response code 400 with ABUS 720P camera

Post by raymondpapaux »

My environment is: ubuntu server 18.04 LTS with lastest patches (apt-get update, apt-get upgrade).

I have been running the ZM 1.30.4 with an ABUS TVIP91700 ONVIF camera successfully for a long while without any issue.
As I was keeping receiving pop-up saying that a new version is avalable, I finally decided to make the step to upgrade.
The upgrade to ZM 1.32.3 went fine without any errors. I was able to retrieve all my configuration setting without any change.

However the new ZM capture (zmc) fails to get stream from the camera, I'm getting the error "Unexpected response code 400, text is 'Bad Request" while zmc is issuing a RTSP - SETUP command. Below is the relevant part of the trace file.

Any advise?
Thanks for your help

01/23/19 12:05:58.224318 zmc_m1[21890].DB1-zm_logger.cpp/251 [LogOpts: level=DB9/DB9, screen=OFF, database=ERR, logfile=DB9->/var/log/zm/zm_debug.log.21890, syslog=WAR]
01/23/19 12:05:58.224409 zmc_m1[21890].DB1-zm_utils.cpp/281 [Detected a x86\x86-64 processor with AVX2]
01/23/19 12:05:58.225054 zmc_m1[21890].DB4-zm_db.cpp/105 [Success running query: SELECT Id, Name, ServerId, StorageId, Type, Function+0, Enabled, LinkedMonitors, AnalysisFPSLimit, AnalysisUpdateDelay, MaxFPS, AlarmMaxFPS,Device, Channel, Format, V4LMultiBuffer, V4LCapturesPerFrame, Protocol, Method, Options, User, Pass, Host, Port, Path, Width, Height, Colours, Palette, Orientation+0, Deinterlacing, RTSPDescribe, SaveJPEGs, VideoWriter, EncoderParameters, RecordAudio, Brightness, Contrast, Hue, Colour, EventPrefix, LabelFormat, LabelX, LabelY, LabelSize,ImageBufferCount, WarmupCount, PreEventCount, PostEventCount, StreamReplayBuffer, AlarmFrameCount, SectionLength, FrameSkip, MotionFrameSkip, FPSReportInterval, RefBlendPerc, AlarmRefBlendPerc, TrackMotion, Exif, SignalCheckPoints, SignalCheckColour FROM Monitors WHERE Id=1]
01/23/19 12:05:58.225115 zmc_m1[21890].DB5-zm_db.cpp/141 [Success]
01/23/19 12:05:58.225128 zmc_m1[21890].DB1-zm_monitor.cpp/2059 [Got 1 for v4l_captures_per_frame]
01/23/19 12:05:58.225192 zmc_m1[21890].DB2-zm_camera.cpp/55 [New camera id: 1 width: 1280 height: 720 colours: 4 subpixelorder: 8 capture: 1]
01/23/19 12:05:58.230608 zmc_m1[21890].DB1-zm_thread.cpp/227 [Creating thread]
01/23/19 12:05:58.230688 zmc_m1[21890].DB2-zm_rtsp.cpp/171 [RTSP Local SSRC is 2b2de2a7]
01/23/19 12:05:58.230704 zmc_m1[21890].DB1-zm_thread.cpp/260 [Starting thread]
01/23/19 12:05:58.230895 zmc_m1[21893].DB2-zm_thread.cpp/237 [Invoking thread]
01/23/19 12:05:58.230935 zmc_m1[21890].DB1-zm_thread.cpp/278 [Started thread 21893]
01/23/19 12:05:58.230939 zmc_m1[21893].DB1-zm_comms.cpp/560 [connect(): Trying '192.168.5.21', family '2', proto '6']
01/23/19 12:05:58.230954 zmc_m1[21890].DB4-zm_image.cpp/243 [Blend: Using fast blend function]
01/23/19 12:05:58.230965 zmc_m1[21890].DB4-zm_image.cpp/336 [Delta: CPU extensions disabled, using standard delta functions]
01/23/19 12:05:58.230974 zmc_m1[21890].DB4-zm_image.cpp/393 [Deinterlace: Using standard functions]
01/23/19 12:05:58.230980 zmc_m1[21890].DB4-zm_image.cpp/406 [Image buffer copy: Using standard memcpy]
01/23/19 12:05:58.230987 zmc_m1[21890].DB3-zm_image.cpp/410 [Setting up static colour tables]
01/23/19 12:05:58.231015 zmc_m1[21890].DB1-zm_monitor.cpp/406 [monitor purpose=1]
01/23/19 12:05:58.231023 zmc_m1[21890].DB1-zm_monitor.cpp/416 [mem.size SharedData=600 TriggerData=560 VideoStoreData=4128 total=184326152]
01/23/19 12:05:58.231032 zmc_m1[21890].DB1-zm_storage.cpp/94 [No id passed to Storage constructor. Using default path /var/cache/zoneminder/events instead]
01/23/19 12:05:58.231040 zmc_m1[21890].DB1-zm_monitor.cpp/420 [Storage path: /var/cache/zoneminder/events]
01/23/19 12:05:58.231056 zmc_m1[21890].DB3-zm_monitor.cpp/517 [Connecting to monitor. Purpose is 1]
01/23/19 12:05:58.231076 zmc_m1[21890].DB3-zm_monitor.cpp/524 [Success opening mmap file at (/dev/shm/zm.mmap.1)]
01/23/19 12:05:58.231085 zmc_m1[21890].DB3-zm_monitor.cpp/546 [MMap file size is 184326152]
01/23/19 12:05:58.231095 zmc_m1[21890].DB1-zm_monitor.cpp/551 [Unable to map file /dev/shm/zm.mmap.1 (184326152 bytes) to locked memory, trying unlocked]
01/23/19 12:05:58.231108 zmc_m1[21890].DB1-zm_monitor.cpp/555 [Mapped file /dev/shm/zm.mmap.1 (184326152 bytes) to unlocked memory]
01/23/19 12:05:58.231115 zmc_m1[21890].DB3-zm_monitor.cpp/567 [mmapped to 0x7f64e102f000]
01/23/19 12:05:58.231122 zmc_m1[21890].DB3-zm_monitor.cpp/590 [Aligning shared memory images to the next 64 byte boundary]
01/23/19 12:05:58.231129 zmc_m1[21890].DB3-zm_monitor.cpp/593 [Allocating 50 image buffers]
01/23/19 12:05:58.231187 zmc_m1[21890].DB3-zm_monitor.cpp/624 [Success connecting]
01/23/19 12:05:58.232456 zmc_m1[21893].DB2-zm_rtsp.cpp/47 [Sending RTSP message: OPTIONS rtsp://192.168.5.21:554/stream1 RTSP/1.0^M
User-Agent: ZoneMinder/1.32.3^M
CSeq: 1^M
^M
]
01/23/19 12:05:58.236435 zmc_m1[21893].DB2-zm_rtsp.cpp/67 [Received RTSP response: RTSP/1.0 200 OK^M
CSeq: 1^M
Date: Wed, Jan 23 2019 11:05:58 GMT^M
Public: OPTIONS, DESCRIBE, SETUP, TEARDOWN, SET_PARAMETER, PLAY^M
^M
(130 bytes)]
01/23/19 12:05:58.236571 zmc_m1[21893].DB2-zm_rtsp.cpp/47 [Sending RTSP message: DESCRIBE rtsp://192.168.5.21:554/stream1 RTSP/1.0^M
User-Agent: ZoneMinder/1.32.3^M
CSeq: 2^M
^M
]
01/23/19 12:05:58.326304 zmc_m1[21890].DB1-zm_monitor.cpp/486 [Monitor Main door has function 3]
01/23/19 12:05:58.326381 zmc_m1[21890].DB1-zm_monitor.cpp/487 [Monitor Main door LBF = '%N - %d-%b-%Y %H:%M:%S %z', LBX = 0, LBY = 0, LBS = 2]
01/23/19 12:05:58.326392 zmc_m1[21890].DB1-zm_monitor.cpp/488 [Monitor Main door IBC = 50, WUC = 30, pEC = 25, PEC = 25, EAF = 2, FRI = 1000, RBP = 12, ARBP = 6, FM = 0]
01/23/19 12:05:58.327094 zmc_m1[21890].DB1-zm_zone.cpp/862 [Got 1 zones for monitor Main door]
01/23/19 12:05:58.327155 zmc_m1[21890].DB5-zm_zone.cpp/893 [Parsing polygon 0,0 1279,0 1279,719 0,719]
01/23/19 12:05:58.327164 zmc_m1[21890].DB3-zm_zone.cpp/737 [Parsing polygon string '0,0 1279,0 1279,719 0,719']
01/23/19 12:05:58.327174 zmc_m1[21890].DB3-zm_zone.cpp/769 [Got coordinate 0,0 from polygon string]
01/23/19 12:05:58.327182 zmc_m1[21890].DB3-zm_zone.cpp/769 [Got coordinate 1279,0 from polygon string]
01/23/19 12:05:58.327189 zmc_m1[21890].DB3-zm_zone.cpp/769 [Got coordinate 1279,719 from polygon string]
01/23/19 12:05:58.327197 zmc_m1[21890].DB3-zm_zone.cpp/769 [Got coordinate 0,719 from polygon string]
01/23/19 12:05:58.327207 zmc_m1[21890].DB3-zm_zone.cpp/789 [Successfully parsed polygon string]
01/23/19 12:05:58.327789 zmc_m1[21890].DB9-zm_image.cpp/2329 [0: min_y: 0, max_y:719, min_x:0.00, 1/m:-0.00]
01/23/19 12:05:58.327849 zmc_m1[21890].DB9-zm_image.cpp/2329 [1: min_y: 0, max_y:719, min_x:1279.00, 1/m:0.00]
01/23/19 12:05:58.327861 zmc_m1[21890].DB9-zm_image.cpp/2340 [Moving global edge]
01/23/19 12:05:58.327870 zmc_m1[21890].DB9-zm_image.cpp/2340 [Moving global edge]
01/23/19 12:05:58.327877 zmc_m1[21890].DB9-zm_image.cpp/2356 [0 - 0: min_y: 0, max_y:719, min_x:0.00, 1/m:-0.00]
01/23/19 12:05:58.327886 zmc_m1[21890].DB9-zm_image.cpp/2356 [0 - 1: min_y: 0, max_y:719, min_x:1279.00, 1/m:0.00]
01/23/19 12:05:58.327904 zmc_m1[21890].DB9-zm_image.cpp/2356 [1 - 0: min_y: 0, max_y:719, min_x:0.00, 1/m:-0.00]
01/23/19 12:05:58.327912 zmc_m1[21890].DB9-zm_image.cpp/2356 [1 - 1: min_y: 0, max_y:719, min_x:1279.00, 1/m:0.00]
....
.... <similar events>
....
01/23/19 12:05:58.345922 zmc_m1[21890].DB9-zm_image.cpp/2356 [717 - 1: min_y: 0, max_y:719, min_x:1279.00, 1/m:0.00]
01/23/19 12:05:58.345939 zmc_m1[21890].DB9-zm_image.cpp/2356 [718 - 0: min_y: 0, max_y:719, min_x:0.00, 1/m:-0.00]
01/23/19 12:05:58.345947 zmc_m1[21890].DB9-zm_image.cpp/2356 [718 - 1: min_y: 0, max_y:719, min_x:1279.00, 1/m:0.00]
01/23/19 12:05:58.345963 zmc_m1[21890].DB9-zm_image.cpp/2397 [Deleting active_edge]
01/23/19 12:05:58.345971 zmc_m1[21890].DB9-zm_image.cpp/2397 [Deleting active_edge]
01/23/19 12:05:58.347898 zmc_m1[21890].DB1-zm_monitor.cpp/2322 [Loaded monitor 1(Main door), 1 zones]
01/23/19 12:05:58.347961 zmc_m1[21890].INF-zmc.cpp/223 [Starting Capture version 1.32.3]
01/23/19 12:05:58.348267 zmc_m1[21890].DB2-zm_remote_camera_rtsp.cpp/143 [Waiting for sources]
01/23/19 12:05:59.092249 zmc_m1[21890].INF-zm_signal.cpp/40 [Got signal 15 (Terminated), exiting]
01/23/19 12:05:59.236867 zmc_m1[21893].DB2-zm_rtsp.cpp/67 [Received RTSP response: RTSP/1.0 200 OK^M
CSeq: 2^M
Date: Wed, Jan 23 2019 11:05:58 GMT^M
Content-Base: rtsp://192.168.5.21:554/stream1/^M
Content-Type: application/sdp^M
Content-Length: 414^M
^M
v=0^M
o=RTSP 1548240622007376 1 IN IP4 0.0.0.0^M
s=RTSP Server^M
t=0 0^M
a=range:npt=0-^M
a=control:*^M
m=video 0 RTP/AVP 99^M
c=IN IP4 0.0.0.0^M
b=AS:0^M
a=rtpmap:99 H264/90000^M
a=fmtp:99 packetization-mode=1;profile-level-id=4D001F;sprop-parameter-sets=Z00AH5WoFAFuQA==,aO48gA==^M
a=control:track1^M
m=application 0 RTP/AVP 107^M
c=IN IP4 0.0.0.0^M
b=AS:0^M
a=rtpmap:107 vnd.onvif.metadata/90000^M
a=recvonly
a=control:track2^M
(579 bytes)]
01/23/19 12:05:59.237009 zmc_m1[21893].DB1-zm_rtsp.cpp/348 [Processing DESCRIBE response header 'RTSP/1.0 200 OK^M
CSeq: 2^M
Date: Wed, Jan 23 2019 11:05:58 GMT^M
Content-Base: rtsp://192.168.5.21:554/stream1/^M
Content-Type: application/sdp^M
Content-Length: 414']
01/23/19 12:05:59.237063 zmc_m1[21893].INF-zm_rtsp.cpp/355 [Received new Content-Base in DESCRIBE response header. Updated device Url to: 'rtsp://192.168.5.21:554/stream1/']
01/23/19 12:05:59.237082 zmc_m1[21893].DB1-zm_rtsp.cpp/364 [Processing SDP 'v=0^M
o=RTSP 1548240622007376 1 IN IP4 0.0.0.0^M
s=RTSP Server^M
t=0 0^M
a=range:npt=0-^M
a=control:*^M
m=video 0 RTP/AVP 99^M
c=IN IP4 0.0.0.0^M
b=AS:0^M
a=rtpmap:99 H264/90000^M
a=fmtp:99 packetization-mode=1;profile-level-id=4D001F;sprop-parameter-sets=Z00AH5WoFAFuQA==,aO48gA==^M
a=control:track1^M
m=application 0 RTP/AVP 107^M
c=IN IP4 0.0.0.0^M
b=AS:0^M
a=rtpmap:107 vnd.onvif.metadata/90000^M
a=recvonly
a=control:track2^M
']
01/23/19 12:05:59.237116 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'v=0']
01/23/19 12:05:59.237139 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'o=RTSP 1548240622007376 1 IN IP4 0.0.0.0']
01/23/19 12:05:59.237180 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 's=RTSP Server']
01/23/19 12:05:59.237196 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 't=0 0']
01/23/19 12:05:59.237211 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=range:npt=0-']
01/23/19 12:05:59.237229 zmc_m1[21893].DB3-zm_sdp.cpp/298 [Ignoring general SDP attribute 'range:npt=0-']
01/23/19 12:05:59.237244 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=control:*']
01/23/19 12:05:59.237260 zmc_m1[21893].DB3-zm_sdp.cpp/298 [Ignoring general SDP attribute 'control:*']
01/23/19 12:05:59.237274 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'm=video 0 RTP/AVP 99']
01/23/19 12:05:59.237300 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'c=IN IP4 0.0.0.0']
01/23/19 12:05:59.237320 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'b=AS:0']
01/23/19 12:05:59.237337 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=rtpmap:99 H264/90000']
01/23/19 12:05:59.237357 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=fmtp:99 packetization-mode=1;profile-level-id=4D001F;sprop-parameter-sets=Z00AH5WoFAFuQA==,aO48gA==']
01/23/19 12:05:59.237399 zmc_m1[21893].DB3-zm_sdp.cpp/286 [Ignoring SDP fmtp attribute 'packetization-mode' for media 'video']
01/23/19 12:05:59.237421 zmc_m1[21893].DB4-zm_sdp.cpp/283 [sprop-parameter-sets value Z00AH5WoFAFuQA==,aO48gA==]
01/23/19 12:05:59.237439 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=control:track1']
01/23/19 12:05:59.237456 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'm=application 0 RTP/AVP 107']
01/23/19 12:05:59.237476 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'c=IN IP4 0.0.0.0']
01/23/19 12:05:59.237495 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'b=AS:0']
01/23/19 12:05:59.237510 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=rtpmap:107 vnd.onvif.metadata/90000']
01/23/19 12:05:59.237532 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=recvonly']
01/23/19 12:05:59.237548 zmc_m1[21893].DB3-zm_sdp.cpp/295 [Ignoring SDP attribute 'recvonly' for media 'application']
01/23/19 12:05:59.237563 zmc_m1[21893].DB3-zm_sdp.cpp/167 [Processing SDP line 'a=control:track2']
01/23/19 12:05:59.237730 zmc_m1[21893].DB1-zm_sdp.cpp/365 [Looking for codec for video payload type 99 / H264]
01/23/19 12:05:59.237749 zmc_m1[21893].DB1-zm_sdp.cpp/405 [Got dynamic payload type 99, H264]
01/23/19 12:05:59.237767 zmc_m1[21893].DB4-zm_sdp.cpp/456 [10: 67 4d 00 1f 95 a8 14 01 6e 40]
01/23/19 12:05:59.237788 zmc_m1[21893].DB4-zm_sdp.cpp/456 [4: 68 ee 3c 80]
01/23/19 12:05:59.237859 zmc_m1[21893].DB1-zm_sdp.cpp/365 [Looking for codec for application payload type 107 / vnd.onvif.metadata]
01/23/19 12:05:59.237877 zmc_m1[21893].DB1-zm_sdp.cpp/405 [Got dynamic payload type 107, vnd.onvif.metadata]
01/23/19 12:05:59.238556 zmc_m1[21893].DB2-zm_rtsp.cpp/126 [Assigned RTP port range is 40200-40499]
01/23/19 12:05:59.238633 zmc_m1[21893].DB2-zm_rtsp.cpp/47 [Sending RTSP message: SETUP rtsp://192.168.5.21:554/stream1/ RTSP/1.0^M
Transport: RTP/AVP;unicast;client_port=40200-40201^M
User-Agent: ZoneMinder/1.32.3^M
CSeq: 3^M
^M
]
01/23/19 12:05:59.240677 zmc_m1[21893].DB2-zm_rtsp.cpp/67 [Received RTSP response: RTSP/1.0 400 Bad Request^M
Date: Wed, Jan 23 2019 11:05:59 GMT^M
Allow: OPTIONS, DESCRIBE, SETUP, TEARDOWN, SET_PARAMETER, PLAY^M
^M
(129 bytes)]
01/23/19 12:05:59.240792 zmc_m1[21893].ERR-zm_rtsp.cpp/87 [Unexpected response code 400, text is 'Bad Request']
01/23/19 12:05:59.257960 zmc_m1[21893].DB2-zm_thread.cpp/249 [Exiting thread, status 0x55e255db72a8]
01/23/19 12:06:08.310428 zmc_m1[21890].FAT-zm_remote_camera_rtsp.cpp/148 [No RTSP sources]
01/23/19 12:06:08.329151 zmc_m1[21890].DB1-zm_logger.cpp/255 [Terminating Logger]
Top
Post Reply

Return to “ZoneMinder 1.32.x”