0:00:00.120876845  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:180:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: buffer size: 35280 bytes = 8820 samples
0:00:00.120905253  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:528:gst_audio_ring_buffer_acquire:<audiosinkringbuffer0> acquiring device 0x11a6010
0:00:00.122664714  9175      0x1195b70 INFO              ringbuffer gstaudioringbuffer.c:552:gst_audio_ring_buffer_acquire:<audiosinkringbuffer0> Allocating an array for 20 timestamps
0:00:00.122702819  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:582:gst_audio_ring_buffer_acquire:<audiosinkringbuffer0> acquired device
0:00:00.122718447  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:740:gst_audio_ring_buffer_activate:<audiosinkringbuffer0> activate device
0:00:00.122903207  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:172:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: buffer time: 200000 usec
0:00:00.129804164  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:174:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: latency time: 10000 usec
0:00:00.129873425  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:175:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: total segments: 20
0:00:00.129912667  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:176:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: latency segments: 21
0:00:00.129963009  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:178:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: segment size: 1764 bytes = 441 samples
0:00:00.130005923  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:180:gst_audio_ring_buffer_debug_spec_buff: acquire ringbuffer: buffer size: 35280 bytes = 8820 samples
0:00:00.130205225  9175      0x1195b70 DEBUG               basesink gstbasesink.c:3001:gst_base_sink_event:<alsasink0> received event 0x112e920 stream-start event at time 99:99:99.999999999: GstEventStreamStart, stream-id=(string)d05cfd96fff031788cf6e007d514bd2237bb953ddd2a6acb9fedcac7945093b0;
0:00:00.130292837  9175      0x1195b70 DEBUG               basesink gstbasesink.c:2904:gst_base_sink_default_event:<alsasink0> Now posting STREAM_START (seqnum:36)
0:00:00.130429696  9175      0x1195b70 DEBUG               basesink gstbasesink.c:3001:gst_base_sink_event:<alsasink0> received event 0x7f3f70024f60 segment event at time 99:99:99.999999999: GstEventSegment, segment=(GstSegment)"GstSegment, flags=(GstSegmentFlags)GST_SEGMENT_FLAG_NONE, rate=(double)1, applied-rate=(double)1, format=(GstFormat)GST_FORMAT_TIME, base=(guint64)0, start=(guint64)0, stop=(guint64)18446744073709551615, time=(guint64)0, position=(guint64)0, duration=(guint64)18446744073709551615;";
0:00:00.130656874  9175      0x1195b70 DEBUG               basesink gstbasesink.c:2937:gst_base_sink_default_event:<alsasink0> configured SEGMENT time segment start=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.134779903  9175      0x1195b70 DEBUG               basesink gstbasesink.c:3166:gst_base_sink_chain_unlocked:<alsasink0> got times start: 0:00:00.000393035, end: 99:99:99.999999999
0:00:00.134850273  9175      0x1195b70 DEBUG               basesink gstbasesink.c:1808:gst_base_sink_get_sync_times:<alsasink0> got times start: 0:00:00.000393035, stop: 99:99:99.999999999, do_sync 0
0:00:00.134909086  9175      0x1195b70 DEBUG               basesink gstbasesink.c:2101:gst_base_sink_do_preroll:<alsasink0> prerolling object 0x11d3050
0:00:00.134960057  9175      0x1195b70 DEBUG               basesink gstbasesink.c:2120:gst_base_sink_do_preroll:<alsasink0> preroll buffer 0:00:00.000393035
0:00:00.135021233  9175      0x1195b70 DEBUG               basesink gstbasesink.c:1371:gst_base_sink_commit_state:<alsasink0> commiting state to PLAYING
0:00:00.135069711  9175      0x1195b70 DEBUG               basesink gstbasesink.c:1409:gst_base_sink_commit_state:<alsasink0> posting PAUSED state change message
0:00:00.135153517  9175      0x1195b70 DEBUG               basesink gstbasesink.c:1415:gst_base_sink_commit_state:<alsasink0> posting async-done message
0:00:00.135402080  9175      0x1195b70 DEBUG               basesink gstbasesink.c:1426:gst_base_sink_commit_state:<alsasink0> posting PLAYING state change message
0:00:00.135499014  9175      0x114a630 DEBUG               basesink gstbasesink.c:1049:gst_base_sink_query_latency:<alsasink0> we are ready for LATENCY query
0:00:00.135574356  9175      0x1195b70 DEBUG               basesink gstbasesink.c:3207:gst_base_sink_chain_unlocked:<alsasink0> rendering object 0x11d3050
0:00:00.135670808  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:1490:default_commit: pointer at 0, write to 18234738-788, diff 18234738, segtotal 20, segsize 1764, base 0
0:00:00.135682292  9175      0x114a630 DEBUG               basesink gstbasesink.c:1092:gst_base_sink_query_latency:<alsasink0> latency query: live: 1, have_latency 1, upstream: 1, min 0:00:00.050000000, max 99:99:99.999999999
0:00:00.135736609  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:1313:wait_segment:<audiosinkringbuffer0> not allowed to start
0:00:00.135797194  9175      0x1195b70 DEBUG             ringbuffer gstaudioringbuffer.c:1578:default_commit:<audiosinkringbuffer0> stopped processing
0:00:00.135825320  9175      0x114a630 DEBUG               basesink gstbasesink.c:4125:gst_base_sink_send_event:<alsasink0> handling event 0x114acc0 latency event at time 99:99:99.999999999: GstEventLatency, latency=(guint64)260000000;
0:00:00.135843944  9175      0x1195b70 DEBUG               basesink gstbasesink.c:2054:gst_base_sink_wait_preroll:<alsasink0> waiting in preroll for flush or PLAYING
0:00:00.135895335  9175      0x114a630 DEBUG               basesink gstbasesink.c:4142:gst_base_sink_send_event:<alsasink0> latency set to 0:00:00.260000000
0:00:00.136053938  9175      0x114a630 DEBUG               basesink gstbasesink.c:4172:gst_base_sink_send_event:<alsasink0> handled event 0x114acc0 latency event at time 99:99:99.999999999: GstEventLatency, latency=(guint64)260000000;: 1

