Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Can't run pipeline #2

Open
dmitry-pozhidaev opened this issue Jul 25, 2017 · 1 comment
Open

Can't run pipeline #2

dmitry-pozhidaev opened this issue Jul 25, 2017 · 1 comment

Comments

@dmitry-pozhidaev
Copy link

dmitry-pozhidaev commented Jul 25, 2017 •
edited
Loading

Hi
I have board IMX.6 Freescale based.

I try use this:
gst-launch-1.0 fakesrc ! "audio/x-raw,format=S16LE,rate=8000,channels=1,channel-mask=(bitmask)1" ! g729enc ! g729dec ! fakesink

and I have no error messages.

But if I try run like this:
gst-launch-1.0 audiotestsrc ! "audio/x-raw,format=S16LE,rate=8000,channels=1,channel-mask=(bitmask)1" ! g729enc ! g729dec ! fakesink

I got many error lines:
export GST_DEBUG=4

........
Pipeline is PREROLLING ...
0:00:00.177057334  1340   0x642430 INFO               GST_EVENT 
gstevent.c:809:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)8000, channels=(int)1, channel-mask=(bitmask)0x0000000000000001
0:00:00.177866000  1340   0x642430 INFO           basetransform gstbasetransform.c:1299:gst_base_transform_setcaps:<capsfilter0> reuse caps
0:00:00.178261667  1340   0x642430 INFO               GST_EVENT gstevent.c:809:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)8000, channels=(int)1, channel-mask=(bitmask)0x0000000000000001
0:00:00.184230334  1340   0x642430 INFO               GST_EVENT gstevent.c:890:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.184865667  1340   0x642430 INFO            audioencoder gstaudioencoder.c:1635:gst_audio_encoder_sink_event_default:<g729enc0> upstream stream tags: taglist, description=(string)"audiotest\ wave";
0:00:00.185255334  1340   0x642430 INFO                 basesrc gstbasesrc.c:2836:gst_base_src_loop:<audiotestsrc0> marking pending DISCONT
0:00:00.186171334  1340   0x642430 INFO               GST_EVENT gstevent.c:809:gst_event_new_caps: creating caps event audio/G729, rate=(int)8000, channels=(int)1
0:00:00.186932334  1340   0x642430 INFO               structure gststructure.c:2595:gst_structure_get_valist: Expected field 'channel-mask' in structure: audio/x-raw, format=(string)S16LE, rate=(int)8000, channels=(int)1, layout=(string)interleaved;
0:00:00.190812000  1340   0x642430 INFO            audiodecoder gstaudiodecoder.c:2292:gst_audio_decoder_sink_eventfunc:<g729dec0> upstream stream tags: taglist, description=(string)"audiotest\ wave";
0:00:00.191876667  1340   0x642430 INFO               GST_EVENT gstevent.c:809:gst_event_new_caps: creating caps event audio/x-raw, format=(string)S16LE, layout=(string)interleaved, rate=(int)8000, channels=(int)1
0:00:00.194170334  1340   0x642430 INFO              GST_STATES gstbin.c:3409:bin_handle_async_done:<pipeline0> committing state from READY to PAUSED, old pending PAUSED
0:00:00.194586667  1340   0x642430 INFO              GST_STATES gstbin.c:3429:bin_handle_async_done:<pipeline0> completed state change, pending VOID
0:00:00.194931667  1340   0x642430 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
0:00:00.196076334  1340   0x52e600 INFO               GST_EVENT gstevent.c:1383:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.196249667  1340   0x52e600 INFO                     bin gstbin.c:2768:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:00.196349667  1340   0x52e600 INFO              GST_STATES gstbin.c:2491:gst_bin_element_set_state:<fakesink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.196657000  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<fakesink0> completed state change to PLAYING
0:00:00.196732667  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<fakesink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.196807000  1340   0x52e600 INFO              GST_STATES gstbin.c:2939:gst_bin_change_state_func:<pipeline0> child 'fakesink0' changed state to 4(PLAYING) successfully
0:00:00.196874667  1340   0x52e600 INFO              GST_STATES gstbin.c:2491:gst_bin_element_set_state:<g729dec0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.196930334  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<g729dec0> completed state change to PLAYING
0:00:00.196970334  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<g729dec0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.197035334  1340   0x52e600 INFO              GST_STATES gstbin.c:2939:gst_bin_change_state_func:<pipeline0> child 'g729dec0' changed state to 4(PLAYING) successfully
0:00:00.197105334  1340   0x52e600 INFO              GST_STATES gstbin.c:2491:gst_bin_element_set_state:<g729enc0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.197174667  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<g729enc0> completed state change to PLAYING
0:00:00.197222334  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<g729enc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.197310667  1340   0x52e600 INFO              GST_STATES gstbin.c:2939:gst_bin_change_state_func:<pipeline0> child 'g729enc0' changed state to 4(PLAYING) successfully
0:00:00.197386334  1340   0x52e600 INFO              GST_STATES gstbin.c:2491:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.197467667  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<capsfilter0> completed state change to PLAYING
0:00:00.197544667  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.197629334  1340   0x52e600 INFO              GST_STATES gstbin.c:2939:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 4(PLAYING) successfully
0:00:00.197692000  1340   0x52e600 INFO              GST_STATES gstbin.c:2491:gst_bin_element_set_state:<audiotestsrc0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.197763000  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<audiotestsrc0> completed state change to PLAYING
0:00:00.197828667  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<audiotestsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.197907334  1340   0x52e600 INFO              GST_STATES gstbin.c:2939:gst_bin_change_state_func:<pipeline0> child 'audiotestsrc0' changed state to 4(PLAYING) successfully
0:00:00.197990334  1340   0x52e600 INFO              GST_STATES gstelement.c:2467:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:00.198055334  1340   0x52e600 INFO              GST_STATES gstelement.c:2372:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
New clock: GstSystemClock

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

(gst-launch-1.0:1340): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

and so on

export GST_DEBUG=5

(gst-launch-1.0:1343): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed
0:00:15.033829335  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<g729dec0:sink> called chainfunction &gst_audio_decoder_chain with buffer 0x75f051f8, returned ok
0:00:15.033907668  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f06060
0:00:15.033977002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f06010
0:00:15.034047335  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0xaa7f88, maxsize:160 offset:0 size:160
0:00:15.034120335  1343   0xaae830 DEBUG           audioencoder gstaudioencoder.c:2872:gst_audio_encoder_allocate_output_buffer: alloc src buffer
0:00:15.034178335  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f2c008, maxsize:13 offset:0 size:10
0:00:15.034959002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f06290, maxsize:2051 offset:1024 size:160
0:00:15.035057002  1343   0xaae830 DEBUG           audioencoder gstaudioencoder.c:921:gst_audio_encoder_finish_frame:<g729enc0> out samples 80
0:00:15.035131335  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<g729dec0:sink> calling chainfunction &gst_audio_decoder_chain with buffer buffer: 0x75f050b8, pts 0:00:04.160000000, dts 0:00:04.160000000, dur 0:00:00.010000000, size 10, offset 4160, offset_end 4170, flags 0x0
0:00:15.035236335  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1464:gst_audio_decoder_push_buffers:<g729dec0> available: 10
0:00:15.035303668  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:3525:gst_audio_decoder_allocate_output_buffer: alloc src buffer
0:00:15.035364002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f33018, maxsize:163 offset:0 size:160
0:00:15.035588668  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1274:gst_audio_decoder_finish_frame:<g729dec0> leading frame ts 0:00:04.160000000
0:00:15.035666668  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1321:gst_audio_decoder_finish_frame:<g729dec0> base_ts resync
0:00:15.035726668  1343   0xaae830 DEBUG                  audio audio.c:109:gst_audio_buffer_clip: timestamp 0:00:04.160000000
0:00:15.035806335  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<fakesink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x75f051f8, pts 0:00:04.160000000, dts 99:99:99.999999999, dur 0:00:00.010000000, size 160, offset none, offset_end none, flags 0x0
0:00:15.035906335  1343   0xaae830 DEBUG               basesink gstbasesink.c:3427:gst_base_sink_chain_unlocked:<fakesink0> got times start: 0:00:04.160000000, end: 0:00:04.170000000
0:00:15.035993335  1343   0xaae830 DEBUG               basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<fakesink0> got times start: 0:00:04.160000000, stop: 0:00:04.170000000, do_sync 1
0:00:15.036087002  1343   0xaae830 DEBUG               basesink gstbasesink.c:2590:gst_base_sink_do_sync:<fakesink0> reset rc_time to time 0:00:04.160000000
0:00:15.036160335  1343   0xaae830 DEBUG               basesink gstbasesink.c:2602:gst_base_sink_do_sync:<fakesink0> possibly waiting for clock to reach 0:00:04.160000000, adjusted 0:00:04.160000000
0:00:15.036241668  1343   0xaae830 DEBUG               basesink gstbasesink.c:2223:gst_base_sink_wait_clock:<fakesink0> sync disabled
0:00:15.036303002  1343   0xaae830 DEBUG               basesink gstbasesink.c:2609:gst_base_sink_do_sync:<fakesink0> clock returned 4, jitter  0:00:00.000000000
0:00:15.036378335  1343   0xaae830 DEBUG               basesink gstbasesink.c:3542:gst_base_sink_chain_unlocked:<fakesink0> rendering object 0x75f051f8
0:00:15.036442335  1343   0xaae830 DEBUG               basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<fakesink0> setting last buffer to 0x75f051f8
0:00:15.036510668  1343   0xaae830 DEBUG               basesink gstbasesink.c:3581:gst_base_sink_chain_unlocked:<fakesink0> object unref after render 0x75f051f8
0:00:15.036578002  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<fakesink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x75f051f8, returned ok

(gst-launch-1.0:1343): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed
0:00:15.036758002  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<g729dec0:sink> called chainfunction &gst_audio_decoder_chain with buffer 0x75f050b8, returned ok
0:00:15.036835002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f06290
0:00:15.036905002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0xaa7f88
0:00:15.036975335  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0xaa7e98, maxsize:160 offset:0 size:160
0:00:15.037049002  1343   0xaae830 DEBUG           audioencoder gstaudioencoder.c:2872:gst_audio_encoder_allocate_output_buffer: alloc src buffer
0:00:15.037107668  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f2c068, maxsize:13 offset:0 size:10
0:00:15.037858668  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0xaa7f38, maxsize:2051 offset:1184 size:160
0:00:15.037954002  1343   0xaae830 DEBUG           audioencoder gstaudioencoder.c:921:gst_audio_encoder_finish_frame:<g729enc0> out samples 80
0:00:15.038028002  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<g729dec0:sink> calling chainfunction &gst_audio_decoder_chain with buffer buffer: 0x75f053d8, pts 0:00:04.170000000, dts 0:00:04.170000000, dur 0:00:00.010000000, size 10, offset 4170, offset_end 4180, flags 0x0
0:00:15.038132002  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1464:gst_audio_decoder_push_buffers:<g729dec0> available: 10
0:00:15.038198335  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:3525:gst_audio_decoder_allocate_output_buffer: alloc src buffer
0:00:15.038256002  1343   0xaae830 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f33110, maxsize:163 offset:0 size:160
0:00:15.038474002  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1274:gst_audio_decoder_finish_frame:<g729dec0> leading frame ts 0:00:04.170000000
0:00:15.038555335  1343   0xaae830 DEBUG           audiodecoder gstaudiodecoder.c:1321:gst_audio_decoder_finish_frame:<g729dec0> base_ts resync
0:00:15.038614002  1343   0xaae830 DEBUG                  audio audio.c:109:gst_audio_buffer_clip: timestamp 0:00:04.170000000
0:00:15.038693335  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<fakesink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x75f05298, pts 0:00:04.170000000, dts 99:99:99.999999999, dur 0:00:00.010000000, size 160, offset none, offset_end none, flags 0x0
0:00:15.038799002  1343   0xaae830 DEBUG               basesink gstbasesink.c:3427:gst_base_sink_chain_unlocked:<fakesink0> got times start: 0:00:04.170000000, end: 0:00:04.180000000
0:00:15.038899335  1343   0xaae830 DEBUG               basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<fakesink0> got times start: 0:00:04.170000000, stop: 0:00:04.180000000, do_sync 1
0:00:15.038998335  1343   0xaae830 DEBUG               basesink gstbasesink.c:2590:gst_base_sink_do_sync:<fakesink0> reset rc_time to time 0:00:04.170000000
0:00:15.039077002  1343   0xaae830 DEBUG               basesink gstbasesink.c:2602:gst_base_sink_do_sync:<fakesink0> possibly waiting for clock to reach 0:00:04.170000000, adjusted 0:00:04.170000000
0:00:15.039160002  1343   0xaae830 DEBUG               basesink gstbasesink.c:2223:gst_base_sink_wait_clock:<fakesink0> sync disabled
0:00:15.039222335  1343   0xaae830 DEBUG               basesink gstbasesink.c:2609:gst_base_sink_do_sync:<fakesink0> clock returned 4, jitter  0:00:00.000000000
0:00:15.039297668  1343   0xaae830 DEBUG               basesink gstbasesink.c:3542:gst_base_sink_chain_unlocked:<fakesink0> rendering object 0x75f05298
0:00:15.039363335  1343   0xaae830 DEBUG               basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<fakesink0> setting last buffer to 0x75f05298
0:00:15.039432668  1343   0xaae830 DEBUG               basesink gstbasesink.c:3581:gst_base_sink_chain_unlocked:<fakesink0> object unref after render 0x75f05298
0:00:15.039502668  1343   0xaae830 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<fakesink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x75f05298, returned ok

export GST_DEBUG=6

(gst-launch-1.0:1347): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed
0:00:33.510191337  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1550:gst_audio_decoder_push_buffers:<g729dec0> done pushing to subclass
0:00:33.510259004  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1655:gst_audio_decoder_chain_forward:<g729dec0> chain-done
0:00:33.510319337  1347   0xa5cc30 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<g729dec0:sink> called chainfunction &gst_audio_decoder_chain with buffer 0x75f05478, returned ok
0:00:33.510401670  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:992:gst_audio_encoder_finish_frame:<g729enc0> buffer pushed: ok
0:00:33.510461004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05518
0:00:33.510524004  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f061a0
0:00:33.510589670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05298
0:00:33.510652337  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f06150
0:00:33.510723670  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:1062:gst_audio_encoder_push_buffers:<g729enc0> available: 800, needed: 160, force: 0
0:00:33.510792337  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f053d8, idx 0, length -1, flags 0001
0:00:33.510865004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f053d8, idx 0, length 1
0:00:33.510934670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f05018
0:00:33.510998670  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f06330, maxsize:160 offset:0 size:160
0:00:33.511072004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f05018, idx -1, mem 0x75f06330
0:00:33.511145004  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:1104:gst_audio_encoder_push_buffers:<g729enc0> providing subclass with 160 bytes at offset 0
0:00:33.511208670  1347   0xa5cc30 DEBUG           audioencoder gstaudioencoder.c:2872:gst_audio_encoder_allocate_output_buffer: alloc src buffer
0:00:33.511267337  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f342a8, maxsize:13 offset:0 size:10
0:00:33.511337337  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f050b8
0:00:33.511399004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f050b8, idx -1, mem 0x75f342a8
0:00:33.511465004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:855:gst_buffer_new_allocate: new buffer 0x75f050b8 of size 10 from allocator (nil)
0:00:33.511533670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05018, idx 0, length -1, flags 0001
0:00:33.511601670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05018, idx 0, length 1
0:00:33.511669337  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f050b8, idx 0, length -1, flags 0002
0:00:33.511738337  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f050b8, idx 0, length 1
0:00:33.512488670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1582:gst_buffer_resize_range: trim 0x75f050b8 0-10 size:10 offs:0 max:13
0:00:33.512602004  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:755:gst_audio_encoder_finish_frame:<g729enc0> accepting 10 bytes encoded data as 80 samples
0:00:33.512683337  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:793:gst_audio_encoder_finish_frame:<g729enc0> 624 samples past prev_ts 0:00:04.352000000
0:00:33.512765670  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:795:gst_audio_encoder_finish_frame:<g729enc0> 35440 samples past base_ts 0:00:00.000000000
0:00:33.512849337  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:808:gst_audio_encoder_finish_frame:<g729enc0> ts diff 0 ms
0:00:33.512918670  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:823:gst_audio_encoder_finish_frame:<g729enc0> new upstream ts only introduces jitter
0:00:33.512985004  1347   0xa5cc30 LOG                  adapter gstadapter.c:824:gst_adapter_get_buffer_fast:<GstAdapter@0xa444a8> getting buffer of 160 bytes
0:00:33.513053337  1347   0xa5cc30 LOG                  adapter gstadapter.c:850:gst_adapter_get_buffer_fast:<GstAdapter@0xa444a8> appending 160 bytes via region copy
0:00:33.513115670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f05158
0:00:33.513178670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:2039:gst_buffer_copy_region: new region copy 0x75f05158 of 0x75f053d8 1248-160
0:00:33.513249670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:515:gst_buffer_copy_into: copy 0x75f053d8 to 0x75f05158, offset 1248-160/2048
0:00:33.513329337  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f062e0, maxsize:2051 offset:1248 size:160
0:00:33.513427670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f05158, idx -1, mem 0x75f062e0
0:00:33.513506670  1347   0xa5cc30 LOG                  adapter gstadapter.c:634:gst_adapter_flush_unchecked:<GstAdapter@0xa444a8> flushing 160 bytes
0:00:33.513573337  1347   0xa5cc30 LOG                  adapter gstadapter.c:571:gst_adapter_unmap:<GstAdapter@0xa444a8> unmap memory buffer 0x75f053d8
0:00:33.513642670  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:909:gst_audio_encoder_finish_frame:<g729enc0> taking 10 bytes for output
0:00:33.513710670  1347   0xa5cc30 DEBUG           audioencoder gstaudioencoder.c:921:gst_audio_encoder_finish_frame:<g729enc0> out samples 80
0:00:33.513778337  1347   0xa5cc30 LOG             audioencoder gstaudioencoder.c:989:gst_audio_encoder_finish_frame:<g729enc0> pushing buffer of size 10 with ts 0:00:04.430000000, duration 0:00:00.010000000
0:00:33.513876004  1347   0xa5cc30 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<g729dec0:sink> calling chainfunction &gst_audio_decoder_chain with buffer buffer: 0x75f050b8, pts 0:00:04.430000000, dts 0:00:04.430000000, dur 0:00:00.010000000, size 10, offset 4430, offset_end 4440, flags 0x0
0:00:33.513980004  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1878:gst_audio_decoder_chain:<g729dec0> received buffer of size 10 with ts 0:00:04.430000000, duration 0:00:00.010000000
0:00:33.514072670  1347   0xa5cc30 LOG                  adapter gstadapter.c:389:gst_adapter_push:<GstAdapter@0xa44568> pushing 0x75f050b8 first 10 bytes
0:00:33.514144004  1347   0xa5cc30 LOG                  adapter gstadapter.c:290:update_timestamps_and_offset:<GstAdapter@0xa44568> new pts 0:00:04.430000000
0:00:33.514218004  1347   0xa5cc30 LOG                  adapter gstadapter.c:296:update_timestamps_and_offset:<GstAdapter@0xa44568> new dts 0:00:04.430000000
0:00:33.514293337  1347   0xa5cc30 LOG                  adapter gstadapter.c:302:update_timestamps_and_offset:<GstAdapter@0xa44568> new offset 4430
0:00:33.514360004  1347   0xa5cc30 DEBUG           audiodecoder gstaudiodecoder.c:1464:gst_audio_decoder_push_buffers:<g729dec0> available: 10
0:00:33.514427004  1347   0xa5cc30 LOG                  adapter gstadapter.c:974:gst_adapter_get_buffer:<GstAdapter@0xa44568> getting buffer of 10 bytes
0:00:33.514494337  1347   0xa5cc30 LOG                  adapter gstadapter.c:989:gst_adapter_get_buffer:<GstAdapter@0xa44568> providing buffer of 10 bytes as head buffer
0:00:33.514561670  1347   0xa5cc30 LOG                  adapter gstadapter.c:634:gst_adapter_flush_unchecked:<GstAdapter@0xa44568> flushing 10 bytes
0:00:33.514626337  1347   0xa5cc30 LOG                  adapter gstadapter.c:656:gst_adapter_flush_unchecked:<GstAdapter@0xa44568> flushing out head buffer
0:00:33.514693337  1347   0xa5cc30 LOG                  adapter gstadapter.c:668:gst_adapter_flush_unchecked:<GstAdapter@0xa44568> adapter empty now
0:00:33.514759670  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1430:gst_audio_decoder_handle_frame:<g729dec0> tracking frame size 10, ts 0:00:04.430000000
0:00:33.514832004  1347   0xa5cc30 DEBUG           audiodecoder gstaudiodecoder.c:3525:gst_audio_decoder_allocate_output_buffer: alloc src buffer
0:00:33.514892004  1347   0xa5cc30 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f36300, maxsize:163 offset:0 size:160
0:00:33.514967670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f051f8
0:00:33.515029670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f051f8, idx -1, mem 0x75f36300
0:00:33.515095670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:855:gst_buffer_new_allocate: new buffer 0x75f051f8 of size 160 from allocator (nil)
0:00:33.515167004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f050b8, idx 0, length -1, flags 0001
0:00:33.515239670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f050b8, idx 0, length 1
0:00:33.515309004  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f051f8, idx 0, length -1, flags 0001
0:00:33.515378337  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f051f8, idx 0, length 1
0:00:33.515446670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f050b8
0:00:33.515666670  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1228:gst_audio_decoder_finish_frame:<g729dec0> accepting 160 bytes == 80 samples for 1 frames
0:00:33.515743337  1347   0xa5cc30 DEBUG           audiodecoder gstaudiodecoder.c:1274:gst_audio_decoder_finish_frame:<g729dec0> leading frame ts 0:00:04.430000000
0:00:33.515822004  1347   0xa5cc30 DEBUG           audiodecoder gstaudiodecoder.c:1321:gst_audio_decoder_finish_frame:<g729dec0> base_ts resync
0:00:33.515886004  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:983:gst_audio_decoder_output:<g729dec0> output buffer of size 160 with ts 0:00:04.430000000, duration 0:00:00.010000000
0:00:33.515975670  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:905:gst_audio_decoder_push_forward:<g729dec0> clipping buffer of size 160 with ts 0:00:04.430000000, duration 0:00:00.010000000
0:00:33.516057337  1347   0xa5cc30 DEBUG                  audio audio.c:109:gst_audio_buffer_clip: timestamp 0:00:04.430000000
0:00:33.516137004  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:956:gst_audio_decoder_push_forward:<g729dec0> pushing buffer of size 160 with ts 0:00:04.430000000, duration 0:00:00.010000000
0:00:33.516227670  1347   0xa5cc30 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<fakesink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x75f051f8, pts 0:00:04.430000000, dts 99:99:99.999999999, dur 0:00:00.010000000, size 160, offset none, offset_end none, flags 0x0
0:00:33.516328004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:3427:gst_base_sink_chain_unlocked:<fakesink0> got times start: 0:00:04.430000000, end: 0:00:04.440000000
0:00:33.516422670  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<fakesink0> got times start: 0:00:04.430000000, stop: 0:00:04.440000000, do_sync 1
0:00:33.516515004  1347   0xa5cc30 LOG                 basesink gstbasesink.c:2550:gst_base_sink_do_sync:<fakesink0> avg frame diff 0:00:00.010000000
0:00:33.516591337  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:2590:gst_base_sink_do_sync:<fakesink0> reset rc_time to time 0:00:04.430000000
0:00:33.516665670  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:2602:gst_base_sink_do_sync:<fakesink0> possibly waiting for clock to reach 0:00:04.430000000, adjusted 0:00:04.430000000
0:00:33.516750004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:2223:gst_base_sink_wait_clock:<fakesink0> sync disabled
0:00:33.516812004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:2609:gst_base_sink_do_sync:<fakesink0> clock returned 4, jitter  0:00:00.000000000
0:00:33.516887004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:3542:gst_base_sink_chain_unlocked:<fakesink0> rendering object 0x75f051f8
0:00:33.516951004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<fakesink0> setting last buffer to 0x75f051f8
0:00:33.517010670  1347   0xa5cc30 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05338
0:00:33.517083004  1347   0xa5cc30 DEBUG               basesink gstbasesink.c:3581:gst_base_sink_chain_unlocked:<fakesink0> object unref after render 0x75f051f8
0:00:33.517151004  1347   0xa5cc30 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<fakesink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x75f051f8, returned ok
0:00:33.517230004  1347   0xa5cc30 LOG             audiodecoder gstaudiodecoder.c:1035:gst_audio_decoder_output:<g729dec0> buffer pushed: ok

(gst-launch-1.0:1347): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed

export GST_DEBUG=9

(gst-launch-1.0:1354): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed
0:03:26.226111024  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1550:gst_audio_decoder_push_buffers:<g729dec0> done pushing to subclass
0:03:26.226288024  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1655:gst_audio_decoder_chain_forward:<g729dec0> chain-done
0:03:26.226460691  1354  0x12ef430 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<g729dec0:sink> called chainfunction &gst_audio_decoder_chain with buffer 0x75f053d8, returned ok
0:03:26.226655358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<g729dec0> 0x12e4a60 unref 2->1
0:03:26.227352691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<g729dec0:sink> 0x12e0430 unref 2->1
0:03:26.227445358  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:992:gst_audio_encoder_finish_frame:<g729enc0> buffer pushed: ok
0:03:26.227509691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05518 unref 1->0
0:03:26.227564691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05518
0:03:26.227629024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x12e89f8: state 00010000, access_mode 4
0:03:26.227697358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x12e89f8 unref 1->0
0:03:26.227767024  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x12e89f8
0:03:26.227831691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fee800: state 00020000, access_mode 4
0:03:26.227897691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75fee800 unref 2->1
0:03:26.227969691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<allocatorsysmem0> 0x11df010 unref 4212->4211
0:03:26.228042358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f051f8 unref 1->0
0:03:26.228106024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f051f8
0:03:26.228164358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75f06380: state 00010000, access_mode 4
0:03:26.228229358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f06380 unref 1->0
0:03:26.228293024  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x75f06380
0:03:26.228357691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<allocatorsysmem0> 0x11df010 unref 4211->4210
0:03:26.228441358  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:1062:gst_audio_encoder_push_buffers:<g729enc0> available: 1440, needed: 160, force: 0
0:03:26.228513024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05298, idx 0, length -1, flags 0001
0:03:26.228626691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05298, idx 0, length 1
0:03:26.228702024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75fee800 ref 1->2
0:03:26.228777358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee800: state 00010000, access_mode 1
0:03:26.228858358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f05018
0:03:26.228931024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<allocatorsysmem0> 0x11df010 ref 4210->4211
0:03:26.229004691  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f06060, maxsize:160 offset:0 size:160
0:03:26.229082024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75f06060: state 00000000, access_mode 4
0:03:26.229152024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f05018, idx -1, mem 0x75f06060
0:03:26.229253691  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:1104:gst_audio_encoder_push_buffers:<g729enc0> providing subclass with 160 bytes at offset 0
0:03:26.229327358  1354  0x12ef430 DEBUG           audioencoder gstaudioencoder.c:2872:gst_audio_encoder_allocate_output_buffer: alloc src buffer
0:03:26.229402024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<allocatorsysmem0> 0x11df010 ref 4211->4212
0:03:26.229482358  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75fe72a0, maxsize:13 offset:0 size:10
0:03:26.229555358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f05478
0:03:26.229618024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fe72a0: state 00000000, access_mode 4
0:03:26.229687691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f05478, idx -1, mem 0x75fe72a0
0:03:26.229757024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:855:gst_buffer_new_allocate: new buffer 0x75f05478 of size 10 from allocator (nil)
0:03:26.229830358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05018, idx 0, length -1, flags 0001
0:03:26.229912024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05018, idx 0, length 1
0:03:26.229980691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75f06060 ref 1->2
0:03:26.230057358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75f06060: state 00010000, access_mode 1
0:03:26.230131691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05478, idx 0, length -1, flags 0002
0:03:26.230203024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05478, idx 0, length 1
0:03:26.230278024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75fe72a0 ref 1->2
0:03:26.230432024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fe72a0: state 00010000, access_mode 2
0:03:26.231329358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75f06060: state 00010101, access_mode 1
0:03:26.231496358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f06060 unref 2->1
0:03:26.231607691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fe72a0: state 00010102, access_mode 2
0:03:26.231691358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75fe72a0 unref 2->1
0:03:26.231775024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1582:gst_buffer_resize_range: trim 0x75f05478 0-10 size:10 offs:0 max:13
0:03:26.231907024  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:755:gst_audio_encoder_finish_frame:<g729enc0> accepting 10 bytes encoded data as 80 samples
0:03:26.232037358  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:793:gst_audio_encoder_finish_frame:<g729enc0> 304 samples past prev_ts 0:00:20.992000000
0:03:26.232143358  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:795:gst_audio_encoder_finish_frame:<g729enc0> 168240 samples past base_ts 0:00:00.000000000
0:03:26.232262024  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:808:gst_audio_encoder_finish_frame:<g729enc0> ts diff 0 ms
0:03:26.232356691  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:823:gst_audio_encoder_finish_frame:<g729enc0> new upstream ts only introduces jitter
0:03:26.232454691  1354  0x12ef430 LOG                  adapter gstadapter.c:824:gst_adapter_get_buffer_fast:<GstAdapter@0x12d6ca8> getting buffer of 160 bytes
0:03:26.232547691  1354  0x12ef430 LOG                  adapter gstadapter.c:850:gst_adapter_get_buffer_fast:<GstAdapter@0x12d6ca8> appending 160 bytes via region copy
0:03:26.232620691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f050b8
0:03:26.232698358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:2039:gst_buffer_copy_region: new region copy 0x75f050b8 of 0x75f05298 608-160
0:03:26.232785358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:515:gst_buffer_copy_into: copy 0x75f05298 to 0x75f050b8, offset 608-160/2048
0:03:26.232879691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee800: state 00010101, access_mode 4
0:03:26.232957024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee800: state 00020101, access_mode 4
0:03:26.233039024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<allocatorsysmem0> 0x11df010 ref 4212->4213
0:03:26.233127358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee800: state 00030101, access_mode 4
0:03:26.233208358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75fee800 ref 2->3
0:03:26.233297024  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75f06330, maxsize:2051 offset:608 size:160
0:03:26.233379691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fee800: state 00040101, access_mode 4
0:03:26.233459024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fee800: state 00030101, access_mode 4
0:03:26.233532024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75f06330: state 00000000, access_mode 4
0:03:26.233602024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f050b8, idx -1, mem 0x75f06330
0:03:26.233691024  1354  0x12ef430 LOG                  adapter gstadapter.c:634:gst_adapter_flush_unchecked:<GstAdapter@0x12d6ca8> flushing 160 bytes
0:03:26.233768024  1354  0x12ef430 LOG                  adapter gstadapter.c:571:gst_adapter_unmap:<GstAdapter@0x12d6ca8> unmap memory buffer 0x75f05298
0:03:26.233827358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fee800: state 00020101, access_mode 1
0:03:26.233894358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75fee800 unref 3->2
0:03:26.233974024  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:909:gst_audio_encoder_finish_frame:<g729enc0> taking 10 bytes for output
0:03:26.234045691  1354  0x12ef430 DEBUG           audioencoder gstaudioencoder.c:921:gst_audio_encoder_finish_frame:<g729enc0> out samples 80
0:03:26.234116024  1354  0x12ef430 LOG             audioencoder gstaudioencoder.c:989:gst_audio_encoder_finish_frame:<g729enc0> pushing buffer of size 10 with ts 0:00:21.030000000, duration 0:00:00.010000000
0:03:26.234226691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<g729dec0:sink> 0x12e0430 ref 1->2
0:03:26.234315024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<g729dec0> 0x12e4a60 ref 1->2
0:03:26.234398358  1354  0x12ef430 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<g729dec0:sink> calling chainfunction &gst_audio_decoder_chain with buffer buffer: 0x75f05478, pts 0:00:21.030000000, dts 0:00:21.030000000, dur 0:00:00.010000000, size 10, offset 21030, offset_end 21040, flags 0x0
0:03:26.234507691  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1878:gst_audio_decoder_chain:<g729dec0> received buffer of size 10 with ts 0:00:21.030000000, duration 0:00:00.010000000
0:03:26.234603691  1354  0x12ef430 LOG                  adapter gstadapter.c:389:gst_adapter_push:<GstAdapter@0x12d6d68> pushing 0x75f05478 first 10 bytes
0:03:26.234684358  1354  0x12ef430 LOG                  adapter gstadapter.c:290:update_timestamps_and_offset:<GstAdapter@0x12d6d68> new pts 0:00:21.030000000
0:03:26.234760024  1354  0x12ef430 LOG                  adapter gstadapter.c:296:update_timestamps_and_offset:<GstAdapter@0x12d6d68> new dts 0:00:21.030000000
0:03:26.234834691  1354  0x12ef430 LOG                  adapter gstadapter.c:302:update_timestamps_and_offset:<GstAdapter@0x12d6d68> new offset 21030
0:03:26.234901691  1354  0x12ef430 DEBUG           audiodecoder gstaudiodecoder.c:1464:gst_audio_decoder_push_buffers:<g729dec0> available: 10
0:03:26.234970024  1354  0x12ef430 LOG                  adapter gstadapter.c:974:gst_adapter_get_buffer:<GstAdapter@0x12d6d68> getting buffer of 10 bytes
0:03:26.235040024  1354  0x12ef430 LOG                  adapter gstadapter.c:989:gst_adapter_get_buffer:<GstAdapter@0x12d6d68> providing buffer of 10 bytes as head buffer
0:03:26.235107691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75f05478 ref 1->2
0:03:26.235189024  1354  0x12ef430 LOG                  adapter gstadapter.c:634:gst_adapter_flush_unchecked:<GstAdapter@0x12d6d68> flushing 10 bytes
0:03:26.235261691  1354  0x12ef430 LOG                  adapter gstadapter.c:656:gst_adapter_flush_unchecked:<GstAdapter@0x12d6d68> flushing out head buffer
0:03:26.235321024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05478 unref 2->1
0:03:26.235404691  1354  0x12ef430 LOG                  adapter gstadapter.c:668:gst_adapter_flush_unchecked:<GstAdapter@0x12d6d68> adapter empty now
0:03:26.235473358  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1430:gst_audio_decoder_handle_frame:<g729dec0> tracking frame size 10, ts 0:00:21.030000000
0:03:26.235560024  1354  0x12ef430 DEBUG           audiodecoder gstaudiodecoder.c:3525:gst_audio_decoder_allocate_output_buffer: alloc src buffer
0:03:26.235633691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<allocatorsysmem0> 0x11df010 ref 4213->4214
0:03:26.235715024  1354  0x12ef430 DEBUG             GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x75fee6d8, maxsize:163 offset:0 size:160
0:03:26.235797691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:799:gst_buffer_new: new 0x75f05338
0:03:26.235869358  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee6d8: state 00000000, access_mode 4
0:03:26.235938358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:414:_memory_add: buffer 0x75f05338, idx -1, mem 0x75fee6d8
0:03:26.236008358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:855:gst_buffer_new_allocate: new buffer 0x75f05338 of size 160 from allocator (nil)
0:03:26.236096691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05478, idx 0, length -1, flags 0001
0:03:26.236180024  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05478, idx 0, length 1
0:03:26.236252024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75fe72a0 ref 1->2
0:03:26.236331024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fe72a0: state 00010000, access_mode 1
0:03:26.236402691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:1721:gst_buffer_map_range: buffer 0x75f05338, idx 0, length -1, flags 0001
0:03:26.236477358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:213:_get_merged_memory: buffer 0x75f05338, idx 0, length 1
0:03:26.236553691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75fee6d8 ref 1->2
0:03:26.236624691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:178:gst_mini_object_lock: lock 0x75fee6d8: state 00010000, access_mode 1
0:03:26.236819691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05478 unref 1->0
0:03:26.236909691  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05478
0:03:26.236993691  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fe72a0: state 00010101, access_mode 4
0:03:26.237065691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75fe72a0 unref 2->1
0:03:26.237348024  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1228:gst_audio_decoder_finish_frame:<g729dec0> accepting 160 bytes == 80 samples for 1 frames
0:03:26.237467024  1354  0x12ef430 DEBUG           audiodecoder gstaudiodecoder.c:1274:gst_audio_decoder_finish_frame:<g729dec0> leading frame ts 0:00:21.030000000
0:03:26.237585358  1354  0x12ef430 DEBUG           audiodecoder gstaudiodecoder.c:1321:gst_audio_decoder_finish_frame:<g729dec0> base_ts resync
0:03:26.237680691  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:983:gst_audio_decoder_output:<g729dec0> output buffer of size 160 with ts 0:00:21.030000000, duration 0:00:00.010000000
0:03:26.237806024  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:905:gst_audio_decoder_push_forward:<g729dec0> clipping buffer of size 160 with ts 0:00:21.030000000, duration 0:00:00.010000000
0:03:26.237918024  1354  0x12ef430 DEBUG                  audio audio.c:109:gst_audio_buffer_clip: timestamp 0:00:21.030000000
0:03:26.238062691  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:956:gst_audio_decoder_push_forward:<g729dec0> pushing buffer of size 160 with ts 0:00:21.030000000, duration 0:00:00.010000000
0:03:26.238206024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<fakesink0:sink> 0x12e06d0 ref 1->2
0:03:26.238318358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:237:gst_object_ref:<fakesink0> 0x12ea228 ref 1->2
0:03:26.238417358  1354  0x12ef430 DEBUG         GST_SCHEDULING gstpad.c:4203:gst_pad_chain_data_unchecked:<fakesink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x75f05338, pts 0:00:21.030000000, dts 99:99:99.999999999, dur 0:00:00.010000000, size 160, offset none, offset_end none, flags 0x0
0:03:26.238558358  1354  0x12ef430 DEBUG               basesink gstbasesink.c:3427:gst_base_sink_chain_unlocked:<fakesink0> got times start: 0:00:21.030000000, end: 0:00:21.040000000
0:03:26.238686691  1354  0x12ef430 DEBUG               basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<fakesink0> got times start: 0:00:21.030000000, stop: 0:00:21.040000000, do_sync 1
0:03:26.238813358  1354  0x12ef430 LOG                 basesink gstbasesink.c:2550:gst_base_sink_do_sync:<fakesink0> avg frame diff 0:00:00.010000000
0:03:26.238908691  1354  0x12ef430 DEBUG               basesink gstbasesink.c:2590:gst_base_sink_do_sync:<fakesink0> reset rc_time to time 0:00:21.030000000
0:03:26.238992691  1354  0x12ef430 DEBUG               basesink gstbasesink.c:2602:gst_base_sink_do_sync:<fakesink0> possibly waiting for clock to reach 0:00:21.030000000, adjusted 0:00:21.030000000
0:03:26.239089691  1354  0x12ef430 DEBUG               basesink gstbasesink.c:2223:gst_base_sink_wait_clock:<fakesink0> sync disabled
0:03:26.239166024  1354  0x12ef430 DEBUG               basesink gstbasesink.c:2609:gst_base_sink_do_sync:<fakesink0> clock returned 4, jitter  0:00:00.000000000
0:03:26.239253024  1354  0x12ef430 DEBUG               basesink gstbasesink.c:3542:gst_base_sink_chain_unlocked:<fakesink0> rendering object 0x75f05338
0:03:26.239319691  1354  0x12ef430 DEBUG               basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<fakesink0> setting last buffer to 0x75f05338
0:03:26.239380024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:351:gst_mini_object_ref: 0x75f05338 ref 1->2
0:03:26.239458358  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:487:gst_mini_object_replace: replace 0x75f03ac8 (4) with 0x75f03ac8 (4)
0:03:26.239532691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05158 unref 1->0
0:03:26.239604358  1354  0x12ef430 LOG               GST_BUFFER gstbuffer.c:725:_gst_buffer_free: finalize 0x75f05158
0:03:26.239665024  1354  0x12ef430 TRACE            GST_LOCKING gstminiobject.c:238:gst_mini_object_unlock: unlock 0x75fee5e0: state 00010101, access_mode 4
0:03:26.239732691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75fee5e0 unref 2->1
0:03:26.239811024  1354  0x12ef430 DEBUG               basesink gstbasesink.c:3581:gst_base_sink_chain_unlocked:<fakesink0> object unref after render 0x75f05338
0:03:26.239872024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05338 unref 2->1
0:03:26.239951358  1354  0x12ef430 DEBUG         GST_SCHEDULING gstpad.c:4209:gst_pad_chain_data_unchecked:<fakesink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x75f05338, returned ok
0:03:26.240029024  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<fakesink0> 0x12ea228 unref 2->1
0:03:26.240113691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstobject.c:264:gst_object_unref:<fakesink0:sink> 0x12e06d0 unref 2->1
0:03:26.240198024  1354  0x12ef430 LOG             audiodecoder gstaudiodecoder.c:1035:gst_audio_decoder_output:<g729dec0> buffer pushed: ok
0:03:26.240264691  1354  0x12ef430 TRACE        GST_REFCOUNTING gstminiobject.c:431:gst_mini_object_unref: 0x75f05478 unref 0->-1

(gst-launch-1.0:1354): GStreamer-CRITICAL **: gst_mini_object_unref: assertion 'mini_object->refcount > 0' failed
@sdroege sdroege mentioned this issue Jul 10, 2019
Merged
@sdroege
Copy link
Contributor

sdroege commented Jul 10, 2019

See #4

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@sdroege @dmitry-pozhidaev