• State TI Thinks Resolved
  • Locked Locked
  • Replies 4 replies
  • Answers 2 answers
  • Subscribers 99 subscribers
  • Views 614 views
  • Users 0 members are here
Support feedback
Options
Options
Related

This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

Linux/DRA72: Play video from MTP device, after seek, get error from decoder.

user5901284
Intellectual 390 points

Part Number: DRA72

Tool/software: Linux

Hi Team,

When we play the attached video from MTP, after seek, get error from decode.

Precondition:
1. HU is powered on.
2. Samsung Galaxy S8 connected as MTP.

Steps:
1. Slect and play the Video file attached.
2. After playback playing, seek to 16000ms. Then TI decoder reports error.

Could you please check. Thanks.

Vicky

  • 0
    TI__Mastermind 30480 points
    Hi Vicky,
    Is gstreamer framework being used for video playback? Please let me know the SDK version.
    What is the error reported by the decoder. Can you share the logs?
    Looks like you have attached the observation video not the actual stream. Can you confirm?

    Thanks
    Ramprasad
  • 0 in reply to Ramprasad
    Intellectual 390 points

    Hi Ramprasad,

    Is gstreamer framework being used for video playback? Please let me know the SDK version.

    [Vicky] Yes, the sdk version: PSDKLA: 3.03

    What is the error reported by the decoder. Can you share the logs?

    [Vicky] Please see anaylsis below, and log attached.

    1. We do seek from here.

    184605 2019/01/10 17:49:15.000000 443.4260 12 ECU1 CrM HMPE 2635 log info verbose 1 T2642 CURIPlayer::seekTo() pos: 16000

    2. We never see PLAYING state from this point, and TI decoder reports error.

    184607 2019/01/10 17:49:15.000000 443.4284 60 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got state-changed message: Element "URI Pipeline" state PAUSED --> PAUSED

    184615 2019/01/10 17:49:15.000000 443.4309 61 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(753):codec_process:<decoder> push failed flushing

    184616 2019/01/10 17:49:15.000000 443.4311 62 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(1482):gst_ducati_viddec_chain:<decoder> push from codec_process failed flushing

    184644 2019/01/10 17:49:15.000000 443.4466 63 ECU1 CrM API 2635 log info verbose 1 T2642 GSTLogs WARN ducati gstducatividdec.c(570):codec_process:<decoder> err=-1, extendedError=00040000

    184645 2019/01/10 17:49:15.000000 443.4467 64 ECU1 CrM API 2635 log info verbose 1 T2642 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end

    184694 2019/01/10 17:49:15.000000 443.4728 69 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(570):codec_process:<decoder> err=-1, extendedError=00040000

    184695 2019/01/10 17:49:15.000000 443.4729 70 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end

    Looks like you have attached the observation video not the actual stream. Can you confirm?

    [Vicky] From the log, we can see issue occurred while playing “20181219_150630.mp4”, which I attached.

    Fullscreen gstreamerlog.txt Download 
    183009 2019/01/10 17:49:14.000000 442.0694 27 ECU1 CrM MMC 2635 log info verbose 1 T2635 MediaManager[Default]: Start to get devices
183012 2019/01/10 17:49:14.000000 442.0695 28 ECU1 CrM MMC 2635 log debug verbose 1 T2635 MediaManager[Default], Device: [mediumId:[mediumId:1,mountPath:/tmp/SDCard_Slot1-p1,Type:DT_USB,syncable: 1, handleId:0, syncState:SYNCEVENT_UNKNOWN,huname:,network address:],DeviceState:[Available],SyncState:[SYNCEVENT_COMPLETE],DeviceName:[nav],uuid:[940AD98A0AD969AE],AuthState:[0],devClass:[MSC],huname:[],SiriState:[0],browse:[0],newFlag:[1];]
183013 2019/01/10 17:49:14.000000 442.0696 29 ECU1 CrM MMC 2635 log debug verbose 1 T2635 MediaManager[Default], Device: [mediumId:[mediumId:2,mountPath:/tmp/MTP1-1.3,Type:DT_MTP,syncable: 1, handleId:0, syncState:SYNCEVENT_UNKNOWN,huname:,network address:],DeviceState:[Available],SyncState:[SYNCEVENT_COMPLETE],DeviceName:[],uuid:[98891a473431334755],AuthState:[0],devClass:[MTP],huname:[],SiriState:[0],browse:[0],newFlag:[0];]
183014 2019/01/10 17:49:14.000000 442.0696 30 ECU1 CrM MMC 2635 log info verbose 1 T2635 MediaManager[Default]: Get devices end
183015 2019/01/10 17:49:14.000000 442.0698 89 ECU1 CrM Glue 2635 log info verbose 1 T2635 CPlayerExtIf: setting current song uri to item://127.0.0.1:13800/mtp/98891a473431334755/videoitem/movie/DCIM%2FCamera%2F20181219%5F150630%2Emp4/?%28Error%3A%20Unknown%20meta%20type%29=0&ALBUM=&ARTIST=&BITRATE=3124378&COVERART=&DURATION=16021&FOLDERID=1073741895&GENRE=&ITEMID=1877&NAME=20181219%5F150630&PATH=DCIM%2FCamera%2F20181219%5F150630%2Emp4&SAMPLINGRATE=0&SIZE=6725282&SONGID=1877&TITLE=20181219%5F150630&TRACKNUM=0&TYPE=video&VIDEOID=1877&ViewId=0
183020 2019/01/10 17:49:14.000000 442.0703 8 ECU1 CrM API 2635 log info verbose 1 T2635 find vsink element
183021 2019/01/10 17:49:14.000000 442.0703 9 ECU1 CrM API 2635 log info verbose 1 T2635 vsink CAPS : framerate 30/1
183022 2019/01/10 17:49:14.000000 442.0705 10 ECU1 CrM API 2635 log info verbose 1 T2635 vsink CAPS : width 640, height 480
183023 2019/01/10 17:49:14.000000 442.0706 11 ECU1 CrM API 2635 log info verbose 1 T2635 vsink CAPS : 1 video stream(s), current idx 0
183025 2019/01/10 17:49:14.000000 442.0707 12 ECU1 CrM API 2635 log info verbose 1 T2635 vsink CAPS : codec: H.264 (High Profile)
183027 2019/01/10 17:49:14.000000 442.0710 13 ECU1 CrM API 2635 log info verbose 1 T2635 find asink element
183029 2019/01/10 17:49:14.000000 442.0710 14 ECU1 CrM API 2635 log info verbose 1 T2635 asink CAPS : sample rate 48000
183030 2019/01/10 17:49:14.000000 442.0710 15 ECU1 CrM API 2635 log info verbose 1 T2635 asink CAPS : 1 audio stream(s), current idx 0
183031 2019/01/10 17:49:14.000000 442.0711 16 ECU1 CrM API 2635 log info verbose 1 T2635 asink CAPS : codec: MPEG-4 AAC
183032 2019/01/10 17:49:14.000000 442.0715 90 ECU1 CrM Glue 2635 log info verbose 1 T2635 DeviceManagerIf: processEvent
183033 2019/01/10 17:49:14.000000 442.0716 91 ECU1 CrM Glue 2635 log info verbose 1 T2635 Get a media stream info event update
183034 2019/01/10 17:49:14.000000 442.0717 92 ECU1 CrM Glue 2635 log  verbose 1 T2635 PlayerIf[Default] >> setStreamInfoAttribute called
183035 2019/01/10 17:49:14.000000 442.0731 239 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CURIPlayer::send EID_ARBITER_DECODERINFO event to arbiter
183036 2019/01/10 17:49:14.000000 442.0732 93 ECU1 CrM Glue 2635 log info verbose 1 T2635 DeviceManagerIf: processEvent
183037 2019/01/10 17:49:14.000000 442.0733 17 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got application Application message
183038 2019/01/10 17:49:14.000000 442.0734 240 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleApplication() called
183039 2019/01/10 17:49:14.000000 442.0734 241 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CheckVideoResolution: limitation (0,0), current[0, 0]
183040 2019/01/10 17:49:14.000000 442.0734 94 ECU1 CrM Glue 2635 log info verbose 1 T2635 DeviceManagerIf: processEvent
183041 2019/01/10 17:49:14.000000 442.0735 95 ECU1 CrM Glue 2635 log  verbose 1 T2635 PlayerIf[Default] >> setVideoPlayStatusAttribute: value=true
183042 2019/01/10 17:49:14.000000 442.0735 96 ECU1 CrM Glue 2635 log  verbose 1 T2635 PlayerIf[Default] << trySetVideoPlayStatusAttribute
183082 2019/01/10 17:49:14.000000 442.0789 18 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183094 2019/01/10 17:49:14.000000 442.0880 19 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 11/128, 0
183097 2019/01/10 17:49:14.000000 442.0893 20 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183116 2019/01/10 17:49:14.000000 442.0950 21 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 12/128, 1
183537 2019/01/10 17:49:14.000000 442.1458 22 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183541 2019/01/10 17:49:14.000000 442.1465 23 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3091962
183542 2019/01/10 17:49:14.000000 442.1466 242 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183547 2019/01/10 17:49:14.000000 442.1468 45 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183548 2019/01/10 17:49:14.000000 442.1469 46 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183549 2019/01/10 17:49:14.000000 442.1469 243 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183551 2019/01/10 17:49:14.000000 442.1470 24 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3106377
183552 2019/01/10 17:49:14.000000 442.1470 244 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183553 2019/01/10 17:49:14.000000 442.1471 47 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183554 2019/01/10 17:49:14.000000 442.1471 48 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183555 2019/01/10 17:49:14.000000 442.1471 245 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183564 2019/01/10 17:49:14.000000 442.1496 25 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 13/128, 0
183567 2019/01/10 17:49:14.000000 442.1537 26 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 14/128, 1
183707 2019/01/10 17:49:14.000000 442.2133 27 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183708 2019/01/10 17:49:14.000000 442.2173 28 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 15/128, 0
183709 2019/01/10 17:49:14.000000 442.2217 29 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 16/128, 1
183762 2019/01/10 17:49:14.000000 442.2804 30 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183769 2019/01/10 17:49:14.000000 442.2829 31 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3095524
183770 2019/01/10 17:49:14.000000 442.2829 246 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183771 2019/01/10 17:49:14.000000 442.2829 49 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183772 2019/01/10 17:49:14.000000 442.2829 50 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183773 2019/01/10 17:49:14.000000 442.2830 247 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183774 2019/01/10 17:49:14.000000 442.2837 32 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3064425
183775 2019/01/10 17:49:14.000000 442.2837 248 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183776 2019/01/10 17:49:14.000000 442.2838 51 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183777 2019/01/10 17:49:14.000000 442.2838 52 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183778 2019/01/10 17:49:14.000000 442.2838 249 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183779 2019/01/10 17:49:14.000000 442.2851 33 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 17/128, 0
183782 2019/01/10 17:49:14.000000 442.2892 34 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN vpe gstvpebufferpool.c(466):gst_vpe_buffer_pool_import: Allocating a new input buffer index: 18/128, 1
183808 2019/01/10 17:49:14.000000 442.3458 35 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183809 2019/01/10 17:49:14.000000 442.3458 36 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3079846
183810 2019/01/10 17:49:14.000000 442.3458 250 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183811 2019/01/10 17:49:14.000000 442.3458 53 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183812 2019/01/10 17:49:14.000000 442.3459 54 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183813 2019/01/10 17:49:14.000000 442.3459 251 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183851 2019/01/10 17:49:14.000000 442.4125 37 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183858 2019/01/10 17:49:14.000000 442.4135 38 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3167280
183859 2019/01/10 17:49:14.000000 442.4136 252 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183860 2019/01/10 17:49:14.000000 442.4136 55 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183861 2019/01/10 17:49:14.000000 442.4136 56 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183862 2019/01/10 17:49:14.000000 442.4136 253 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183914 2019/01/10 17:49:14.000000 442.4797 39 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3134216
183915 2019/01/10 17:49:14.000000 442.4799 40 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183916 2019/01/10 17:49:14.000000 442.4804 254 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183917 2019/01/10 17:49:14.000000 442.4804 57 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183918 2019/01/10 17:49:14.000000 442.4804 58 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183919 2019/01/10 17:49:14.000000 442.4805 255 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183960 2019/01/10 17:49:14.000000 442.5452 41 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3144885
183961 2019/01/10 17:49:14.000000 442.5453 42 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
183962 2019/01/10 17:49:14.000000 442.5456 0 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
183963 2019/01/10 17:49:14.000000 442.5457 59 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
183964 2019/01/10 17:49:14.000000 442.5457 60 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
183965 2019/01/10 17:49:14.000000 442.5457 1 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
183991 2019/01/10 17:49:14.000000 442.6118 43 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184032 2019/01/10 17:49:14.000000 442.6791 44 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3112422
184033 2019/01/10 17:49:14.000000 442.6792 45 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184034 2019/01/10 17:49:14.000000 442.6795 2 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
184035 2019/01/10 17:49:14.000000 442.6796 61 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
184036 2019/01/10 17:49:14.000000 442.6796 62 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
184037 2019/01/10 17:49:14.000000 442.6796 3 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
184066 2019/01/10 17:49:14.000000 442.7455 46 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184113 2019/01/10 17:49:15.000000 442.8132 47 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184114 2019/01/10 17:49:15.000000 442.8132 48 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3101396
184115 2019/01/10 17:49:15.000000 442.8132 4 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
184116 2019/01/10 17:49:15.000000 442.8132 63 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
184117 2019/01/10 17:49:15.000000 442.8133 64 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
184118 2019/01/10 17:49:15.000000 442.8133 5 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
184175 2019/01/10 17:49:15.000000 442.8798 49 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184206 2019/01/10 17:49:15.000000 442.9459 50 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184234 2019/01/10 17:49:15.000000 443.0115 51 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184260 2019/01/10 17:49:15.000000 443.0495 6 ECU1 CrM HMPE 2635 log debug verbose 1 T3722 CgstBasePlayer::timeThread() play at 3047 / 16021 (millseconds).
184261 2019/01/10 17:49:15.000000 443.0495 97 ECU1 CrM Glue 2635 log info verbose 1 T3722 DeviceManagerIf: processEvent
184262 2019/01/10 17:49:15.000000 443.0495 98 ECU1 CrM Glue 2635 log debug verbose 1 T3722 MPIf: Processing play pos 3047 / 16021
184263 2019/01/10 17:49:15.000000 443.0495 99 ECU1 CrM Glue 2635 log  verbose 1 T3722 PlayerIf[Default] >> setPositionAttribute: value=3047000
184264 2019/01/10 17:49:15.000000 443.0497 100 ECU1 CrM Glue 2635 log  verbose 1 T3722 PlayerIf[Default] >> setDurationAttribute: value=16021000
184265 2019/01/10 17:49:15.000000 443.0498 101 ECU1 CrM Glue 2635 log info verbose 1 T3722 CPlayerExtIf: currentSongURI: item://127.0.0.1:13800/mtp/98891a473431334755/videoitem/movie/DCIM%2FCamera%2F20181219%5F150630%2Emp4/?%28Error%3A%20Unknown%20meta%20type%29=0&ALBUM=&ARTIST=&BITRATE=3124378&COVERART=&DURATION=16021&FOLDERID=1073741895&GENRE=&ITEMID=1877&NAME=20181219%5F150630&PATH=DCIM%2FCamera%2F20181219%5F150630%2Emp4&SAMPLINGRATE=0&SIZE=6725282&SONGID=1877&TITLE=20181219%5F150630&TRACKNUM=0&TYPE=video&VIDEOID=1877&ViewId=0, isCurrentSongSaved: 0, playedTick: 0
184266 2019/01/10 17:49:15.000000 443.0498 102 ECU1 CrM Glue 2635 log info verbose 1 T3722 CPlayerExtIf: it is video ignore this
184355 2019/01/10 17:49:15.000000 443.0790 52 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184356 2019/01/10 17:49:15.000000 443.0791 53 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3086617
184357 2019/01/10 17:49:15.000000 443.0791 7 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleBitrate() called
184358 2019/01/10 17:49:15.000000 443.0791 65 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] << getPlaylistMode
184359 2019/01/10 17:49:15.000000 443.0792 66 ECU1 CrM Vugs 2635 log  verbose 1 T2635 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
184360 2019/01/10 17:49:15.000000 443.0792 8 ECU1 CrM HMPE 2635 log info verbose 1 T2635 CgstBasePlayer::onHandleChanged() called
184394 2019/01/10 17:49:15.000000 443.1459 54 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184420 2019/01/10 17:49:15.000000 443.2126 55 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184459 2019/01/10 17:49:15.000000 443.2788 56 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184485 2019/01/10 17:49:15.000000 443.3455 57 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184515 2019/01/10 17:49:15.000000 443.4120 58 ECU1 CrM API 2635 log info verbose 1 T6652 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool17> alloc function failed
184516 2019/01/10 17:49:15.000000 443.4120 59 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3125380


184605 2019/01/10 17:49:15.000000 443.4260 12 ECU1 CrM HMPE 2635 log info verbose 1 T2642 CURIPlayer::seekTo() pos: 16000
184606 2019/01/10 17:49:15.000000 443.4260 193 ECU1 CrM CAPI 2635 log debug verbose 1 Time stamp after callback execution : 443426, for method : setPosition
184607 2019/01/10 17:49:15.000000 443.4284 60 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got state-changed message: Element "URI Pipeline" state PAUSED --> PAUSED
184615 2019/01/10 17:49:15.000000 443.4309 61 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(753):codec_process:<decoder> push failed flushing
184616 2019/01/10 17:49:15.000000 443.4311 62 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(1482):gst_ducati_viddec_chain:<decoder> push from codec_process failed flushing
184644 2019/01/10 17:49:15.000000 443.4466 63 ECU1 CrM API 2635 log info verbose 1 T2642 GSTLogs WARN ducati gstducatividdec.c(570):codec_process:<decoder> err=-1, extendedError=00040000
184645 2019/01/10 17:49:15.000000 443.4467 64 ECU1 CrM API 2635 log info verbose 1 T2642 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end
184646 2019/01/10 17:49:15.000000 443.4482 65 ECU1 CrM API 2635 log debug verbose 1 T2635 CgstBasePlayer::gstBusCallback()(default) Got reset-time message from Element "vsink"
184647 2019/01/10 17:49:15.000000 443.4483 66 ECU1 CrM API 2635 log debug verbose 1 T2635 CgstBasePlayer::gstBusCallback()(default) Got reset-time message from Element "asink"
184664 2019/01/10 17:49:15.000000 443.4639 67 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3104990
184671 2019/01/10 17:49:15.000000 443.4682 68 ECU1 CrM API 2635 log info verbose 1 T2635 CgstPlayerApi::gstBusCallback() Got tag message from Element vsink: TagName: bitrate: 3197234
184694 2019/01/10 17:49:15.000000 443.4728 69 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs WARN ducati gstducatividdec.c(570):codec_process:<decoder> err=-1, extendedError=00040000
184695 2019/01/10 17:49:15.000000 443.4729 70 ECU1 CrM API 2635 log info verbose 1 T6127 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end

  • 0 in reply to user5901284
    TI__Mastermind 30480 points
    Hi Vicky,
    Error 0x40000 from decoder means it is end of stream(EOS). When seek operation is performed, then also decoder throws this error at FLUSH_STOP. but this is not a serious error and this is expected in seeking scenario.

    I could play and do trick-play with 20181219_150630.mp4 video but no issue observed.

    Thanks
    Ramprasad
  • 0 in reply to Ramprasad
    TI__Mastermind 30480 points
    Hi Vicky,
    Please apply this patch on gstvpe. This patch seems to be missing.
    Your issue should get resolved with this.
    e2e.ti.com/.../751281
    vpe_EOS_handle.zip

    Thanks
    Ram