0:00:07.430500000  7129 0x8114a18 DEBUG              GST_BUS gstbus.c:335:gst_bus_post:<bus1> [msg 0xb5f0cc58] posting on bus, type state-changed, GstMessageState, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GS
T_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING
0:00:07.430533000  7129 0x8115d30 DEBUG            GST_CLOCK gstsystemclock.c:292:gst_system_clock_async_thread: async entry 0xb5f02168 unlocked
0:00:07.430570000  7129 0x8114a18 DEBUG              GST_BUS gstbus.c:365:gst_bus_post:<bus1> [msg 0xb5f0cc58] pushing on async queue
0:00:07.430597000  7129 0x8149a60 DEBUG            GST_CLOCK gstsystemclock.c:431:gst_system_clock_id_wait_jitter_unlocked: entry 0xb5f01ec8 unlocked with signal
0:00:07.430596000  7129 0x8115d30 DEBUG        baseaudiosink gstbaseaudiosink.c:339:gst_base_audio_sink_get_time:<alsasink> processed samples: raw 0, delay 0, real 0, time 0:00:00.000000000
0:00:07.430649000  7129 0x8149a60 DEBUG            GST_CLOCK gstclock.c:414:gst_clock_id_wait:<GstSystemClock> done waiting entry 0xb5f01ec8, res: 3
0:00:07.430669000  7129 0x8115d30 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<GstAudioSinkClock> internal time 0:00:00.000000000
0:00:07.430683000  7129 0x8149a60 DEBUG            GST_CLOCK gstclock.c:209:_gst_clock_id_free: freed entry 0xb5f01ec8
0:00:07.430720000  7129 0x8149a60 DEBUG              basesrc gstbasesrc.c:1404:gst_base_src_get_range:<alsasrc> clock returned 3, not returning
0:00:07.430731000  7129 0x8115d30 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<GstSystemClock> internal time 327903:09:00.236858000
0:00:07.430753000  7129 0x8149a60 INFO               basesrc gstbasesrc.c:1551:gst_base_src_loop:<alsasrc> pausing after gst_base_src_get_range() = wrong-state
0:00:07.430767000  7129 0x8115d30 DEBUG            GST_CLOCK gstclock.c:829:gst_clock_get_time:<GstSystemClock> adjusted time 327903:09:00.236858000
0:00:07.430785000  7129 0x8149a60 DEBUG              basesrc gstbasesrc.c:1623:gst_base_src_loop:<alsasrc> pausing task, reason wrong-state
0:00:07.430802000  7129 0x8115d30 DEBUG            GST_CLOCK gstclock.c:932:gst_clock_slave_callback:<GstAudioSinkClock> master 327903:09:00.236858000, slave 0:00:00.000000000
0:00:07.430820000  7129 0x8149a60 DEBUG             GST_PADS gstpad.c:4338:gst_pad_pause_task:<alsasrc:src> pause task
0:00:07.430848000  7129 0x8115d30 DEBUG            GST_CLOCK gstsystemclock.c:397:gst_system_clock_id_wait_jitter_unlocked: entry 0xb5f02168 target 327903:09:00.335821000 entry 327903:09:00.335820000 now 327903:09:00.2369750
00 real 327903:09:00.236975000 diff 98845000
0:00:07.430885000  7129 0x8149a60 DEBUG                 task gsttask.c:476:gst_task_pause:<task2> Pausing task 0x81140b8