It is currently 28 Jun 2017, 20:07



Caspar CG plays files in not timely manner.

Help to specific problems with your setup, or discussing CasparCG bugs

Moderators: Macey, Jonas Hummelstrand, didikunz

Caspar CG plays files in not timely manner.

Postby Japri » 29 Apr 2017, 13:38

I encounter caspar cg 2.07 plays file too fast.

I tried to convert the file using handbrake. but the file keep playing too fast.

like 69 seconds file being played in caspar just 52 seconds. in tends too skip forward behaviours. If played using VLS everything become normal again.

anyone have any clue? I'm stuck.
Japri
 
Posts: 22
Joined: 20 Nov 2013, 16:13

Re: Caspar CG plays files in not timely manner.

Postby Japri » 29 Apr 2017, 17:19

I manage to solved this by downgrading to caspar 2.06
Japri
 
Posts: 22
Joined: 20 Nov 2013, 16:13

Re: Caspar CG plays files in not timely manner.

Postby zbang » 01 May 2017, 07:28

Error messages? (a bit more detail would really help)

I recall that 2.07 doesn't handle things like 3:2 pulldown encodings in mpg files, so any files with that will play fast.

z!
zbang
 
Posts: 44
Joined: 10 Apr 2015, 02:09

Re: Caspar CG plays files in not timely manner.

Postby jackreynolds » 01 May 2017, 09:45

Does 2.0.6 run 3:2 pulldowns? This is a common issue we have and need a solution for.
jackreynolds
 
Posts: 161
Joined: 02 Nov 2015, 18:02
Location: Pittsburgh, Pennsylvania, USA

Re: Caspar CG plays files in not timely manner.

Postby Japri » 01 May 2017, 14:30

zbang wrote:Error messages? (a bit more detail would really help)

I recall that 2.07 doesn't handle things like 3:2 pulldown encodings in mpg files, so any files with that will play fast.

z!


there is no error messasge, it just go unnoticed until i times it with a stopwatch. it also noticeable in the caspar client. when you play the rundown, you can notice that play timer bar is skipping.
Japri
 
Posts: 22
Joined: 20 Nov 2013, 16:13

Re: Caspar CG plays files in not timely manner.

Postby zbang » 01 May 2017, 18:41

Frame rate mismatch? (A 25fps clip played at 30fps?)

Anyway, please give us more details about the file(s) you're playing and the caspar setup; otherwise we're just guessing. If nothing else, post the log messages from the server (which is where errors would show, not in the client) from when you play the clip.

You might also try the 2.10 beta server.

z!
zbang
 
Posts: 44
Joined: 10 Apr 2015, 02:09

Re: Caspar CG plays files in not timely manner.

Postby baoqngo » 15 May 2017, 19:15

Hi, I have similar problem. Playing a 10 minutes clips = about 7s faster. 1 minutes clip = about 17 frames faster. I've tried 2.07 and it's the same. I'm building a playlist client that need the playing time to be more accurate.


LOG

[2017-05-15 11:52:10.766] [11868] [info] Starting CasparCG Video and Graphics Playout Server 2.1.0.3437 dc2e94b Beta 2
[2017-05-15 11:52:10.766] [11868] [info] on Windows 7 Professional Service Pack 1
[2017-05-15 11:52:10.766] [11868] [info] Intel(R) Core(TM) i7-6800K CPU @ 3.40GHz Physical Threads: 12
[2017-05-15 11:52:10.766] [11868] [info] All Series
[2017-05-15 11:52:10.767] [11868] [info] Initialized reroute module.
[2017-05-15 11:52:10.767] [11868] [info] Initialized ffmpeg module.
[2017-05-15 11:52:10.767] [11868] [info] Initialized oal module.
[2017-05-15 11:52:10.941] [11868] [info] Initialized bluefish module.
[2017-05-15 11:52:10.941] [11868] [info] Initialized decklink module.
[2017-05-15 11:52:10.942] [11868] [info] Initialized screen module.
[2017-05-15 11:52:10.954] [11868] [info] Initialized html module.
[2017-05-15 11:52:10.954] [11868] [info] Initialized psd module.
[2017-05-15 11:52:10.989] [11868] [info] Initialized flash module.
[2017-05-15 11:52:10.989] [11868] [info] Initialized newtek module.
[2017-05-15 11:52:10.990] [11868] [info] Initialized image module.
[2017-05-15 11:52:11.004] [11868] [info] Loaded C:\Windows\system32\Processing.AirSend.x64.dll
[2017-05-15 11:52:11.004] [11868] [info] ffmpeg.avcodec 56.41.100
[2017-05-15 11:52:11.005] [11868] [info] ffmpeg.avformat 56.36.100
[2017-05-15 11:52:11.005] [11868] [info] ffmpeg.avfilter 5.16.101
[2017-05-15 11:52:11.005] [11868] [info] ffmpeg.avutil 54.27.100
[2017-05-15 11:52:11.005] [11868] [info] ffmpeg.swscale 3.1.101
[2017-05-15 11:52:11.005] [11868] [info] bluefish.version Not found
[2017-05-15 11:52:11.005] [11868] [info] decklink.version 10.8.4
[2017-05-15 11:52:11.005] [11868] [info] decklink.device DeckLink 4K Extreme [1]
[2017-05-15 11:52:11.005] [11868] [info] decklink.device DeckLink SDI 4K [2]
[2017-05-15 11:52:11.005] [11868] [info] cef.version 3.1750.1805
[2017-05-15 11:52:11.005] [11868] [info] cef.chromeversion 33.0.1750.170
[2017-05-15 11:52:11.005] [11868] [info] flash 24.0.0.194
[2017-05-15 11:52:11.005] [11868] [info] newtek-ivga.version available
[2017-05-15 11:52:11.005] [11868] [info] freeimage 3.16.0
[2017-05-15 11:52:11.005] [11868] [info] casparcg.config:
[2017-05-15 11:52:11.005] [11868] [info] -----------------------------------------
[2017-05-15 11:52:11.005] [11868] [info] <?xml version="1.0" encoding="utf-8"?>
[2017-05-15 11:52:11.005] [11868] [info] <configuration>
[2017-05-15 11:52:11.005] [11868] [info] <paths>
[2017-05-15 11:52:11.005] [11868] [info] <media-path>media/</media-path>
[2017-05-15 11:52:11.005] [11868] [info] <log-path>log/</log-path>
[2017-05-15 11:52:11.005] [11868] [info] <data-path>data/</data-path>
[2017-05-15 11:52:11.005] [11868] [info] <template-path>template/</template-path>
[2017-05-15 11:52:11.005] [11868] [info] <thumbnail-path>thumbnail/</thumbnail-path>
[2017-05-15 11:52:11.005] [11868] [info] <font-path>font/</font-path>
[2017-05-15 11:52:11.005] [11868] [info] </paths>
[2017-05-15 11:52:11.005] [11868] [info] <lock-clear-phrase>secret</lock-clear-phrase>
[2017-05-15 11:52:11.005] [11868] [info] <channels>
[2017-05-15 11:52:11.005] [11868] [info] <channel>
[2017-05-15 11:52:11.005] [11868] [info] <video-mode>1080p2997</video-mode>
[2017-05-15 11:52:11.005] [11868] [info] <channel-layout>stereo</channel-layout>
[2017-05-15 11:52:11.005] [11868] [info] <consumers/>
[2017-05-15 11:52:11.005] [11868] [info] </channel>
[2017-05-15 11:52:11.005] [11868] [info] </channels>
[2017-05-15 11:52:11.005] [11868] [info] <controllers>
[2017-05-15 11:52:11.005] [11868] [info] <tcp>
[2017-05-15 11:52:11.005] [11868] [info] <port>5250</port>
[2017-05-15 11:52:11.005] [11868] [info] <protocol>AMCP</protocol>
[2017-05-15 11:52:11.005] [11868] [info] </tcp>
[2017-05-15 11:52:11.005] [11868] [info] <tcp>
[2017-05-15 11:52:11.005] [11868] [info] <port>3250</port>
[2017-05-15 11:52:11.005] [11868] [info] <protocol>LOG</protocol>
[2017-05-15 11:52:11.005] [11868] [info] </tcp>
[2017-05-15 11:52:11.005] [11868] [info] </controllers>
[2017-05-15 11:52:11.005] [11868] [info] <osc>
[2017-05-15 11:52:11.005] [11868] [info] <default-port>6251</default-port>
[2017-05-15 11:52:11.005] [11868] [info] <disable-send-to-amcp-clients>false</disable-send-to-amcp-clients>
[2017-05-15 11:52:11.005] [11868] [info] </osc>
[2017-05-15 11:52:11.005] [11868] [info] <accelerator>auto</accelerator>
[2017-05-15 11:52:11.005] [11868] [info] </configuration>
[2017-05-15 11:52:11.005] [11868] [info] -----------------------------------------
[2017-05-15 11:52:11.006] [11868] [info] Initialized audio config.
[2017-05-15 11:52:11.006] [11868] [info] Initializing OpenGL Device.
[2017-05-15 11:52:11.722] [11868] [info] Successfully initialized OpenGL 4.5.0 NVIDIA 381.89 NVIDIA Corporation
[2017-05-15 11:52:11.726] [11868] [info] Initialized OpenGL Accelerated GPU Image Mixer for channel 1
[2017-05-15 11:52:11.726] [11868] [info] video_channel[1|1080p2997] Successfully Initialized.
[2017-05-15 11:52:11.726] [11868] [info] Initialized channels.
[2017-05-15 11:52:11.726] [11868] [info] Initialized OpenGL Accelerated GPU Image Mixer for channel 0
[2017-05-15 11:52:11.727] [11868] [info] Initialized thumbnail generator.
[2017-05-15 11:52:11.727] [11868] [info] Initialized controllers.
[2017-05-15 11:52:11.727] [11868] [info] Initialized osc.
[2017-05-15 11:52:11.727] [11868] [info] Started initial media information retrieval.
[2017-05-15 11:52:12.221] [10344] [info] Initial media information retrieval finished.
[2017-05-15 11:55:34.930] [15820] [info] async_event_server[:5250] Accepted connection from 192.168.40.242 (1 connections).
[2017-05-15 11:55:35.057] [15820] [info] Received message from 192.168.40.242: CLEAR 1 \r\n
[2017-05-15 11:55:35.057] [13776] [info] Sent message to 192.168.40.242:202 CLEAR OK\r\n
[2017-05-15 11:55:35.058] [15820] [info] Received message from 192.168.40.242: LOADBG 1-10 "TEST/DFW" AUTO\r\n
[2017-05-15 11:55:35.095] [13776] [info] ffmpeg[dfw.mp4|1920x1080p29.97|0/18904] [video-decoder] H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
[2017-05-15 11:55:35.098] [13776] [info] ffmpeg[dfw.mp4|1920x1080p29.97|0/18904] [audio-decoder] AAC (Advanced Audio Coding)
[2017-05-15 11:55:35.101] [13776] [info] transition[empty=>ffmpeg[dfw.mp4|1920x1080p29.97|0/18904]] Initialized
[2017-05-15 11:55:35.101] [13776] [info] Sent message to 192.168.40.242:202 LOADBG OK\r\n
[2017-05-15 11:55:35.304] [15432] [info] [frame_muxer] simple 1920x1080p29.97
[2017-05-15 11:56:01.768] [15820] [info] Received message from 192.168.40.242: INFO 1-10\r\n
[2017-05-15 11:56:01.769] [13776] [info] Sent more than 512 bytes to 192.168.40.242
[2017-05-15 11:57:31.045] [14588] [info] Received message from Console: diag\r\n
[2017-05-15 12:05:54.146] [15820] [info] Received message from 192.168.40.242: LOADBG 1-10 "TEST/DFW" AUTO\r\n
[2017-05-15 12:05:54.180] [13776] [info] ffmpeg[dfw.mp4|1920x1080p29.97|0/18904] [video-decoder] H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
[2017-05-15 12:05:54.183] [13776] [info] ffmpeg[dfw.mp4|1920x1080p29.97|0/18904] [audio-decoder] AAC (Advanced Audio Coding)
[2017-05-15 12:05:54.184] [13776] [info] transition[empty=>ffmpeg[dfw.mp4|1920x1080p29.97|0/18904]] Initialized
[2017-05-15 12:05:54.184] [13776] [info] Sent message to 192.168.40.242:202 LOADBG OK\r\n
[2017-05-15 12:05:59.104] [12956] [info] ffmpeg[dfw.mp4|1920x1080p29.97|18899/18904] Destroyed.


MEDIA INFO....
Video
ID : 1
Format : AVC
Format/Info : Advanced Video Codec
Format profile : High@L4.1
Format settings, CABAC : Yes
Format settings, ReFrames : 3 frames
Codec ID : avc1
Codec ID/Info : Advanced Video Coding
Duration : 10 min 30 s
Bit rate mode : Constant
Bit rate : 25.0 Mb/s
Width : 1 920 pixels
Height : 1 080 pixels
Display aspect ratio : 16:9
Frame rate mode : Constant
Frame rate : 29.970 (30000/1001) FPS
Standard : NTSC
Color space : YUV
Chroma subsampling : 4:2:0
Bit depth : 8 bits
Scan type : Interlaced
Scan type, store method : Separated fields
Scan order : Top Field First
Bits/(Pixel*Frame) : 0.402
Stream size : 1.78 GiB (99%)
Language : English
Encoded date : UTC 2017-04-20 22:14:13
Tagged date : UTC 2017-04-20 22:14:13
Color range : Limited
Color primaries : BT.709
Transfer characteristics : BT.709
Matrix coefficients : BT.709
baoqngo
 
Posts: 3
Joined: 13 May 2017, 00:15

Re: Caspar CG plays files in not timely manner.

Postby zbang » 15 May 2017, 19:47

baoqngo wrote:Hi, I have similar problem. Playing a 10 minutes clips = about 7s faster. 1 minutes clip = about 17 frames faster. I've tried 2.07 and it's the same.

(a back-of-the-envelope calculation suggests this is not a drop-frame rate problem)

Are you sure the run times of the clips are completely frame accurate? I've found that the metadata is often incorrect and have occasionally used mplayer or ffmpeg to play the clip and count the frames. They're also good at handling (and reporting) encoding problems that casper will either ignore or error on.

If the clip is a real 00:10:30.00, which at 29.97 should be ~18881 frames, and caspar is dropping some frames on the way out....that would be weird, and probably due to something in the encoding that isn't supported in caspar. Does ffmpeg play the clips properly (and without errors)? (There are ffmpeg windows builds that include decklink support, too.)

Do the clips have audio? Sometimes the presence of an audio stream mess up the overall run time, especially if the streams don't start at the same time.

Oh, and is this on all of your clips or just some? Could make one of a test pattern for a specific number of frames and see how that does.

Please let us know how it goes, I've wrestled with similar issues.

z!
zbang
 
Posts: 44
Joined: 10 Apr 2015, 02:09

Re: Caspar CG plays files in not timely manner.

Postby baoqngo » 15 May 2017, 20:50

The actually clip duration is 10:30.81 or (18904 frame) *Mediainfo as shown was rounded to seconds.

I've tried to play with ffmpeg it played completely fine with no error totaling 630.75 seconds

ffplay.png
ffplay
ffplay.png (18.97 KiB) Viewed 419 times


-The clip does have audio
-I do see some frame drop on the screen consumer diag (i'm not sure if that's just the consumer or the channel as a whole)

diag.png
diag
diag.png (70.2 KiB) Viewed 419 times


I've tested many clips as well as different frame rate etc. and have the same issue.

As you can see from the casparcg log.
Time the frame_muxer is initialized
[2017-05-15 11:55:35.304] [15432] [info] [frame_muxer] simple 1920x1080p29.97
Time the Clip is destroyed
[2017-05-15 12:05:59.104] [12956] [info] ffmpeg[dfw.mp4|1920x1080p29.97|18899/18904] Destroyed.
baoqngo
 
Posts: 3
Joined: 13 May 2017, 00:15

Re: Caspar CG plays files in not timely manner.

Postby Japri » 20 May 2017, 15:45

zbang wrote:Frame rate mismatch? (A 25fps clip played at 30fps?)

Anyway, please give us more details about the file(s) you're playing and the caspar setup; otherwise we're just guessing. If nothing else, post the log messages from the server (which is where errors would show, not in the client) from when you play the clip.

You might also try the 2.10 beta server.

z!


Sorry for the slow respond.

The file is 30 fps. i thought it might be error so i also convert it using handbreak.
Japri
 
Posts: 22
Joined: 20 Nov 2013, 16:13

Re: Caspar CG plays files in not timely manner.

Postby Japri » 20 May 2017, 16:48

Now I really know where's the culpirt belongs.

It belong to buggy decklink duo driver version 1.07.

I update it and it all ok now.

REF:
http://casparcg.com/forum/viewtopic.php?t=4023
Japri
 
Posts: 22
Joined: 20 Nov 2013, 16:13


Return to Help & Support

Who is online

Users browsing this forum: No registered users and 3 guests