12-02 18:06:52.411: I/XMFramework(15369): onActivityResult: 12-02 18:06:52.411: I/XMFramework(15369): Setting last_folder to /storage/emulated/0 12-02 18:06:52.411: I/XMFramework(15369): XMFramework setMediaUri start 12-02 18:06:52.411: D/GStreamer+playbin(15369): 0:07:23.657968920 0x5ce0e400 gstplaybin2.c:1688:gst_play_bin_set_uri set new uri to file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.mkv 12-02 18:06:52.411: D/GStreamer+playbin(15369): 0:07:23.658122753 0x5ce0e400 gstplaybin2.c:1705:gst_play_bin_set_suburi setting new .sub uri to file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.smi 12-02 18:06:52.411: I/XMFramework(15369): XMFramework setMediaUri End 12-02 18:06:52.411: D/Activity(15369): #3 setTransGradationModeColor to false 12-02 18:06:52.421: D/XMFramework(15369): Surface created: Surface(name=null)/@0x42bcfae0 12-02 18:06:52.421: D/XMFramework(15369): Surface changed to format 4 width 720 height 540 12-02 18:06:52.426: I/XMFramework(15369): XMFramework initialized: 12-02 18:06:52.426: I/XMFramework(15369): playing:false position:0 uri: file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.mkv 12-02 18:06:52.426: I/XMFramework(15369): playing:false position:0 suburi: file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.smi 12-02 18:06:52.426: I/XMFramework(15369): XMFramework setMediaUri start 12-02 18:06:52.426: D/GStreamer+playbin(15369): 0:07:23.672618253 0x5ce0e400 gstplaybin2.c:1688:gst_play_bin_set_uri set new uri to file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.mkv 12-02 18:06:52.426: D/GStreamer+playbin(15369): 0:07:23.672850337 0x5ce0e400 gstplaybin2.c:1705:gst_play_bin_set_suburi setting new .sub uri to file:///storage/emulated/0/My.Neighbor.Totoro.1988.x264.DTS.3AUDIO-WAF.smi 12-02 18:06:52.426: I/XMFramework(15369): XMFramework setMediaUri End 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.673247962 0x5ce0e400 gstelement.c:2481:gst_element_set_state_func: set_state to PAUSED 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.673441545 0x5ce0e400 gstelement.c:2506:gst_element_set_state_func: setting target state to PAUSED 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.673634128 0x5ce0e400 gstelement.c:2519:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.673822045 0x5ce0e400 gstelement.c:2553:gst_element_set_state_func: final: setting state from READY to PAUSED 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.674023462 0x5ce0e400 gstbin.c:3461:gst_bin_handle_message_func: [msg 0x666e7410] handling child playbin0 message of type async-start 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.674204837 0x5ce0e400 gstbin.c:3651:gst_bin_handle_message_func: ASYNC_START message 0x666e7410, playbin0 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.674391087 0x5ce0e400 gstbin.c:883:find_message: no message found matching types 00100000 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.674571253 0x5ce0e400 gstbin.c:890:find_message: async-start 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.674753378 0x5ce0e400 gstbin.c:930:bin_replace_message: got new message 0x666e7410, async-start from playbin0 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.674929670 0x5ce0e400 gstbin.c:3124:bin_handle_async_start: state change busy 12-02 18:06:52.426: D/GStreamer+pipeline(15369): 0:07:23.675107962 0x5ce0e400 gstpipeline.c:305:reset_start_time: reset start_time to 0 12-02 18:06:52.426: D/GStreamer+GST_STATES(15369): 0:07:23.675293045 0x5ce0e400 gstbin.c:2591:gst_bin_change_state_func: changing state of children from READY to PAUSED 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.675471170 0x5ce0e400 gstbin.c:2611:gst_bin_change_state_func: clearing EOS elements 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.675650086 0x5ce0e400 gstbin.c:964:bin_remove_messages: not deleting message 0x666e7410 of type 0x00100000 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.675826336 0x5ce0e400 gstbin.c:964:bin_remove_messages: not deleting message 0x666e7410 of type 0x00100000 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.676000628 0x5ce0e400 gstbin.c:2436:gst_bin_src_pads_activate: activate pads 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.676184503 0x5ce0e400 gstbin.c:2444:gst_bin_src_pads_activate: pad activation successful 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.676361961 0x5ce0e400 gstbin.c:2122:gst_bin_sort_iterator_resync: resync 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.676541378 0x5ce0e400 gstbin.c:1908:add_to_queue: adding 'playsink' to queue 12-02 18:06:52.426: D/GStreamer+bin(15369): 0:07:23.676720670 0x5ce0e400 gstbin.c:2048:update_degree: element playsink not linked on any sinkpads 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.676952962 0x5ce0e400 gstbin.c:2109:gst_bin_sort_iterator_next: queue head gives playsink 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.677132170 0x5ce0e400 gstbin.c:2048:update_degree: element playsink not linked on any sinkpads 12-02 18:06:52.431: I/GStreamer+GST_STATES(15369): 0:07:23.677320920 0x5ce0e400 gstbin.c:2243:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.677498628 0x5ce0e400 gstbin.c:883:find_message: no message found matching types 00100000 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.677672670 0x5ce0e400 gstbin.c:890:find_message: async-start 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.677861712 0x5ce0e400 gstbin.c:2338:gst_bin_element_set_state: setting element playsink to PAUSED, base_time 0:00:00.000000000 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.678039337 0x5ce0e400 gstelement.c:2481:gst_element_set_state_func: set_state to PAUSED 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.678221920 0x5ce0e400 gstelement.c:2506:gst_element_set_state_func: setting target state to PAUSED 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.678404545 0x5ce0e400 gstelement.c:2519:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.678588753 0x5ce0e400 gstelement.c:2553:gst_element_set_state_func: final: setting state from READY to PAUSED 12-02 18:06:52.431: I/GStreamer+playsink(15369): 0:07:23.678770462 0x5ce0e400 gstplaysink.c:1400:do_async_start: Sending async_start message 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.678959837 0x5ce0e400 gstbin.c:3461:gst_bin_handle_message_func: [msg 0x6416d778] handling child playsink message of type async-start 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.679141420 0x5ce0e400 gstbin.c:3651:gst_bin_handle_message_func: ASYNC_START message 0x6416d778, playsink 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.679323795 0x5ce0e400 gstbin.c:883:find_message: no message found matching types 00100000 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.679508670 0x5ce0e400 gstbin.c:890:find_message: async-start 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.679699420 0x5ce0e400 gstbin.c:930:bin_replace_message: got new message 0x6416d778, async-start from playsink 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.679889587 0x5ce0e400 gstbin.c:3124:bin_handle_async_start: state change busy 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.680071712 0x5ce0e400 gstbin.c:3110:bin_handle_async_start: posting ASYNC_START to parent 12-02 18:06:52.431: D/GStreamer+GST_BUS(15369): 0:07:23.680304086 0x5ce0e400 gstbus.c:309:gst_bus_post: [msg 0x6416d6c8] posting on bus async-start message: 0x6416d6c8, time 99:99:99.999999999, seq-num 145, element 'playsink', (NULL) 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.680503295 0x5ce0e400 gstbin.c:3461:gst_bin_handle_message_func: [msg 0x6416d6c8] handling child playsink message of type async-start 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.680689878 0x5ce0e400 gstbin.c:3651:gst_bin_handle_message_func: ASYNC_START message 0x6416d6c8, playsink 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.680870461 0x5ce0e400 gstbin.c:883:find_message: no message found matching types 00100000 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.681048586 0x5ce0e400 gstbin.c:890:find_message: async-start 12-02 18:06:52.431: D/GStreamer+bin(15369): 0:07:23.681229378 0x5ce0e400 gstbin.c:930:bin_replace_message: got new message 0x6416d6c8, async-start from playsink 12-02 18:06:52.431: D/GStreamer+GST_STATES(15369): 0:07:23.681401336 0x5ce0e400 gstbin.c:3124:bin_handle_async_start: state change busy 12-02 18:06:52.431: D/GStreamer+GST_BUS(15369): 0:07:23.681577961 0x5ce0e400 gstbus.c:344:gst_bus_post: [msg 0x6416d6c8] dropped 12-02 18:06:52.436: D/GStreamer+GST_STATES(15369): 0:07:23.681803420 0x5ce0e400 gstbin.c:2591:gst_bin_change_state_func: changing state of children from READY to PAUSED 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.681979087 0x5ce0e400 gstbin.c:2611:gst_bin_change_state_func: clearing EOS elements 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.682163670 0x5ce0e400 gstbin.c:964:bin_remove_messages: not deleting message 0x6416d778 of type 0x00100000 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.682340587 0x5ce0e400 gstbin.c:964:bin_remove_messages: not deleting message 0x6416d778 of type 0x00100000 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.682522503 0x5ce0e400 gstbin.c:2436:gst_bin_src_pads_activate: activate pads 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.682704420 0x5ce0e400 gstbin.c:2444:gst_bin_src_pads_activate: pad activation successful 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.682888003 0x5ce0e400 gstbin.c:2122:gst_bin_sort_iterator_resync: resync 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.683077045 0x5ce0e400 gstbin.c:2048:update_degree: element streamsynchronizer0 not linked on any sinkpads 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.683471795 0x5ce0e400 gstbin.c:2094:gst_bin_sort_iterator_next: queue empty, next best: streamsynchronizer0 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.683708170 0x5ce0e400 gstbin.c:2109:gst_bin_sort_iterator_next: queue head gives streamsynchronizer0 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.684175253 0x5ce0e400 gstbin.c:2048:update_degree: element streamsynchronizer0 not linked on any sinkpads 12-02 18:06:52.436: I/GStreamer+GST_STATES(15369): 0:07:23.684373670 0x5ce0e400 gstbin.c:2243:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.684579962 0x5ce0e400 gstbin.c:883:find_message: no message found matching types 00100000 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.684784045 0x5ce0e400 gstbin.c:890:find_message: async-start 12-02 18:06:52.436: D/GStreamer+bin(15369): 0:07:23.684994712 0x5ce0e400 gstbin.c:2338:gst_bin_element_set_state: setting element streamsynchronizer0 to PAUSED, base_time 0:00:00.000000000 12-02 18:06:52.436: D/GStreamer+GST_STATES(15369): 0:07:23.685193711 0x5ce0e400 gstelement.c:2481:gst_element_set_state_func: set_state to PAUSED 12-02 18:06:52.436: D/GStreamer+GST_STATES(15369): 0:07:23.685393295 0x5ce0e400 gstelement.c:2506:gst_element_set_state_func: setting target state to PAUSED 12-02 18:06:52.436: D/GStreamer+GST_STATES(15369): 0:07:23.685598586 0x5ce0e400 gstelement.c:2519:gst_element_set_state_func: current READY, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS 12-02 18:06:52.436: D/GStreamer+GST_STATES(15369): 0:07:23.685797461 0x5ce0e400 gstelement.c:2553:gst_element_set_state_func: final: setting state from READY to PAUSED 12-02 18:06:52.436: D/GStreamer+streamsynchronizer(15369): 0:07:23.686001503 0x5ce0e400 gststreamsynchronizer.c:909:gst_stream_synchronizer_change_state: State change READY->PAUSED 12-02 18:06:52.436: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.686328045 0x5ce0e400 gstelement.c:2747:gst_element_pads_activate: activate pads 12-02 18:06:52.436: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.686560420 0x5ce0e400 gstelement.c:2766:gst_element_pads_activate: pad activation successful 12-02 18:06:52.441: D/GStreamer+streamsynchronizer(15369): 0:07:23.686756003 0x5ce0e400 gststreamsynchronizer.c:936:gst_stream_synchronizer_change_state: Base class state changed returned: 1 12-02 18:06:52.441: D/GStreamer+GST_STATES(15369): 0:07:23.686988462 0x5ce0e400 gstelement.c:2638:gst_element_change_state: element changed state SUCCESS 12-02 18:06:52.441: I/GStreamer+GST_STATES(15369): 0:07:23.687178962 0x5ce0e400 gstelement.c:2330:gst_element_continue_state: completed state change to PAUSED 12-02 18:06:52.441: I/GStreamer+GST_STATES(15369): 0:07:23.687373128 0x5ce0e400 gstelement.c:2235:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending) 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.687667920 0x5ce0e400 gstbus.c:309:gst_bus_post: [msg 0x666e7468] posting on bus state-changed message: 0x666e7468, time 99:99:99.999999999, seq-num 146, element 'streamsynchronizer0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 12-02 18:06:52.441: D/GStreamer+bin(15369): 0:07:23.687863837 0x5ce0e400 gstbin.c:3461:gst_bin_handle_message_func: [msg 0x666e7468] handling child streamsynchronizer0 message of type state-changed 12-02 18:06:52.441: D/GStreamer+bin(15369): 0:07:23.688096128 0x5ce0e400 gstbin.c:3796:gst_bin_handle_message_func: posting message upward 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.688354462 0x5ce0e400 gstbus.c:309:gst_bus_post: [msg 0x666e7468] posting on bus state-changed message: 0x666e7468, time 99:99:99.999999999, seq-num 146, element 'streamsynchronizer0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 12-02 18:06:52.441: D/GStreamer+bin(15369): 0:07:23.688545712 0x5ce0e400 gstbin.c:3461:gst_bin_handle_message_func: [msg 0x666e7468] handling child streamsynchronizer0 message of type state-changed 12-02 18:06:52.441: D/GStreamer+bin(15369): 0:07:23.688725920 0x5ce0e400 gstbin.c:3796:gst_bin_handle_message_func: posting message upward 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.688980753 0x5ce0e400 gstbus.c:309:gst_bus_post: [msg 0x666e7468] posting on bus state-changed message: 0x666e7468, time 99:99:99.999999999, seq-num 146, element 'streamsynchronizer0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.689164045 0x5ce0e400 gstbus.c:348:gst_bus_post: [msg 0x666e7468] pushing on async queue 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.689434128 0x6403c860 gstbus.c:521:gst_bus_timed_pop_filtered: got message 0x666e7468, state-changed from streamsynchronizer0, type mask is 4294967295 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.689690045 0x6403c860 gstbus.c:789:gst_bus_source_dispatch: source 0x6491cae0 calling dispatch with state-changed message: 0x666e7468, time 99:99:99.999999999, seq-num 146, element 'streamsynchronizer0', GstMessageStateChanged, old-state=(GstState)GST_STATE_READY, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_VOID_PENDING; 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.689904087 0x6403c860 gstbus.c:794:gst_bus_source_dispatch: source 0x6491cae0 handler returns 1 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.690169503 0x5ce0e400 gstbus.c:351:gst_bus_post: [msg 0x666e7468] pushed on async queue 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.690371878 0x5ce0e400 gstbus.c:344:gst_bus_post: [msg 0x666e7468] dropped 12-02 18:06:52.441: D/GStreamer+GST_BUS(15369): 0:07:23.690564170 0x5ce0e400 gstbus.c:344:gst_bus_post: [msg 0x666e7468] dropped 12-02 18:06:52.441: D/GStreamer+GST_STATES(15369): 0:07:23.690759545 0x5ce0e400 gstelement.c:2565:gst_element_set_state_func: returned SUCCESS 12-02 18:06:52.441: I/GStreamer+GST_STATES(15369): 0:07:23.690961545 0x5ce0e400 gstbin.c:2707:gst_bin_change_state_func: child 'streamsynchronizer0' changed state to 3(PAUSED) successfully 12-02 18:06:52.441: D/GStreamer+bin(15369): 0:07:23.691168086 0x5ce0e400 gstbin.c:2098:gst_bin_sort_iterator_next: queue empty, elements exhausted 12-02 18:06:52.441: D/GStreamer+GST_STATES(15369): 0:07:23.691368378 0x5ce0e400 gstbin.c:2766:gst_bin_change_state_func: iterator done 12-02 18:06:52.441: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.691583086 0x5ce0e400 gstelement.c:2747:gst_element_pads_activate: activate pads 12-02 18:06:52.446: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.691848295 0x5ce0e400 gstelement.c:2766:gst_element_pads_activate: pad activation successful 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.692084004 0x5ce0e400 gstbin.c:2140:gst_bin_sort_iterator_free: free 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.692279795 0x5ce0e400 gstbin.c:2801:gst_bin_change_state_func: no async elements 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.692465212 0x5ce0e400 gstbin.c:2835:gst_bin_change_state_func: done changing bin's state from READY to PAUSED, now in READY, ret SUCCESS 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.692638587 0x5ce0e400 gstelement.c:2620:gst_element_change_state: element will change state ASYNC 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.692812170 0x5ce0e400 gstelement.c:2565:gst_element_set_state_func: returned ASYNC 12-02 18:06:52.446: I/GStreamer+GST_STATES(15369): 0:07:23.692993212 0x5ce0e400 gstbin.c:2713:gst_bin_change_state_func: child 'playsink' is changing state asynchronously to PAUSED 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.693163920 0x5ce0e400 gstbin.c:2098:gst_bin_sort_iterator_next: queue empty, elements exhausted 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.693330837 0x5ce0e400 gstbin.c:2766:gst_bin_change_state_func: iterator done 12-02 18:06:52.446: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.693503962 0x5ce0e400 gstelement.c:2747:gst_element_pads_activate: activate pads 12-02 18:06:52.446: D/GStreamer+GST_ELEMENT_PADS(15369): 0:07:23.693687045 0x5ce0e400 gstelement.c:2766:gst_element_pads_activate: pad activation successful 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.693864587 0x5ce0e400 gstbin.c:2784:gst_bin_change_state_func: we have ASYNC elements SUCCESS -> ASYNC 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.694036962 0x5ce0e400 gstbin.c:2140:gst_bin_sort_iterator_free: free 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.694204045 0x5ce0e400 gstbin.c:2812:gst_bin_change_state_func: check async elements 12-02 18:06:52.446: D/GStreamer+bin(15369): 0:07:23.694381253 0x5ce0e400 gstbin.c:881:find_message: we found a message 0x6416d6c8 from playsink matching types 00100000 12-02 18:06:52.446: D/GStreamer+GST_STATES(15369): 0:07:23.694558212 0x5ce0e400 gstbin.c:2835:gst_bin_change_state_func: done changing bin's state from READY to PAUSED, now in READY, ret ASYNC 12-02 18:06:52.446: D/GStreamer+playbin(15369): 0:07:23.694722087 0x5ce0e400 gstplaybin2.c:5551:setup_next_source: setup sources 12-02 18:06:52.446: D/GStreamer+playbin(15369): 0:07:23.694889045 0x5ce0e400 gstplaybin2.c:5145:activate_group: activating group 0x640659f8 12-02 18:06:52.446: D/GStreamer+GST_BUS(15369): 0:07:23.695084378 0x5ce0e400 gstbus.c:226:gst_bus_init: created 12-02 18:06:52.446: D/GStreamer+GST_POLL(15369): 0:07:23.695282920 0x5ce0e400 gstpoll.c:557:gst_poll_new 0x63f6fa18: new controllable : 1 12-02 18:06:52.446: D/GStreamer+GST_POLL(15369): 0:07:23.695516545 0x5ce0e400 gstpoll.c:726:gst_poll_add_fd_unlocked 0x63f6fa18: fd (fd:68, idx:0) 12-02 18:06:52.446: D/GStreamer+GST_POLL(15369): 0:07:23.695683086 0x5ce0e400 gstpoll.c:893:gst_poll_fd_ctl_read_unlocked 0x63f6fa18: fd (fd:68, idx:0), active : 1 12-02 18:06:52.446: D/GStreamer+GST_BUS(15369): 0:07:23.695871295 0x5ce0e400 gstbus.c:280:gst_bus_new: created new bus 12-02 18:06:52.446: D/GStreamer+GST_PARENTAGE(15369): 0:07:23.696073836 0x5ce0e400 gstelement.c:2972:gst_element_set_bus_func: