0:00:00.302143000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:1295:gst_play_bin_set_uri:[00m set new uri to file:///media/hdd/movie/wav/Nightwish - Dark Passion Play - DTS/(03) [Nightwish] Amaranth.wav
Setting pipeline to PAUSED ...
0:00:00.317665000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned audio sink (NULL), the originally set audio sink is (NULL)
0:00:00.318056000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned video sink (NULL), the originally set video sink is (NULL)
0:00:00.318312000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned text sink (NULL), the originally set text sink is (NULL)
0:00:00.322407000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:3742:gst_play_bin_change_state:<playbin20>[00m clearing shutdown flag
0:00:00.322681000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3637:setup_next_source:<playbin20>[00m setup sources
0:00:00.322808000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3324:activate_group:<playbin20>[00m activating group 0x4aa3a4
0:00:00.322934000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3345:activate_group:<playbin20>[00m making new uridecodebin
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: ring-buffer-max-size = 0
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: buffer-size = -1
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: buffer-duration = -1
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: use-buffering = FALSE
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: download = FALSE
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: uri = "file:///media/hdd/movie/wav/Nightwish - Dark Passion Play - DTS/(03) [Nightwish] Amaranth.wav"
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: connection-speed = 0
0:00:00.337923000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3303:group_set_locked_state_unlocked:<playbin20>[00m locked_state 0 on group 0x4aa3a4
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0: source = (GstFileSrc) source
0:00:00.364879000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1093:gst_decode_bin_set_caps:<decodebin20>[00m Setting new caps: video/x-raw-yuv; video/x-raw-rgb; video/x-raw-gray; audio/x-raw-int; audio/x-raw-float; text/plain; text/x-pango-markup; video/x-dvd-subpicture; subpicture/x-pgs
0:00:00.366426000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1147:gst_decode_bin_set_subs_encoding:<decodebin20>[00m Setting new encoding: (NULL)
0:00:00.366574000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1149:gst_decode_bin_set_subs_encoding:<decodebin20>[00m subtitle locking from thread 0x418050
0:00:00.366692000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1149:gst_decode_bin_set_subs_encoding:<decodebin20>[00m subtitle lock from thread 0x418050
0:00:00.366807000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1158:gst_decode_bin_set_subs_encoding:<decodebin20>[00m subtitle unlocking from thread 0x418050
0:00:00.368893000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3894:gst_decode_bin_change_state:<decodebin20>[00m dynlocking from thread 0x418050
0:00:00.369098000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3894:gst_decode_bin_change_state:<decodebin20>[00m dynlocked from thread 0x418050
0:00:00.369218000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3895:gst_decode_bin_change_state:<decodebin20>[00m clearing shutdown flag
0:00:00.369395000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3897:gst_decode_bin_change_state:<decodebin20>[00m dynunlocking from thread 0x418050
0:00:00.369766000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2240:gst_play_bin_handle_message:<playbin20>[00m Ignoring async state change of uridecodebin: uridecodebin0
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstTypeFindElement:typefind.GstPad:src: caps = audio/x-wav
0:00:00.446413000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2239:type_found:<decodebin20>[00m typefind found caps audio/x-wav
0:00:00.446969000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2230:check_upstream_seekable:<decodebin20>[00m upstream seekable: 1
0:00:00.447133000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2718:gst_decode_chain_new:<decodebin20>[00m Creating new chain 0x4b2150 with parent group (nil)
0:00:00.447267000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1384:analyze_new_pad:<decodebin20>[00m Pad typefind:src caps:audio/x-wav
0:00:00.447428000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:3764:gst_decode_pad_new:<decodebin20>[00m making new decodepad
0:00:00.452285000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3131:autoplug_continue_cb:<playbin20>[00m continue autoplugging group 0x4aa3a4 for '':decodepad0, audio/x-wav: 1
0:00:00.452691000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1278:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:00.452869000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2497:are_final_caps:<decodebin20>[00m Checking with caps audio/x-wav
0:00:00.453049000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2504:are_final_caps:<decodebin20>[00m Caps are not final caps
0:00:00.453277000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2966:autoplug_factories_cb:<playbin20>[00m factories group 0x4aa3a4 for '':decodepad0, audio/x-wav
0:00:00.473196000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2976:autoplug_factories_cb:<playbin20>[00m found factories 0x4bb060
0:00:00.473813000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1601:analyze_new_pad:<typefind:src>[00m Let's continue discovery on this pad
0:00:00.473938000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1736:connect_pad:<decodebin20>[00m pad typefind:src , chain:0x4b2150
0:00:00.475105000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3180:autoplug_select_cb:<playbin20>[00m select group 0x4aa3a4 for '':decodepad0, audio/x-wav
0:00:00.475297000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3183:autoplug_select_cb:<playbin20>[00m checking factory wavparse
0:00:00.475466000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1850:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:00.508172000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1916:connect_pad:<decodebin20>[00m linked on pad typefind:src
0:00:00.508403000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1918:connect_pad:<decodebin20>[00m locking chain 0x4b2150 from thread 0x418050
0:00:00.508528000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1918:connect_pad:<decodebin20>[00m locked chain 0x4b2150 from thread 0x418050
0:00:00.508719000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1924:connect_pad:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x418050
0:00:00.508844000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2055:connect_element:<decodebin20>[00m Attempting to connect element wavparse0 [chain:0x4b2150] further
0:00:00.508970000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2068:connect_element:<decodebin20>[00m got a source pad template wavparse_src
0:00:00.509109000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2103:connect_element:<decodebin20>[00m did not get the sometimes pad of template wavparse_src
0:00:00.509228000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2119:connect_element:<decodebin20>[00m Adding signals to element wavparse0 in chain 0x4b2150
0:00:00.510403000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:2589:gst_wavparse_sink_activate:[00m going to pull mode
Pipeline is PREROLLING ...
0:00:00.521262000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:2036:gst_wavparse_loop:<wavparse0>[00m process data
0:00:00.521583000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:2040:gst_wavparse_loop:<wavparse0>[00m GST_WAVPARSE_START
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstWavParse:wavparse0.GstPad:sink: caps = audio/x-wav
0:00:00.523179000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:2048:gst_wavparse_loop:<wavparse0>[00m GST_WAVPARSE_HEADER
0:00:00.523714000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1226:gst_wavparse_stream_headers:<wavparse0>[00m creating the caps
0:00:00.524147000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1282:gst_wavparse_stream_headers:<wavparse0>[00m blockalign = 4
0:00:00.524268000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1283:gst_wavparse_stream_headers:<wavparse0>[00m width      = 16
0:00:00.524383000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1284:gst_wavparse_stream_headers:<wavparse0>[00m depth      = 16
0:00:00.524499000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1285:gst_wavparse_stream_headers:<wavparse0>[00m av_bps     = 176400
0:00:00.524615000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1286:gst_wavparse_stream_headers:<wavparse0>[00m frequency  = 44100
0:00:00.524732000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1287:gst_wavparse_stream_headers:<wavparse0>[00m channels   = 2
0:00:00.524847000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1288:gst_wavparse_stream_headers:<wavparse0>[00m bytes_per_sample = 4
0:00:00.524963000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1294:gst_wavparse_stream_headers:<wavparse0>[00m bps        = 176400
0:00:00.525844000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1296:gst_wavparse_stream_headers:<wavparse0>[00m caps = audio/x-raw-int, endianness=(int)1234, channels=(int)2, width=(int)16, depth=(int)16, signed=(boolean)true, rate=(int)44100
0:00:00.526993000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1319:gst_wavparse_stream_headers:<wavparse0>[00m upstream size 40819004
0:00:00.527361000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:1337:gst_wavparse_stream_headers:<wavparse0>[00m Got TAG: data, offset 36
0:00:00.527487000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1344:gst_wavparse_stream_headers:<wavparse0>[00m Got 'data' TAG, size : 40818960
0:00:00.527698000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1369:gst_wavparse_stream_headers:<wavparse0>[00m datasize = 40818960
0:00:00.527821000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1548:gst_wavparse_stream_headers:<wavparse0>[00m Finished parsing headers
0:00:00.527939000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:1121:gst_wavparse_calculate_duration:<wavparse0>[00m Got datasize 40818960
0:00:00.528096000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:1125:gst_wavparse_calculate_duration:<wavparse0>[00m Got duration (bps) 0:03:51.400000000
0:00:00.528227000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:818:gst_wavparse_perform_seek:<wavparse0>[00m doing seek without event
0:00:00.528370000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:892:gst_wavparse_perform_seek:<wavparse0>[00m stopped streaming at 0
0:00:00.528487000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:911:gst_wavparse_perform_seek:<wavparse0>[00m cur_type =2
0:00:00.528605000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:919:gst_wavparse_perform_seek:<wavparse0>[00m offset=0
0:00:00.528720000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:921:gst_wavparse_perform_seek:<wavparse0>[00m offset=0
0:00:00.528833000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:923:gst_wavparse_perform_seek:<wavparse0>[00m offset=44
0:00:00.528954000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:932:gst_wavparse_perform_seek:<wavparse0>[00m end_offset=40818960
0:00:00.529080000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:934:gst_wavparse_perform_seek:<wavparse0>[00m end_offset=40818960
0:00:00.529194000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:936:gst_wavparse_perform_seek:<wavparse0>[00m end_offset=40819004
0:00:00.529504000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:955:gst_wavparse_perform_seek:<wavparse0>[00m seek: rate 1.000000, offset 44, end 40819004, segment 0:00:00.000000000 -- 0:03:51.400000000
0:00:00.529708000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:990:gst_wavparse_perform_seek:<wavparse0>[00m Creating newsegment from 0 to 231400000000
0:00:00.530041000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1594:gst_wavparse_stream_headers:<wavparse0>[00m max buffer size 7056
0:00:00.530174000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:2053:gst_wavparse_loop:<wavparse0>[00m GST_WAVPARSE_DATA
0:00:00.530293000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:1832:gst_wavparse_stream_data:<wavparse0>[00m offset: 44 , end: 40819004 , dataleft: 40818960
0:00:00.530420000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:1848:gst_wavparse_stream_data:<wavparse0>[00m Fetching 7056 bytes of data from the sinkpad
0:00:00.530794000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1767:gst_wavparse_add_src_pad:<wavparse0>[00m adding src pad
0:00:00.570577000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:1777:gst_wavparse_add_src_pad:[00m typefind caps = audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false, P=100
0:00:00.571186000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:1779:gst_wavparse_add_src_pad:<wavparse0>[00m Found DTS marker in file marked as raw PCM
0:00:00.571984000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:242:gst_wavparse_create_sourcepad:<wavparse0>[00m srcpad created
0:00:00.572376000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2316:pad_added_cb:<wavparse0:src>[00m pad added, chain:0x4b2150
0:00:00.572541000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1384:analyze_new_pad:<decodebin20>[00m Pad wavparse0:src caps:audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
0:00:00.572909000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:3764:gst_decode_pad_new:<decodebin20>[00m making new decodepad
0:00:00.575134000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3131:autoplug_continue_cb:<playbin20>[00m continue autoplugging group 0x4aa3a4 for '':decodepad1, audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false: 1
0:00:00.575671000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1278:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:00.575816000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2497:are_final_caps:<decodebin20>[00m Checking with caps audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
0:00:00.576179000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2504:are_final_caps:<decodebin20>[00m Caps are not final caps
0:00:00.576398000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2966:autoplug_factories_cb:<playbin20>[00m factories group 0x4aa3a4 for '':decodepad1, audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
0:00:00.577478000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2976:autoplug_factories_cb:<playbin20>[00m found factories 0x47ac10
0:00:00.577946000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1601:analyze_new_pad:<wavparse0:src>[00m Let's continue discovery on this pad
0:00:00.578088000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1736:connect_pad:<decodebin20>[00m pad wavparse0:src , chain:0x4b2150
0:00:00.579086000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1824:connect_pad:<decodebin20>[00m locking chain 0x4b2150 from thread 0x4d54d0
0:00:00.579221000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1824:connect_pad:<decodebin20>[00m locked chain 0x4b2150 from thread 0x4d54d0
0:00:00.579344000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1834:connect_pad:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x4d54d0
0:00:00.579546000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3180:autoplug_select_cb:<playbin20>[00m select group 0x4aa3a4 for '':decodepad1, audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
0:00:00.579916000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3183:autoplug_select_cb:<playbin20>[00m checking factory dcaparse
0:00:00.580103000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1850:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:00.589932000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:502:gst_base_parse_init:<GstBaseParse@0x4e00d8>[00m gst_base_parse_init
0:00:00.590671000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:524:gst_base_parse_init:<GstBaseParse@0x4e00d8>[00m sinkpad created
0:00:00.591221000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:538:gst_base_parse_init:<GstBaseParse@0x4e00d8>[00m src created
0:00:00.591601000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:550:gst_base_parse_init:<GstBaseParse@0x4e00d8>[00m init ok
0:00:00.591738000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2948:gst_base_parse_set_min_frame_size:<GstDcaParse@0x4e00d8>[00m set frame_min_size: 96
0:00:00.592814000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1916:connect_pad:<decodebin20>[00m linked on pad wavparse0:src
0:00:00.592974000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1918:connect_pad:<decodebin20>[00m locking chain 0x4b2150 from thread 0x4d54d0
0:00:00.593118000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1918:connect_pad:<decodebin20>[00m locked chain 0x4b2150 from thread 0x4d54d0
0:00:00.593261000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1924:connect_pad:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x4d54d0
0:00:00.593382000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2055:connect_element:<decodebin20>[00m Attempting to connect element dcaparse0 [chain:0x4b2150] further
0:00:00.593513000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2068:connect_element:<decodebin20>[00m got a source pad template src
0:00:00.593645000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2079:connect_element:<decodebin20>[00m got the pad for always template src
0:00:00.593842000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1384:analyze_new_pad:<decodebin20>[00m Pad dcaparse0:src caps:audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]
0:00:00.594373000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:3764:gst_decode_pad_new:<decodebin20>[00m making new decodepad
0:00:00.596329000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3131:autoplug_continue_cb:<playbin20>[00m continue autoplugging group 0x4aa3a4 for '':decodepad2, audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]: 1
0:00:00.596952000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1278:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:00.597112000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2497:are_final_caps:<decodebin20>[00m Checking with caps audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]
0:00:00.597583000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2504:are_final_caps:<decodebin20>[00m Caps are not final caps
0:00:00.597795000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2966:autoplug_factories_cb:<playbin20>[00m factories group 0x4aa3a4 for '':decodepad2, audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]
0:00:00.599003000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2976:autoplug_factories_cb:<playbin20>[00m found factories 0x4d6e70
0:00:00.599553000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1562:analyze_new_pad:[00m Trying factory dcaparse
0:00:00.599669000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1565:analyze_new_pad:[00m Skipping factory
0:00:00.599774000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1562:analyze_new_pad:[00m Trying factory dvbaudiosink
0:00:00.605955000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1601:analyze_new_pad:<capsfilter0:src>[00m Let's continue discovery on this pad
0:00:00.606272000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1736:connect_pad:<decodebin20>[00m pad capsfilter0:src , chain:0x4b2150
0:00:00.607376000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1824:connect_pad:<decodebin20>[00m locking chain 0x4b2150 from thread 0x4d54d0
0:00:00.607527000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1824:connect_pad:<decodebin20>[00m locked chain 0x4b2150 from thread 0x4d54d0
0:00:00.607654000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1834:connect_pad:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x4d54d0
0:00:00.607783000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1838:connect_pad:<decodebin20>[00m Skipping factory 'dcaparse' because it was already used in this chain
0:00:00.608800000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3180:autoplug_select_cb:<playbin20>[00m select group 0x4aa3a4 for '':decodepad2, audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]
0:00:00.609406000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3183:autoplug_select_cb:<playbin20>[00m checking factory dvbaudiosink
0:00:00.609539000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3191:autoplug_select_cb:<playbin20>[00m we found a sink
0:00:00.609660000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3197:autoplug_select_cb:<playbin20>[00m we found an audio sink
0:00:00.609784000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3240:autoplug_select_cb:<playbin20>[00m we have no pending sink, try to create one
0:00:00.617541000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m        dvbaudiosink gstdvbaudiosink.c:222:gst_dvbaudiosink_base_init:[00m model is DM8000 set broadcom dts caps
0:00:00.620257000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:1061:gst_dvbaudiosink_change_state:<dvbaudiosink0>[00m GST_STATE_CHANGE_NULL_TO_READY
0:00:00.620461000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:995:gst_dvbaudiosink_start:<dvbaudiosink0>[00m start
0:00:00.621135000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:1023:gst_dvbaudiosink_stop:<dvbaudiosink0>[00m stop
0:00:00.621334000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:1106:gst_dvbaudiosink_change_state:<dvbaudiosink0>[00m GST_STATE_CHANGE_READY_TO_NULL
0:00:00.621580000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:325:gst_dvbaudiosink_dispose:<dvbaudiosink0>[00m dispose
0:00:00.621713000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:331:gst_dvbaudiosink_dispose:<dvbaudiosink0>[00m success
0:00:00.621826000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m        dvbaudiosink gstdvbaudiosink.c:354:gst_dvbaudiosink_dispose:<dvbaudiosink0>[00m state in dispose 1, pending 0
0:00:00.622318000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1859:connect_pad:<decodebin20>[00m autoplug select requested skip
0:00:00.623201000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:3899:gst_base_parse_change_state:<dcaparse0>[00m no index provided creating our own
0:00:00.630747000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2761:gst_base_parse_sink_activate:<dcaparse0>[00m sink activate
0:00:00.630977000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2767:gst_base_parse_sink_activate:<dcaparse0>[00m trying to activate in push mode
0:00:00.631183000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2812:gst_base_parse_sink_activate_push:<dcaparse0>[00m sink activate push 1
0:00:00.631301000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2782:gst_base_parse_activate:<dcaparse0>[00m activate 1
0:00:00.631421000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:150:gst_dca_parse_start:<dcaparse0>[00m starting
0:00:00.631537000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2800:gst_base_parse_activate:<dcaparse0>[00m activate return: 1
0:00:00.631653000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2819:gst_base_parse_sink_activate_push:<dcaparse0>[00m sink activate push return: 1
0:00:00.631775000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2771:gst_base_parse_sink_activate:<dcaparse0>[00m sink activate return 1
0:00:00.632926000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2323:pad_added_cb:<decodebin20>[00m expose locking from thread 0x4d54d0
0:00:00.633128000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2323:pad_added_cb:<decodebin20>[00m expose locked from thread 0x4d54d0
0:00:00.633248000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3042:gst_decode_chain_is_complete:<decodebin20>[00m locking chain 0x4b2150 from thread 0x4d54d0
0:00:00.633373000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3042:gst_decode_chain_is_complete:<decodebin20>[00m locked chain 0x4b2150 from thread 0x4d54d0
0:00:00.633493000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:3062:gst_decode_chain_is_complete:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x4d54d0
0:00:00.633614000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:3063:gst_decode_chain_is_complete:<decodebin20>[00m Chain 0x4b2150 is complete: 0
0:00:00.633738000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2330:pad_added_cb:<decodebin20>[00m expose unlocking from thread 0x4d54d0
0:00:00.633938000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2362:no_more_pads_cb:<wavparse0>[00m got no more pads
0:00:00.634074000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2364:no_more_pads_cb:<decodebin20>[00m locking chain 0x4b2150 from thread 0x4d54d0
0:00:00.634195000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2364:no_more_pads_cb:<decodebin20>[00m locked chain 0x4b2150 from thread 0x4d54d0
0:00:00.634316000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2368:no_more_pads_cb:<decodebin20>[00m no-more-pads from old chain element 'wavparse0'
0:00:00.634435000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2369:no_more_pads_cb:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x4d54d0
0:00:00.634627000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1808:gst_wavparse_add_src_pad:<wavparse0>[00m Send start segment event on newpad
0:00:00.634812000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:859:gst_base_parse_sink_event:<dcaparse0>[00m handling event 102, newsegment
0:00:00.635012000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:929:gst_base_parse_sink_eventfunc:<dcaparse0>[00m newseg rate 1, applied rate 1, format 3, start = 0:00:00.000000000, stop = 0:03:51.400000000, pos = 0:00:00.000000000
0:00:00.635241000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1010:gst_base_parse_sink_eventfunc:<dcaparse0>[00m draining current segment
0:00:00.635367000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1977:gst_base_parse_drain:<dcaparse0>[00m draining
0:00:00.635498000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:893:gst_base_parse_sink_event:<dcaparse0>[00m event handled
0:00:00.635718000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:859:gst_base_parse_sink_event:<dcaparse0>[00m handling event 118, tag
0:00:00.635891000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:893:gst_base_parse_sink_event:<dcaparse0>[00m event handled
0:00:00.636760000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:1967:gst_wavparse_stream_data:<wavparse0>[00m marking DISCONT
0:00:00.636920000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:1981:gst_wavparse_stream_data:<wavparse0>[00m Got buffer. timestamp:0:00:00.000000000 , duration:0:00:00.040000000, size:7056
0:00:00.637155000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:3837:gst_base_parse_sink_setcaps:<dcaparse0>[00m caps: audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstDcaParse:dcaparse0.GstPad:sink: caps = audio/x-dts, rate=(int)44100, channels=(int)6, depth=(int)14, endianness=(int)1234, framed=(boolean)false
0:00:00.638553000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2192:gst_base_parse_chain:<dcaparse0>[00m buffer size: 7056, offset = 0
0:00:00.638768000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2267:gst_base_parse_chain:<dcaparse0>[00m marking DISCONT
0:00:00.639496000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:380:gst_dca_parse_check_valid_frame:<dcaparse0>[00m possible sync ff1f00e8 at buffer offset 288
0:00:00.639627000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2291:gst_base_parse_chain:<dcaparse0>[00m finding sync, skipping 288 bytes
0:00:00.639751000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2267:gst_base_parse_chain:<dcaparse0>[00m marking DISCONT
0:00:00.640403000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:380:gst_dca_parse_check_valid_frame:<dcaparse0>[00m possible sync ff1f00e8 at buffer offset 0
0:00:00.640526000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:236:gst_dca_parse_parse_header:<dcaparse0>[00m dts sync marker 0xff1f00e8 at offset 0
0:00:00.640647000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:255:gst_dca_parse_parse_header:<dcaparse0>[00m frame header: fc7cdff262c00d3b
0:00:00.640769000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:396:gst_dca_parse_check_valid_frame:<dcaparse0>[00m got frame, sync ff1f00e8, size 4096, rate 44100, channels 6
0:00:00.641013000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:406:gst_dca_parse_check_valid_frame:<dcaparse0>[00m resyncing; checking next frame syncword
0:00:00.641584000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:236:gst_dca_parse_parse_header:<dcaparse0>[00m dts sync marker 0xff1f00e8 at offset 4096
0:00:00.641710000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:255:gst_dca_parse_parse_header:<dcaparse0>[00m frame header: fc7cdff262c00d3b
0:00:00.641836000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2948:gst_base_parse_set_min_frame_size:<dcaparse0>[00m set frame_min_size: 4096
0:00:00.641975000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2283:gst_base_parse_chain:<dcaparse0>[00m valid frame of size 4096 at pos -1
0:00:00.642240000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1610:gst_base_parse_handle_and_push_frame:<dcaparse0>[00m marking DISCONT
0:00:00.642508000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:2453:gst_wavparse_pad_query:<wavparse0:src>[00m seeking query
0:00:00.642693000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1520:gst_base_parse_check_seekability:<dcaparse0>[00m seekable: 0 (0 - 231400000000)
0:00:00.642821000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1524:gst_base_parse_check_seekability:<dcaparse0>[00m idx_interval: 0ms
0:00:00.643010000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            wavparse gstwavparse.c:2453:gst_wavparse_pad_query:<wavparse0:src>[00m duration query
0:00:00.643973000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2896:gst_base_parse_set_duration:<dcaparse0>[00m set duration: 231400000000
0:00:00.644176000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2903:gst_base_parse_set_duration:<dcaparse0>[00m set update interval: 0
0:00:00.644290000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1543:gst_base_parse_check_upstream:<dcaparse0>[00m upstream_has_duration: 1
0:00:00.644406000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:1625:gst_base_parse_handle_and_push_frame:<dcaparse0>[00m parsing frame at offset 288 (0x120) of size 4096
0:00:00.644535000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:236:gst_dca_parse_parse_header:<dcaparse0>[00m dts sync marker 0xff1f00e8 at offset 0
0:00:00.644652000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:255:gst_dca_parse_parse_header:<dcaparse0>[00m frame header: fc7cdff262c00d3b
0:00:00.645016000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:171:gst_dca_parse_negotiate:<dcaparse0>[00m ***gst_dca_parse_negotiate*** allowed caps: audio/x-dts, framed=(boolean)true, channels=(int)[ 1, 8 ], rate=(int)[ 8000, 192000 ], depth=(int){ 14, 16 }, endianness=(int){ 1234, 4321 }, block-size=(int)[ 1, 2147483647 ], frame-size=(int)[ 1, 2147483647 ]
0:00:00.646489000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:183:gst_dca_parse_negotiate:<dcaparse0>[00m downstream depth not negotiated, stays native 14
0:00:00.646625000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:191:gst_dca_parse_negotiate:<dcaparse0>[00m downstream endianness not negotiated, stays native 1234
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstDcaParse:dcaparse0.GstPad:src: caps = audio/x-dts, framed=(boolean)true, rate=(int)44100, channels=(int)6, endianness=(int)1234, depth=(int)14, block-size=(int)1024, frame-size=(int)4096
0:00:00.648124000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2999:gst_base_parse_set_frame_rate:<dcaparse0>[00m set fps: 44100/1024 => duration: 23 ms
0:00:00.648332000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:3003:gst_base_parse_set_frame_rate:<dcaparse0>[00m set lead in: 0 frames = 0 ms, lead out: 0 frames = 0 ms
0:00:00.648475000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:1750:gst_base_parse_push_frame:<dcaparse0>[00m processing buffer of size 4096 with ts 0:00:00.000000000, duration 0:00:00.023219954
0:00:00.648622000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1860:gst_base_parse_push_frame:<dcaparse0>[00m chain push pending segment
0:00:00.648916000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1563:gst_base_parse_check_media:<dcaparse0>[00m media is video: 0
0:00:00.649056000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:1343:gst_base_parse_update_bitrates:<dcaparse0>[00m frame bitrate 1411200, avg bitrate 1411200
0:00:00.649261000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:1933:gst_base_parse_push_frame:<dcaparse0>[00m pushing frame (4096 bytes) now..
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstCapsFilter:capsfilter0.GstPad:src: caps = audio/x-dts, framed=(boolean)true, rate=(int)44100, channels=(int)6, endianness=(int)1234, depth=(int)14, block-size=(int)1024, frame-size=(int)4096
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstCapsFilter:capsfilter0.GstPad:sink: caps = audio/x-dts, framed=(boolean)true, rate=(int)44100, channels=(int)6, endianness=(int)1234, depth=(int)14, block-size=(int)1024, frame-size=(int)4096
0:00:00.652218000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:1935:gst_base_parse_push_frame:<dcaparse0>[00m frame pushed, flow not-linked
0:00:00.652379000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2363:gst_base_parse_chain:<dcaparse0>[00m push returned -1
0:00:00.652499000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2369:gst_base_parse_chain:<dcaparse0>[00m chain leaving
0:00:00.652639000 [331m 1116[00m   0x4d54d0 [32;01mINFO   [00m [00m            wavparse gstwavparse.c:2025:gst_wavparse_stream_data:<wavparse0>[00m Error pushing on srcpad wavparse0:src, reason not-linked, is linked? = 1
0:00:00.652769000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m            wavparse gstwavparse.c:2070:gst_wavparse_loop:<wavparse0>[00m pausing task, reason not-linked
0:00:00.652976000 [331m 1116[00m   0x4d54d0 [33;01mWARN   [00m [00m            wavparse gstwavparse.c:2104:gst_wavparse_loop:<wavparse0>[00m error: Internal data flow error.
0:00:00.653163000 [331m 1116[00m   0x4d54d0 [33;01mWARN   [00m [00m            wavparse gstwavparse.c:2104:gst_wavparse_loop:<wavparse0>[00m error: streaming task paused, reason not-linked (-1)
0:00:00.654820000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:859:gst_base_parse_sink_event:<dcaparse0>[00m handling event 86, eos
0:00:00.657981000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned audio sink (NULL), the originally set audio sink is (NULL)
0:00:00.658352000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned video sink (NULL), the originally set video sink is (NULL)
0:00:00.658601000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:1899:gst_play_bin_get_current_sink:<playbin20>[00m play_sink_get_sink() returned text sink (NULL), the originally set text sink is (NULL)
0:00:00.660531000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1111:gst_decode_bin_get_caps:<decodebin20>[00m Getting currently set caps
0:00:00.660875000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1166:gst_decode_bin_get_subs_encoding:<decodebin20>[00m Getting currently set encoding
0:00:00.660999000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1168:gst_decode_bin_get_subs_encoding:<decodebin20>[00m subtitle locking from thread 0x418050
0:00:00.661141000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1168:gst_decode_bin_get_subs_encoding:<decodebin20>[00m subtitle lock from thread 0x418050
0:00:00.661256000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1170:gst_decode_bin_get_subs_encoding:<decodebin20>[00m subtitle unlocking from thread 0x418050
0:00:00.661392000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1135:gst_decode_bin_get_sink_caps:<decodebin20>[00m Getting currently set caps
0:00:00.669717000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1283:gst_base_parse_post_bitrates:<dcaparse0>[00m Updated bitrates. Min: 4294967295, Avg: 1411200, Max: 0
ERROR: from element /GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstWavParse:wavparse0: Internal data flow error.
Additional debug info:
gstwavparse.c(2104): gst_wavparse_loop (): /GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstWavParse:wavparse0:
streaming task paused, reason not-linked (-1)
ERROR: pipeline doesn't want to preroll.
Setting pipeline to NULL ...
0:00:00.672522000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:3754:gst_play_bin_change_state:<playbin20>[00m setting shutdown flag
0:00:00.672681000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m            playbin2 gstplaybin2.c:3762:gst_play_bin_change_state:<playbin20>[00m dynamic lock taken, we can continue shutdown
0:00:00.672801000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3714:groups_set_locked_state:<playbin20>[00m setting locked state to 0 on all groups
0:00:00.672923000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3303:group_set_locked_state_unlocked:<playbin20>[00m locked_state 0 on group 0x4aa3a4
0:00:00.673066000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3303:group_set_locked_state_unlocked:<playbin20>[00m locked_state 0 on group 0x4aa268
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstCapsFilter:capsfilter0.GstPad:src: caps = NULL
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstCapsFilter:capsfilter0.GstPad:sink: caps = NULL
0:00:00.677410000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2812:gst_base_parse_sink_activate_push:<dcaparse0>[00m sink activate push 0
0:00:00.677573000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2782:gst_base_parse_activate:<dcaparse0>[00m activate 0
0:00:00.670678000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1977:gst_base_parse_drain:<dcaparse0>[00m draining
0:00:00.677875000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2244:gst_base_parse_chain:<dcaparse0>[00m draining, data left: 2672
0:00:00.678041000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:380:gst_dca_parse_check_valid_frame:<dcaparse0>[00m possible sync 00000000 at buffer offset 0
0:00:00.678175000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:236:gst_dca_parse_parse_header:<dcaparse0>[00m dts sync marker 0xff1f00e8 at offset 0
0:00:00.678293000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:255:gst_dca_parse_parse_header:<dcaparse0>[00m frame header: fc7cdff262c00d3b
0:00:00.678466000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m            dcaparse gstdcaparse.c:396:gst_dca_parse_check_valid_frame:<dcaparse0>[00m got frame, sync 00000000, size 4096, rate 44100, channels 6
0:00:00.678606000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2279:gst_base_parse_chain:<dcaparse0>[00m found valid frame but not enough data available (only 2672 bytes)
0:00:00.678740000 [331m 1116[00m   0x4d54d0 [37mLOG    [00m [00m           baseparse gstbaseparse.c:2369:gst_base_parse_chain:<dcaparse0>[00m chain leaving
0:00:00.678859000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:1991:gst_base_parse_drain:<dcaparse0>[00m no change during draining; flushing
0:00:00.679177000 [331m 1116[00m   0x4d54d0 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:893:gst_base_parse_sink_event:<dcaparse0>[00m event handled
0:00:00.679336000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            dcaparse gstdcaparse.c:160:gst_dca_parse_stop:<dcaparse0>[00m stopping
0:00:00.679452000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2800:gst_base_parse_activate:<dcaparse0>[00m activate return: 1
0:00:00.679566000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m           baseparse gstbaseparse.c:2819:gst_base_parse_sink_activate_push:<dcaparse0>[00m sink activate push return: 1
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstDcaParse:dcaparse0.GstPad:src: caps = NULL
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstDcaParse:dcaparse0.GstPad:sink: caps = NULL
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstWavParse:wavparse0.GstPad:src: caps = NULL
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstWavParse:wavparse0.GstPad:sink: caps = NULL
0:00:00.684817000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2338:pad_removed_cb:<wavparse0:src>[00m pad removed, chain:0x4b2150
0:00:00.684977000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2342:pad_removed_cb:<decodebin20>[00m locking chain 0x4b2150 from thread 0x418050
0:00:00.685911000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2342:pad_removed_cb:<decodebin20>[00m locked chain 0x4b2150 from thread 0x418050
0:00:00.686113000 [331m 1116[00m   0x418050 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2354:pad_removed_cb:<decodebin20>[00m unlocking chain 0x4b2150 from thread 0x418050
/GstPlayBin2:playbin20/GstURIDecodeBin:uridecodebin0/GstDecodeBin2:decodebin20/GstTypeFindElement:typefind.GstPad:src: caps = NULL
0:00:00.690653000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:2240:gst_play_bin_handle_message:<playbin20>[00m Ignoring async state change of uridecodebin: uridecodebin0
0:00:00.692130000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3691:save_current_group:<playbin20>[00m save current group
0:00:00.692331000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3544:deactivate_group:<playbin20>[00m unlinking group 0x4aa3a4
0:00:00.692453000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3551:deactivate_group:<playbin20>[00m unlinking selector audio/
0:00:00.692568000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3551:deactivate_group:<playbin20>[00m unlinking selector video/
0:00:00.692684000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3551:deactivate_group:<playbin20>[00m unlinking selector text/
0:00:00.693369000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3714:groups_set_locked_state:<playbin20>[00m setting locked state to 1 on all groups
0:00:00.693563000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3303:group_set_locked_state_unlocked:<playbin20>[00m locked_state 1 on group 0x4aa268
0:00:00.693684000 [331m 1116[00m   0x418050 [36mDEBUG  [00m [00m            playbin2 gstplaybin2.c:3303:group_set_locked_state_unlocked:<playbin20>[00m locked_state 1 on group 0x4aa3a4
Freeing pipeline ...
