View Bug Details

IDProjectCategoryView StatusLast Update
0001136DCP-o-maticBugspublic2018-10-17 20:16
Reportercarl Assigned Tocarl  
PrioritynormalSeverityminorReproducibilityhave not tried
Status closedResolutionfixed 
Target Version2.12.0 
Summary0001136: Problems with 2.11.x on DSS200
Description

Everything slows up and it end up with "Transport not available."

Bug present in 2.11.24;2.11.26 not in 2.10.5.

TagsNo tags attached.
Branch
Estimated weeks required
Estimated work requiredUnknown

Activities

carl

2017-12-07 11:33

administrator   ~0001845

Jonathan Jensen has seen similar with Barco ICMP (v1.2.7.10.17247).

carl

2017-12-07 11:35

administrator   ~0001846

He says same DCPs work fine on Doremi IMB/SV, DCP2000, IMS2000.

carl

2017-12-13 11:51

administrator   ~0001849

Last edited: 2017-12-13 11:54

Gunnar reports that this started after 2.11.22. Almost certainly d505cef which bumps libdcp 047d8ff..7e67afe and hence openjpeg2-cdist from 8ea62cc..bf14163. This is from somewhere before 2.2.0 to somewhere after.

Carsten

2017-12-19 14:53

manager   ~0001859

Is it possible to do some basic analysis on mxf files created before/after? e.g. 2.10.5. and 2.11.27?

I have a few colleagues around with DSS200, but I guess I need to come well prepared to keep testing time to a minimum, and would like to know what to look for.

Will also check with our Barco ICMP shortly. This creates a very explicit decoder log, which might help.

  • Carsten

carl

2017-12-27 22:09

administrator   ~0001861

hacks/j2k_dump.py needs to be extended to see if there are differences in the codestreams. As it stands there are non apparent between 2.10.5 and 2.11.x.

Carsten

2017-12-31 00:17

manager   ~0001872

Today I created BigBuckBunny test DCPs, both IOP and SMPTE, with 2.10.5, and 2.11.31 (WIN64). Our Barco ICMP with most recent firmware on it played the 2.10.5 versions okay, but refused to play the 2.11.31 DCPs. Sometimes it would play them, but stutter, stop, etc. There must be something wrong with the codestream.
I looked up the logs, there are issues, but no clear-text remarks that would help us. All four DCPs load and play okay in DOM player. As the Barco choked on the 2.11.31 files, I didn't even bother to try playing them on our Sony.

  • Carsten

Carsten

2017-12-31 00:31

manager   ~0001873

Dec 30 22:59:59 FFBARCO user.debug SM: IMB Event- J2K decoder event wd0=338 wd1=80000 at FrameId=824
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Event- J2K decoder event wd0=0 wd1=40000 at FrameId=0
Dec 30 22:59:59 FFBARCO user.info SM: IMB Event- Monitoring event wd0=89 wd1=108000 FifoLevelBelowThreshold 4 - acceptable at play startup
Dec 30 22:59:59 FFBARCO user.warning SM: IMB Event- Monitoring event wd0=102680 wd1=18023 J2KtoMemBufferEmpty VideoFifo=0 AudioFifo=77 Audio2MemFifo=64
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1e00: 0bad0add 00200304 40010100 00bb8019 00000000 00001001 00000000 00100000
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1e20: 10000000 00000000 00000000 10000000 000027c0 00010320 00010320 00000232
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1e40: 00000002 1512003e 00000000 00000000 00000000 00000000 00000000 0bad0add
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1e60: 00000400 0bad0add 0bad0add 0bad0add 00410505 0bad0add 0bad0add 0bad0add
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1e80: 0bad0add 0bad0add 0bad0add 0bad0add 0bad0add 0bad0add 0bad0add 0bad0add
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1ea0: 0bad0add 0bad0add 00000000 00400000 00014014 0000008c 0000008c 0000005a
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1ec0: 00000000 00ffffff 00000000 00000000 0040004e 00000333 00000000 00000033
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- debug info 1ee0: dff30cf3 00a1dff3 62e5ffca 00ffffca 3fffffff 00000000 00000000 0bad0add
Dec 30 22:59:59 FFBARCO user.debug SMS: SMS- Buffer depletion, enter to refill mode...
Dec 30 22:59:59 FFBARCO user.err SMS: Player- Player error: player - buffering in progress
Dec 30 22:59:59 FFBARCO user.warning SMS: Player- Holding playback: pausing to refill buffers...
Dec 30 22:59:59 FFBARCO user.err SM: IMB Controller- pause not allowed in current state: starting play
Dec 30 22:59:59 FFBARCO user.err SMS: Player- Holding playback pause error: sm error 10998 - require user action
Dec 30 22:59:59 FFBARCO user.debug SMS: Player- Player subtitles: set gip timeline: PREPARE_TO_STOP
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Event- J2K decoder event wd0=5a wd1=80000 at FrameId=90
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Event- J2K decoder event wd0=0 wd1=40000 at FrameId=0
Dec 30 22:59:59 FFBARCO user.debug SMS: Player- Player subtitles: set gip timeline: STOP
Dec 30 22:59:59 FFBARCO user.debug SMS: Player- starting streams at frame id: 0 - streams: AV
Dec 30 22:59:59 FFBARCO user.debug SM: IMB Controller- Sync Payload Updated (frameId: 0, status: 0)
Dec 30 22:59:59 FFBARCO user.debug SMS: Player- Player subtitles: updating gip timeline: 00:00:00:000
Dec 30 22:59:59 FFBARCO user.info SMS: Report- |STOPPED|

carl

2018-01-01 23:55

administrator   ~0001880

See http://www.dcpomatic.com/forum/viewtopic.php?f=2&t=965

carl

2018-01-01 23:55

administrator   ~0001881

Bisecting openjpeg looking for the 0x00/0x80 swaps that Carsten spotted (in the forum thread) points at openjpeg commit 2609fb8077125b5b31f1bcc2f98c12ff1e6572d7

carl

2018-01-02 00:48

administrator   ~0001882

2.11.34 reverts that openjpeg commit to see if it makes anything better.

carl

2018-01-03 14:55

administrator   ~0001898

Believed fixed in 2.11.34.

Bug History

Date Modified Username Field Change
2017-10-18 13:34 carl New Bug
2017-12-07 11:33 carl Note Added: 0001845
2017-12-07 11:35 carl Note Added: 0001846
2017-12-13 11:51 carl Note Added: 0001849
2017-12-13 11:54 carl Note Edited: 0001849
2017-12-19 14:53 Carsten Note Added: 0001859
2017-12-27 22:09 carl Note Added: 0001861
2017-12-31 00:17 Carsten Note Added: 0001872
2017-12-31 00:31 Carsten Note Added: 0001873
2018-01-01 23:55 carl Note Added: 0001880
2018-01-01 23:55 carl Note Added: 0001881
2018-01-02 00:48 carl Note Added: 0001882
2018-01-02 01:14 carl Assigned To => carl
2018-01-02 01:14 carl Status new => confirmed
2018-01-03 14:55 carl Status confirmed => resolved
2018-01-03 14:55 carl Resolution open => fixed
2018-01-03 14:55 carl Note Added: 0001898
2018-10-17 20:16 carl Status resolved => closed