Ticket #7926: ffmpeg_log.txt

File ffmpeg_log.txt, 15.5 KB (added by roe1974, 7 years ago)
Line 
1May 26 18:29:32 hm-raspi homebridge[1755]: [5/26/2019, 6:29:32 PM] [Camera-ffmpeg] Start streaming video from Parkplatz with 1280x720@299kBit
2May 26 18:29:32 hm-raspi homebridge[1755]: ffmpeg -re -i rtsp://192.172.200.8:554/ipcam_mjpeg.sdp -map 0:0 -vcodec h264_omx -pix_fmt yuv420p -r 30 -f rawvideo -tune zerolatency -vf scale=1280:720 -b:v 299k -bufsize 299k -maxrate 299k -payload_type 99 -ssrc 11688972 -f rtp -srtp_out_suite AES_CM_128_HMAC_SHA1_80 -srtp_out_params Y5uDObxne3Vv4Y1qkVGJzpgXbeQYjba02VhMCcMQ srtp://192.168.0.133:65495?rtcpport=65495&localrtcpport=65495&pkt_size=1316 -loglevel debug
3May 26 18:29:33 hm-raspi homebridge[1755]: ffmpeg version 3.2.14-1~deb9u1 Copyright (c) 2000-2019 the FFmpeg developers
4May 26 18:29:33 hm-raspi homebridge[1755]: built with gcc 6.3.0 (Raspbian 6.3.0-18+rpi1+deb9u1) 20170516
5May 26 18:29:33 hm-raspi homebridge[1755]: configuration: --prefix=/usr --extra-version='1~deb9u1' --toolchain=hardened --libdir=/usr/lib/arm-linux-gnueabihf --incdir=/usr/include/arm-linux-gnueabihf --enable-gpl --disable-stripping --enable-avresample --enable-avisynth --enable-gnutls --enable-ladspa --enable-libass --enable-libbluray --enable-libbs2b --enable-libcaca --enable-libcdio --enable-libebur128 --enable-libflite --enable-libfontconfig --enable-libfreetype --enable-libfribidi --enable-libgme --enable-libgsm --enable-libmp3lame --enable-libopenjpeg --enable-libopenmpt --enable-libopus --enable-libpulse --enable-librubberband --enable-libshine --enable-libsnappy --enable-libsoxr --enable-libspeex --enable-libssh --enable-libtheora --enable-libtwolame --enable-libvorbis --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx265 --enable-libxvid --enable-libzmq --enable-libzvbi --enable-omx --enable-openal --enable-opengl --enable-sdl2 --enable-libdc1394 --enable-libiec61883 --enable-chromaprint --enable-frei0r --enable-libopencv --enable-libx264 --enable-shared
6May 26 18:29:33 hm-raspi homebridge[1755]: libavutil 55. 34.101 / 55. 34.101
7May 26 18:29:33 hm-raspi homebridge[1755]: libavcodec 57. 64.101 / 57. 64.101
8May 26 18:29:33 hm-raspi homebridge[1755]: libavformat 57. 56.101 / 57. 56.101
9May 26 18:29:33 hm-raspi homebridge[1755]: libavdevice 57. 1.100 / 57. 1.100
10May 26 18:29:33 hm-raspi homebridge[1755]: libavfilter 6. 65.100 / 6. 65.100
11May 26 18:29:33 hm-raspi homebridge[1755]: libavresample 3. 1. 0 / 3. 1. 0
12May 26 18:29:33 hm-raspi homebridge[1755]: libswscale 4. 2.100 / 4. 2.100
13May 26 18:29:33 hm-raspi homebridge[1755]: libswresample 2. 3.100 / 2. 3.100
14May 26 18:29:33 hm-raspi homebridge[1755]: libpostproc 54. 1.100 / 54. 1.100
15May 26 18:29:33 hm-raspi homebridge[1755]: Splitting the commandline.
16May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-re' ... matched as option 're' (read input at native frame rate) with argument '1'.
17May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-i' ... matched as input url with argument 'rtsp://192.172.200.8:554/ipcam_mjpeg.sdp'.
18May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-map' ... matched as option 'map' (set input stream mapping) with argument '0:0'.
19May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-vcodec' ... matched as option 'vcodec' (force video codec ('copy' to copy stream)) with argument 'h264_omx'.
20May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-pix_fmt' ... matched as option 'pix_fmt' (set pixel format) with argument 'yuv420p'.
21May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-r' ... matched as option 'r' (set frame rate (Hz value, fraction or abbreviation)) with argument '30'.
22May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-f' ... matched as option 'f' (force format) with argument 'rawvideo'.
23May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-tune' ...
24May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'tune' with argument 'zerolatency'.
25May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-vf' ... matched as option 'vf' (set video filters) with argument 'scale=1280:720'.
26May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-b:v' ... matched as option 'b' (video bitrate (please use -b:v)) with argument '299k'.
27May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-bufsize' ...
28May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'bufsize' with argument '299k'.
29May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-maxrate' ...
30May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'maxrate' with argument '299k'.
31May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-payload_type' ...
32May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'payload_type' with argument '99'.
33May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-ssrc' ...
34May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'ssrc' with argument '11688972'.
35May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-f' ... matched as option 'f' (force format) with argument 'rtp'.
36May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-srtp_out_suite' ... matched as AVOption 'srtp_out_suite' with argument 'AES_CM_128_HMAC_SHA1_80'.
37May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-srtp_out_params' ...
38May 26 18:29:33 hm-raspi homebridge[1755]: matched as AVOption 'srtp_out_params' with argument 'Y5uDObxne3Vv4Y1qkVGJzpgXbeQYjba02VhMCcMQ'.
39May 26 18:29:33 hm-raspi homebridge[1755]: Reading option 'srtp://192.168.0.133:65495?rtcpport=65495&localrtcpport=65495&pkt_size=1316' ... matched as output url.
40May 26 18:29:33 hm-raspi homebridge[1755]: Reading option '-loglevel' ... matched as option 'loglevel' (set logging level) with argument 'debug'.
41May 26 18:29:33 hm-raspi homebridge[1755]: Finished splitting the commandline.
42May 26 18:29:33 hm-raspi homebridge[1755]: Parsing a group of options: global .
43May 26 18:29:33 hm-raspi homebridge[1755]: Applying option loglevel (set logging level) with argument debug.
44May 26 18:29:33 hm-raspi homebridge[1755]: Successfully parsed a group of options.
45May 26 18:29:33 hm-raspi homebridge[1755]: Parsing a group of options: input url rtsp://192.172.200.8:554/ipcam_mjpeg.sdp.
46May 26 18:29:33 hm-raspi homebridge[1755]: Applying option re (read input at native frame rate) with argument 1.
47May 26 18:29:33 hm-raspi homebridge[1755]: Successfully parsed a group of options.
48May 26 18:29:33 hm-raspi homebridge[1755]: Opening an input file: rtsp://192.172.200.8:554/ipcam_mjpeg.sdp.
49May 26 18:29:33 hm-raspi homebridge[1755]: [tcp @ 0xc40530] No default whitelist set
50May 26 18:29:33 hm-raspi homebridge[1755]: [rtsp @ 0xc3e6e0] SDP:
51May 26 18:29:33 hm-raspi homebridge[1755]: v=0
52May 26 18:29:33 hm-raspi homebridge[1755]: o=- 1 1 IN IP4 127.0.0.1
53May 26 18:29:33 hm-raspi homebridge[1755]: s=IPCam
54May 26 18:29:33 hm-raspi homebridge[1755]: c=IN IP4 0.0.0.0
55May 26 18:29:33 hm-raspi homebridge[1755]: t=0 0
56May 26 18:29:33 hm-raspi homebridge[1755]: a=type:broadcast
57May 26 18:29:33 hm-raspi homebridge[1755]: m=video 0 RTP/AVP 26
58May 26 18:29:33 hm-raspi homebridge[1755]: a=control:track0
59May 26 18:29:33 hm-raspi homebridge[1755]: [rtp @ 0xc406c0] No default whitelist set
60May 26 18:29:33 hm-raspi homebridge[1755]: [udp @ 0xc45c10] No default whitelist set
61May 26 18:29:33 hm-raspi homebridge[1755]: [udp @ 0xc45c10] end receive buffer size reported is 131072
62May 26 18:29:33 hm-raspi homebridge[1755]: [udp @ 0xc55e80] No default whitelist set
63May 26 18:29:33 hm-raspi homebridge[1755]: [udp @ 0xc55e80] end receive buffer size reported is 131072
64May 26 18:29:33 hm-raspi homebridge[1755]: [rtsp @ 0xc3e6e0] setting jitter buffer size to 500
65May 26 18:29:33 hm-raspi homebridge[1755]: [rtsp @ 0xc3e6e0] hello state=0
66May 26 18:29:33 hm-raspi homebridge[1755]: Failed to parse interval end specification ''
67May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=d8 avail_size_in_buf=24637
68May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 0 bytes (0 bits)
69May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=e0 avail_size_in_buf=24635
70May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 16 bytes (128 bits)
71May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=db avail_size_in_buf=24617
72May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] index=0
73May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] qscale[0]: 3
74May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] index=1
75May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] qscale[1]: 5
76May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 132 bytes (1056 bits)
77May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=c4 avail_size_in_buf=24483
78May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 0 bytes (0 bits)
79May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=c0 avail_size_in_buf=24063
80May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] Changing bps from 0 to 8
81May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] sof0: picture: 640x360
82May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] component 0 2:1 id: 0 quant:0
83May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] component 1 1:1 id: 1 quant:1
84May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] component 2 1:1 id: 2 quant:1
85May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] pix fmt id 21111100
86May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 17 bytes (136 bits)
87May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] escaping removed 50 bytes
88May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=da avail_size_in_buf=24044
89May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker parser used 0 bytes (0 bits)
90May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] marker=d9 avail_size_in_buf=2
91May 26 18:29:33 hm-raspi homebridge[1755]: [mjpeg @ 0xc41540] decode frame unused 2 bytes
92May 26 18:29:35 hm-raspi homebridge[1755]: [rtsp @ 0xc3e6e0] All info found
93May 26 18:29:35 hm-raspi homebridge[1755]: [rtsp @ 0xc3e6e0] Setting avg frame rate based on r frame rate
94May 26 18:29:35 hm-raspi homebridge[1755]: Input #0, rtsp, from 'rtsp://192.172.200.8:554/ipcam_mjpeg.sdp':
95May 26 18:29:35 hm-raspi homebridge[1755]: Metadata:
96May 26 18:29:35 hm-raspi homebridge[1755]: title : IPCam
97May 26 18:29:35 hm-raspi homebridge[1755]: Duration: N/A, start: 0.000000, bitrate: N/A
98May 26 18:29:35 hm-raspi homebridge[1755]: Stream #0:0, 21, 1/90000: Video: mjpeg, 1 reference frame, yuvj422p(pc, bt470bg/unknown/unknown, center), 640x360 [SAR 1:1 DAR 16:9], 0/1, 15 fps, 15 tbr, 90k tbn, 90k tbc
99May 26 18:29:35 hm-raspi homebridge[1755]: Successfully opened the file.
100May 26 18:29:35 hm-raspi homebridge[1755]: Parsing a group of options: output url srtp://192.168.0.133:65495?rtcpport=65495&localrtcpport=65495&pkt_size=1316.
101May 26 18:29:35 hm-raspi homebridge[1755]: Applying option map (set input stream mapping) with argument 0:0.
102May 26 18:29:35 hm-raspi homebridge[1755]: Applying option vcodec (force video codec ('copy' to copy stream)) with argument h264_omx.
103May 26 18:29:35 hm-raspi homebridge[1755]: Applying option pix_fmt (set pixel format) with argument yuv420p.
104May 26 18:29:35 hm-raspi homebridge[1755]: Applying option r (set frame rate (Hz value, fraction or abbreviation)) with argument 30.
105May 26 18:29:35 hm-raspi homebridge[1755]: Applying option f (force format) with argument rawvideo.
106May 26 18:29:35 hm-raspi homebridge[1755]: Applying option vf (set video filters) with argument scale=1280:720.
107May 26 18:29:35 hm-raspi homebridge[1755]: Applying option b:v (video bitrate (please use -b:v)) with argument 299k.
108May 26 18:29:35 hm-raspi homebridge[1755]: Applying option f (force format) with argument rtp.
109May 26 18:29:35 hm-raspi homebridge[1755]: Successfully parsed a group of options.
110May 26 18:29:35 hm-raspi homebridge[1755]: Opening an output file: srtp://192.168.0.133:65495?rtcpport=65495&localrtcpport=65495&pkt_size=1316.
111May 26 18:29:35 hm-raspi homebridge[1755]: Codec AVOption tune (Tune the encoding to a specific scenario) specified for output file #0 (srtp://192.168.0.133:65495?rtcpport=65495&localrtcpport=65495&pkt_size=1316) has not been used for any stream. The most likely reason is either wrong type (e.g. a video option with no video streams) or that it is a private option of some encoder which was not actually used for any stream.
112May 26 18:29:35 hm-raspi homebridge[1755]: [srtp @ 0xced320] No default whitelist set
113May 26 18:29:35 hm-raspi homebridge[1755]: [rtp @ 0xce6bb0] No default whitelist set
114May 26 18:29:35 hm-raspi homebridge[1755]: [udp @ 0xcc54c0] No default whitelist set
115May 26 18:29:35 hm-raspi homebridge[1755]: [udp @ 0xcc53a0] No default whitelist set
116May 26 18:29:35 hm-raspi homebridge[1755]: Successfully opened the file.
117May 26 18:29:35 hm-raspi homebridge[1755]: detected 4 logical cores
118May 26 18:29:35 hm-raspi homebridge[1755]: [Parsed_scale_0 @ 0xcfa9b0] Setting 'w' to value '1280'
119May 26 18:29:35 hm-raspi homebridge[1755]: [Parsed_scale_0 @ 0xcfa9b0] Setting 'h' to value '720'
120May 26 18:29:35 hm-raspi homebridge[1755]: [Parsed_scale_0 @ 0xcfa9b0] Setting 'flags' to value 'bicubic'
121May 26 18:29:35 hm-raspi homebridge[1755]: [Parsed_scale_0 @ 0xcfa9b0] w:1280 h:720 flags:'bicubic' interl:0
122May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'video_size' to value '640x360'
123May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'pix_fmt' to value '13'
124May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'time_base' to value '1/90000'
125May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'pixel_aspect' to value '1/1'
126May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'sws_param' to value 'flags=2'
127May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] Setting 'frame_rate' to value '15/1'
128May 26 18:29:35 hm-raspi homebridge[1755]: [graph 0 input from stream 0:0 @ 0xcbeb00] w:640 h:360 pixfmt:yuvj422p tb:1/90000 fr:15/1 sar:1/1 sws_param:flags=2
129May 26 18:29:35 hm-raspi homebridge[1755]: [format @ 0xc96e70] compat: called with args=[yuv420p]
130May 26 18:29:35 hm-raspi homebridge[1755]: [format @ 0xc96e70] Setting 'pix_fmts' to value 'yuv420p'
131May 26 18:29:35 hm-raspi homebridge[1755]: [AVFilterGraph @ 0xc3e680] query_formats: 4 queried, 3 merged, 0 already done, 0 delayed
132May 26 18:29:35 hm-raspi homebridge[1755]: [swscaler @ 0xd357d0] deprecated pixel format used, make sure you did set range correctly
133May 26 18:29:35 hm-raspi homebridge[1755]: [Parsed_scale_0 @ 0xcfa9b0] w:640 h:360 fmt:yuvj422p sar:1/1 -> w:1280 h:720 fmt:yuv420p sar:1/1 flags:0x4
134May 26 18:29:35 hm-raspi homebridge[1755]: [h264_omx @ 0xd01390] libOMX_Core.so not found
135May 26 18:29:35 hm-raspi homebridge[1755]: [h264_omx @ 0xd01390] libOmxCore.so not found
136May 26 18:29:35 hm-raspi homebridge[1755]: Stream mapping:
137May 26 18:29:35 hm-raspi homebridge[1755]: Stream #0:0 -> #0:0 (mjpeg (native) -> h264 (h264_omx))
138May 26 18:29:35 hm-raspi homebridge[1755]: Error while opening encoder for output stream #0:0 - maybe incorrect parameters such as bit_rate, rate, width or height
139May 26 18:29:35 hm-raspi homebridge[1755]: [AVIOContext @ 0xced640] Statistics: 0 seeks, 0 writeouts
140May 26 18:29:35 hm-raspi homebridge[1755]: [5/26/2019, 6:29:35 PM] [Camera-ffmpeg] ERROR: FFmpeg exited with code 1