[Bug 692953] alsa modules are silent or noisy after several hours of use
GStreamer (bugzilla.gnome.org)
bugzilla at gnome.org
Thu Aug 22 09:03:33 PDT 2013
https://bugzilla.gnome.org/show_bug.cgi?id=692953
GStreamer | gst-plugins-base | 1.0.6
--- Comment #37 from Thomas DEBESSE <thomas.debesse at rcf.fr> 2013-08-22 16:03:25 UTC ---
The first xrun since the silence (there was two or three xruns since the start
but are unrelated) is at 14:28:48, or 52128s (it does not look like a known
magic number).
Normal output:
----8<------------------------------------------
0:14:43.766123708 26625 0x1610190 DEBUG GST_MEMORY
gstmemory.c:133:gst_memory_init: new memory 0x7f8b2c0126a0, maxsize:1543
offset:0 size:1536
0:14:43.766122801 26625 0x1610230 DEBUG basesink
gstbasesink.c:3168:gst_base_sink_chain_unlocked:<alsasink0> got times start:
0:14:42.747210884, end: 0:14:42.755918367
0:14:43.766139077 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1708:gst_audio_ring_buffer_read: pointer at 101379, sample
38929536, read from 101379-0, to_read 384, diff 0, segtotal 23, segsize 1536
0:14:43.766154333 26625 0x1610230 DEBUG basesink
gstbasesink.c:1803:gst_base_sink_get_sync_times:<alsasink0> got times start:
0:14:42.747210884, stop: 0:14:42.755918367, do_sync 0
0:14:43.766166008 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1281:wait_segment:<audiosrcringbuffer0> waiting..
0:14:43.766180904 26625 0x1610230 DEBUG basesink
gstbasesink.c:3209:gst_base_sink_chain_unlocked:<alsasink0> rendering object
0x7f8b2c011300
0:14:43.766205628 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1664:gst_audio_base_sink_render:<alsasink0> time
0:14:42.747210884, offset 38929152, start 0:00:00.000000000, samples 384
0:14:43.766219843 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1701:gst_audio_base_sink_render:<alsasink0> sync-offset 0,
render-delay 0:00:00.000000000, ts-offset 0
0:14:43.766230219 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1751:gst_audio_base_sink_render:<alsasink0> no sync needed.
Using render_start=38941056
0:14:43.766238838 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1885:gst_audio_base_sink_render:<alsasink0> rendering at
38941056 384/384
0:14:43.766250048 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1490:default_commit: pointer at 101400, write to 101409-0,
diff 9, segtotal 23, segsize 1536, base 0
0:14:43.766260279 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1521:default_commit:<audiosinkringbuffer0> write
@0x7f8b34003400 seg 2, sps 384, off 0, avail 1536
0:14:43.766269660 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1547:default_commit: copy 1536 bytes
0:14:43.766278604 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1896:gst_audio_base_sink_render:<alsasink0> wrote 384 of 384
0:14:43.766287674 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1928:gst_audio_base_sink_render:<alsasink0> next sample
expected at 38941440
0:14:43.766296194 26625 0x1610230 DEBUG basesink
gstbasesink.c:3248:gst_base_sink_chain_unlocked:<alsasink0> object unref after
render 0x7f8b2c011300
0:14:43.766305118 26625 0x1610230 DEBUG GST_MEMORY
gstmemory.c:86:_gst_memory_free: free memory 0x7f8b2c012000
0:14:43.766319326 26625 0x1610230 DEBUG queue_dataflow
gstqueue.c:1149:gst_queue_loop:<queue0> queue is empty
0:14:43.771431920 26625 0x7f8b2c003a30 DEBUG alsa
gstalsasink.c:1021:gst_alsasink_write:<alsasink0> written 384 frames out of 384
0:14:43.774634964 26625 0x7f8b2c003630 INFO ringbuffer
gstaudioringbuffer.c:1966:gst_audio_ring_buffer_set_timestamp:<audiosrcringbuffer0>
Storing timestamp 99:99:99.999999999 @ 18
0:14:43.774683267 26625 0x7f8b2c003630 DEBUG ringbuffer
gstaudioringbuffer.c:1851:gst_audio_ring_buffer_advance:<audiosrcringbuffer0>
signal waiter
0:14:43.774771007 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1708:gst_audio_ring_buffer_read: pointer at 101380, sample
38929536, read from 101379-0, to_read 384, diff 1, segtotal 23, segsize 1536
0:14:43.774818365 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1733:gst_audio_ring_buffer_read:<audiosrcringbuffer0> read
@0x7f8b2c00d930 seg 18, off 0, sampleslen 384
0:14:43.774844454 26625 0x1610190 INFO ringbuffer
gstaudioringbuffer.c:1761:gst_audio_ring_buffer_read:<audiosrcringbuffer0>
Retrieved timestamp 99:99:99.999999999 @ 18
0:14:43.774850959 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:821:gst_audio_base_src_create:<alsasrc0> read 384 of 384
0:14:43.774860402 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2213:gst_base_src_do_sync:<alsasrc0> no sync needed
0:14:43.774866679 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2414:gst_base_src_get_range:<alsasrc0> buffer ok
0:14:43.774893048 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2351:gst_base_src_get_range:<alsasrc0> calling create offset
18446744073709551615 length 0, time 0
0:14:43.774900158 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:721:gst_audio_base_src_get_offset:<alsasrc0> at segment
101380 and sample 38929920
0:14:43.774905381 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:744:gst_audio_base_src_get_offset:<alsasrc0> reading from
101380, we are at 101380, sample 38929920
0:14:43.774910744 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:799:gst_audio_base_src_create:<alsasrc0> reading from sample
38929920 length 1536
----8<------------------------------------------
(endlessly)
Abnormal output (after the silence occurs):
----8<------------------------------------------
14:29:05.895356358 26625 0x1610190 DEBUG GST_MEMORY
gstmemory.c:133:gst_memory_init: new memory 0x7f8b2c0126a0, maxsize:1543
offset:0 size:1536
14:29:05.895368792 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1708:gst_audio_ring_buffer_read: pointer at 5986895,
sample 2298967680, read from 5986895-0, to_read 384, diff 0, segtotal 23,
segsize 1536
14:29:05.895380135 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1281:wait_segment:<audiosrcringbuffer0> waiting..
14:29:05.895314747 26625 0x1610230 DEBUG queue_dataflow
gstqueue.c:1161:gst_queue_loop:<queue0> queue is not empty
14:29:05.895407136 26625 0x1610230 DEBUG basesink
gstbasesink.c:3168:gst_base_sink_chain_unlocked:<alsasink0> got times start:
14:28:50.777687074, end: 14:28:50.786394557
14:29:05.895419691 26625 0x1610230 DEBUG basesink
gstbasesink.c:1803:gst_base_sink_get_sync_times:<alsasink0> got times start:
14:28:50.777687074, stop: 14:28:50.786394557, do_sync 0
14:29:05.895431416 26625 0x1610230 DEBUG basesink
gstbasesink.c:3209:gst_base_sink_chain_unlocked:<alsasink0> rendering object
0x7f8b2c0111f0
14:29:05.895442079 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1664:gst_audio_base_sink_render:<alsasink0> time
14:28:50.777687074, offset 2298967296, start 0:00:00.000000000, samples 384
14:29:05.895454969 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1701:gst_audio_base_sink_render:<alsasink0> sync-offset 0,
render-delay 0:00:00.000000000, ts-offset 0
14:29:05.895465726 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1751:gst_audio_base_sink_render:<alsasink0> no sync needed.
Using render_start=18446744071713611904
14:29:05.895475361 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1885:gst_audio_base_sink_render:<alsasink0> rendering at
18446744071713611904 384/384
14:29:05.895485044 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1490:default_commit: pointer at 5987050, write to
-1436853525-0, diff -1442840575, segtotal 23, segsize 1536, base 0
14:29:05.895494841 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1521:default_commit:<audiosinkringbuffer0> write
@0x7f8b33fff200 seg -9, sps 384, off 0, avail 1536
14:29:05.895504454 26625 0x1610230 DEBUG ringbuffer
gstaudioringbuffer.c:1547:default_commit: copy 1536 bytes
14:29:05.895514573 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1896:gst_audio_base_sink_render:<alsasink0> wrote 384 of 384
14:29:05.895522992 26625 0x1610230 DEBUG audiobasesink
gstaudiobasesink.c:1928:gst_audio_base_sink_render:<alsasink0> next sample
expected at 18446744071713612288
14:29:05.895531653 26625 0x1610230 DEBUG basesink
gstbasesink.c:3248:gst_base_sink_chain_unlocked:<alsasink0> object unref after
render 0x7f8b2c0111f0
14:29:05.895540936 26625 0x1610230 DEBUG GST_MEMORY
gstmemory.c:86:_gst_memory_free: free memory 0x7f8b2c012000
14:29:05.895552551 26625 0x1610230 DEBUG queue_dataflow
gstqueue.c:1149:gst_queue_loop:<queue0> queue is empty
14:29:05.897220447 26625 0x7f8b2c003a30 DEBUG alsa
gstalsasink.c:1021:gst_alsasink_write:<alsasink0> written 384 frames out of 384
14:29:05.903790547 26625 0x7f8b2c003630 INFO ringbuffer
gstaudioringbuffer.c:1966:gst_audio_ring_buffer_set_timestamp:<audiosrcringbuffer0>
Storing timestamp 99:99:99.999999999 @ 18
14:29:05.903814778 26625 0x7f8b2c003630 DEBUG ringbuffer
gstaudioringbuffer.c:1851:gst_audio_ring_buffer_advance:<audiosrcringbuffer0>
signal waiter
14:29:05.903834577 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1708:gst_audio_ring_buffer_read: pointer at 5986896,
sample 2298967680, read from 5986895-0, to_read 384, diff 1, segtotal 23,
segsize 1536
14:29:05.903850805 26625 0x1610190 DEBUG ringbuffer
gstaudioringbuffer.c:1733:gst_audio_ring_buffer_read:<audiosrcringbuffer0> read
@0x7f8b2c00d930 seg 18, off 0, sampleslen 384
14:29:05.903862467 26625 0x1610190 INFO ringbuffer
gstaudioringbuffer.c:1761:gst_audio_ring_buffer_read:<audiosrcringbuffer0>
Retrieved timestamp 99:99:99.999999999 @ 18
14:29:05.903873311 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:821:gst_audio_base_src_create:<alsasrc0> read 384 of 384
14:29:05.903888559 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2213:gst_base_src_do_sync:<alsasrc0> no sync needed
14:29:05.903897918 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2414:gst_base_src_get_range:<alsasrc0> buffer ok
14:29:05.903920407 26625 0x1610190 DEBUG basesrc
gstbasesrc.c:2351:gst_base_src_get_range:<alsasrc0> calling create offset
18446744073709551615 length 0, time 0
14:29:05.903931604 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:721:gst_audio_base_src_get_offset:<alsasrc0> at segment
5986896 and sample 2298968064
14:29:05.903939887 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:744:gst_audio_base_src_get_offset:<alsasrc0> reading from
5986896, we are at 5986896, sample 2298968064
14:29:05.903949599 26625 0x1610190 DEBUG audiobasesrc
gstaudiobasesrc.c:799:gst_audio_base_src_create:<alsasrc0> reading from sample
2298968064 length 1536
----8<------------------------------------------
(endlessly)
--
Configure bugmail: https://bugzilla.gnome.org/userprefs.cgi?tab=email
------- You are receiving this mail because: -------
You are the QA contact for the bug.
You are the assignee for the bug.
More information about the gstreamer-bugs
mailing list