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.

DRA746: Gstreamser stuck when stop

Part Number: DRA746

Hi TI team,

There is an once issue, gStreamer got stuck after stop video. Attached the log.

8016156 2019/09/12 15:13:46.000000 5738.1687 130 ECU1 CrM CoMN 2424 log info verbose 1 T2633 CEventHandlerQueueSample[Default]::processing NORMAL event [handleNext] [4722]
8016157 2019/09/12 15:13:46.000000 5738.1688 243 ECU1 CrM Glue 2424 log  verbose 1 T2633 PlayerIf[Default] << next Version: 19371 Engine: gstreamer
8016158 2019/09/12 15:13:46.000000 5738.1688 0 ECU1 CrM PlEg 2424 log  verbose 1 T2633 PlayEngine[Default] << next step[1]
8016159 2019/09/12 15:13:46.000000 5738.1688 173 ECU1 CrM HMPE 2424 log  verbose 1 T2633 GSTPlayer[Default] << next: step=1
8016160 2019/09/12 15:13:46.000000 5738.1688 174 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::updateFileLastmode. current play state: Playing
8016161 2019/09/12 15:13:46.000000 5738.1688 175 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::updateFileLastmode: Try update current file last mode.
8016162 2019/09/12 15:13:46.000000 5738.1689 132 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getCurrentPos
8016163 2019/09/12 15:13:46.000000 5738.1689 133 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] >> getCurrentPos: index=64
8016164 2019/09/12 15:13:46.000000 5738.1689 134 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getItem: index=64
8016165 2019/09/12 15:13:46.000000 5738.1689 176 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::updateFileLastmode: Has updated item to playlist. itemid:7; play position:3080.
8016166 2019/09/12 15:13:46.000000 5738.1690 177 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::commitFileState(void): start update file state. row 914
8016167 2019/09/12 15:13:46.000000 5738.1690 178 ECU1 CrM HMPE 2424 log info verbose 1 T2633 Try to update file state; 7; 3080.
8016168 2019/09/12 15:13:46.000000 5738.1690 179 ECU1 CrM HMPE 2424 log info verbose 1 T2633 File: CgstBasePlayer. Function: updateDataBase. row: 1441. update file state to database. 7; 1; 3080
8016169 2019/09/12 15:13:46.000000 5738.1691 190 ECU1 CrM DBES 2424 log info verbose 1 T2633 sqlite execute executeSQL::sqlite3_exec [ 75 ]us, stmt=0x0, sql={ select 1; }
8016170 2019/09/12 15:13:46.000000 5738.1691 80 ECU1 CrM DBEC 2424 log info verbose 1 T2633 setAbortHandler: abortHandler = 0x143e4c
8016171 2019/09/12 15:13:46.000000 5738.1692 191 ECU1 CrM DBES 2424 log info verbose 1 T2633 setAbortHandler: set abortHandler = 0x143e4c
8016172 2019/09/12 15:13:46.000000 5738.1694 192 ECU1 CrM DBES 2424 log info verbose 1 T2633 sqlite execute sqlite3_prepare_v2 [ 67 ]us, stmt=0xa6e35978, sql={ UPDATE files SET playable = ? , lastplaypos = ? WHERE id = ? }
8016173 2019/09/12 15:13:46.000000 5738.1699 180 ECU1 CrM HMPE 2424 log info verbose 1 T2633 File: CgstBasePlayer. Function: updateDataBase. row: 1504. execute: succeed.
8016174 2019/09/12 15:13:46.000000 5738.1700 181 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::commitFileState: over update file state. row 947
8016175 2019/09/12 15:13:46.000000 5738.1701 135 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getPlayableItemByCurrent: currentitem:64; offset:1; item list size:133, force: 1
8016176 2019/09/12 15:13:46.000000 5738.1701 136 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView getItemByCurrent: offset=1
8016177 2019/09/12 15:13:46.000000 5738.1701 137 ECU1 CrM Vugs 2424 log info verbose 1 T2633 GetItemByCurrent fastFBw true
8016178 2019/09/12 15:13:46.000000 5738.1701 138 ECU1 CrM Vugs 2424 log info verbose 1 T2633 GetItemByCurrent is USB fastFBw false
8016179 2019/09/12 15:13:46.000000 5738.1701 139 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getPlayableItemByCurrent: has found playable item 65
8016180 2019/09/12 15:13:46.000000 5738.1702 182 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::commitFileState(void): start update file state. row 914
8016181 2019/09/12 15:13:46.000000 5738.1702 183 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::commitFileState. No pending file state need to commit to database.
8016182 2019/09/12 15:13:46.000000 5738.1702 131 ECU1 CrM CoMN 2424 log info verbose 1 T2633 HResult: Unknown
8016183 2019/09/12 15:13:46.000000 5738.1702 184 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CgstBasePlayer: commitFileState done
8016184 2019/09/12 15:13:46.000000 5738.1702 185 ECU1 CrM HMPE 2424 log info verbose 1 T2633 searchFile:: path file:///tmp/USB1%2D1%2E2%2Dsda1/New%20folder/Vedio%20song/Haar%20Jaani%20Aa%20%2D%20Mehtab%20Virk%20%5F%5F%20Panj%2Daab%20Records%20%5F%5F%20Desiroutz%20%5F%5F%20Sad%20Romantic%20Song%20of%202014%2Emp4 type :5; device type: 0
8016185 2019/09/12 15:13:46.000000 5738.1702 140 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getCount
8016186 2019/09/12 15:13:46.000000 5738.1702 141 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] >> getCount: count=133
8016187 2019/09/12 15:13:46.000000 5738.1704 142 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << setCurrentPos: index=65
8016188 2019/09/12 15:13:46.000000 5738.1704 143 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] << getPlaylistMode
8016189 2019/09/12 15:13:46.000000 5738.1704 144 ECU1 CrM Vugs 2424 log  verbose 1 T2633 PlayView[CView] >> getPlaylistMode: mode=PLAYLIST_VIDEO
8016190 2019/09/12 15:13:46.000000 5738.1705 244 ECU1 CrM Glue 2424 log info verbose 1 T2633 EID_TRACK_STATE event received
8016191 2019/09/12 15:13:46.000000 5738.1705 245 ECU1 CrM Glue 2424 log info verbose 1 T2633 CPlayrIf::processEvent total:133,current:65
8016192 2019/09/12 15:13:46.000000 5738.1705 246 ECU1 CrM Glue 2424 log  verbose 1 T2633 PlayerIf[Default] >>= setTotalTracksAttribute: value=133
8016194 2019/09/12 15:13:46.000000 5738.1707 247 ECU1 CrM Glue 2424 log  verbose 1 T2633 PlayerIf[Default] >> setCurrentTrackAttribute: value=65
8016206 2019/09/12 15:13:46.000000 5738.1712 145 ECU1 CrM Vugs 2424 log info verbose 1 T2633 CPlayViewCore::setCurrentPos: Audio playlist notified EID_TRACK_STATE of index 65 instead of 65
8016207 2019/09/12 15:13:46.000000 5738.1712 186 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer:SetNext(file:///tmp/USB1%2D1%2E2%2Dsda1/New%20folder/Vedio%20song/Haar%20Jaani%20Aa%20%2D%20Mehtab%20Virk%20%5F%5F%20Panj%2Daab%20Records%20%5F%5F%20Desiroutz%20%5F%5F%20Sad%20Romantic%20Song%20of%202014%2Emp4) m_pPipeline:0x70ce60
8016208 2019/09/12 15:13:46.000000 5738.1713 248 ECU1 CrM Glue 2424 log info verbose 1 T2633 DeviceManagerIf: processEvent
8016209 2019/09/12 15:13:46.000000 5738.1713 249 ECU1 CrM Glue 2424 log info verbose 1 T2633 MPIf: CMetadata EID_PLAYER_SESSION_END
8016210 2019/09/12 15:13:46.000000 5738.1713 132 ECU1 CrM CoMN 2424 log info verbose 1 T2633 MPIf: handleSessionEnd, CMetadata::currentMetadata().use_count() = 2, session 99, TTL 1
8016211 2019/09/12 15:13:46.000000 5738.1713 133 ECU1 CrM CoMN 2424 log info verbose 1 T2633 CMetadata::getSessionResult, CMetadata::currentMetadata().use_count() = 2, session 99, TTL 1
8016212 2019/09/12 15:13:46.000000 5738.1713 134 ECU1 CrM CoMN 2424 log info verbose 1 T2633 MPIf: handleSessionEnd CMetadata reduce TTL, current is TTL 1
8016213 2019/09/12 15:13:46.000000 5738.1714 135 ECU1 CrM CoMN 2424 log warn verbose 1 T2633 CMetadata::deattachMetadata reduce TTL and reset it, current session 99, current is TTL 0
8016214 2019/09/12 15:13:46.000000 5738.1714 136 ECU1 CrM CoMN 2424 log info verbose 1 T2633 CMetadata::CMetadata destroyed Session 99
8016215 2019/09/12 15:13:46.000000 5738.1714 137 ECU1 CrM CoMN 2424 log info verbose 1 T2633 MPIf: handleSessionEnd CMetadata normal error code 0
8016216 2019/09/12 15:13:46.000000 5738.1714 250 ECU1 CrM Glue 2424 log info verbose 1 T2633 DeviceManagerIf: processEvent
8016217 2019/09/12 15:13:46.000000 5738.1715 251 ECU1 CrM Glue 2424 log info verbose 1 T2633 MPIf: CMetadata EID_PLAYER_SESSION_START
8016218 2019/09/12 15:13:46.000000 5738.1715 138 ECU1 CrM CoMN 2424 log info verbose 1 T2633 CMetadata::CMetadata new Cmetadata Session 100
8016219 2019/09/12 15:13:46.000000 5738.1715 139 ECU1 CrM CoMN 2424 log warn verbose 1 T2633 CMetadata::attachMetadata no metadata, attach new one, current TTL is 1, current session 100
8016220 2019/09/12 15:13:46.000000 5738.1715 252 ECU1 CrM Glue 2424 log info verbose 1 T2633 MPIf: CMetadata::currentMetadata().use_count() = 3, current session 100, TTL 1
8016221 2019/09/12 15:13:46.000000 5738.1715 199 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:stop() set state to stop
8016222 2019/09/12 15:13:46.000000 5738.1715 200 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:stopAsync() set state to stop
8016298 2019/09/12 15:13:46.000000 5738.1877 187 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x9ac158
8016299 2019/09/12 15:13:46.000000 5738.1879 188 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xa65ee3b0, pool 0x9ac158
8016301 2019/09/12 15:13:46.000000 5738.1891 201 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016302 2019/09/12 15:13:46.000000 5738.1907 202 ECU1 CrM API 2424 log info verbose 1 T2424 CgstPlayerApi::gstBusCallback() Got state-changed message: Element "URI Pipeline" state PLAYING --> PAUSED
8016303 2019/09/12 15:13:46.000000 5738.1907 189 ECU1 CrM HMPE 2424 log info verbose 1 T2424 updatePlayState mTargetPlayState = Idle, mPlayState = Paused, oriState=Playing, updateState=0
8016304 2019/09/12 15:13:46.000000 5738.1909 203 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "audiosinkringbuffer96", type: 2, owner: asink, object type: GThread, value: (nil)
8016305 2019/09/12 15:13:46.000000 5738.1911 204 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: aqueue, object type: GstTask, value: 0x63eb70
8016313 2019/09/12 15:13:46.000000 5738.1999 205 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016315 2019/09/12 15:13:46.000000 5738.2104 206 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016320 2019/09/12 15:13:46.000000 5738.2210 207 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016333 2019/09/12 15:13:46.000000 5738.2318 208 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016359 2019/09/12 15:13:46.000000 5738.2424 209 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016361 2019/09/12 15:13:47.000000 5738.2529 210 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016366 2019/09/12 15:13:47.000000 5738.2637 211 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016368 2019/09/12 15:13:47.000000 5738.2744 212 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016378 2019/09/12 15:13:47.000000 5738.2850 213 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016386 2019/09/12 15:13:47.000000 5738.2957 214 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016390 2019/09/12 15:13:47.000000 5738.3062 215 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016397 2019/09/12 15:13:47.000000 5738.3168 216 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016414 2019/09/12 15:13:47.000000 5738.3272 217 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016425 2019/09/12 15:13:47.000000 5738.3378 218 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016426 2019/09/12 15:13:47.000000 5738.3482 219 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016427 2019/09/12 15:13:47.000000 5738.3587 220 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016436 2019/09/12 15:13:47.000000 5738.3691 221 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016488 2019/09/12 15:13:47.000000 5738.3793 222 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016490 2019/09/12 15:13:47.000000 5738.3894 223 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016496 2019/09/12 15:13:47.000000 5738.3996 224 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016505 2019/09/12 15:13:47.000000 5738.4098 225 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016514 2019/09/12 15:13:47.000000 5738.4199 226 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016526 2019/09/12 15:13:47.000000 5738.4302 227 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016536 2019/09/12 15:13:47.000000 5738.4404 228 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016548 2019/09/12 15:13:47.000000 5738.4506 229 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016549 2019/09/12 15:13:47.000000 5738.4608 230 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016562 2019/09/12 15:13:47.000000 5738.4711 231 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016574 2019/09/12 15:13:47.000000 5738.4813 232 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016580 2019/09/12 15:13:47.000000 5738.4904 190 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x9ac0f0
8016582 2019/09/12 15:13:47.000000 5738.4906 191 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xa6dee3b0, pool 0x9ac0f0
8016584 2019/09/12 15:13:47.000000 5738.4914 233 ECU1 CrM API 2424 log info verbose 1 T9159 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool137> alloc function failed
8016585 2019/09/12 15:13:47.000000 5738.4917 234 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: vqueue, object type: GstTask, value: 0xb2624978
8016599 2019/09/12 15:13:47.000000 5738.5018 192 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x9ac020
8016600 2019/09/12 15:13:47.000000 5738.5019 235 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: vpe, object type: GstTask, value: 0xb2624eb8
8016601 2019/09/12 15:13:47.000000 5738.5020 193 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xaad5b3b0, pool 0x9ac020
8016602 2019/09/12 15:13:47.000000 5738.5047 236 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool137> stop failed
8016603 2019/09/12 15:13:47.000000 5738.5077 237 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool137> stop failed
8016605 2019/09/12 15:13:47.000000 5738.5151 238 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN ducati gstducatividdec.c(571):codec_process:<decoder> err=-1, extendedError=00040000
8016606 2019/09/12 15:13:47.000000 5738.5152 239 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end
8016607 2019/09/12 15:13:47.000000 5738.5169 194 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0xa53131a8
8016608 2019/09/12 15:13:47.000000 5738.5170 240 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src_0", type: 2, owner: multiqueue68, object type: GstTask, value: 0xb2624cc0
8016609 2019/09/12 15:13:47.000000 5738.5172 195 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xac8fe3b0, pool 0xa53131a8
8016610 2019/09/12 15:13:47.000000 5738.5173 241 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src_1", type: 2, owner: multiqueue68, object type: GstTask, value: 0x3504c0
8016611 2019/09/12 15:13:47.000000 5738.5173 196 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x354b80
8016612 2019/09/12 15:13:47.000000 5738.5176 197 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xa77b83b0, pool 0x354b80
8016613 2019/09/12 15:13:47.000000 5738.5195 242 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "sink", type: 2, owner: typefind, object type: GstTask, value: 0x350568
8016614 2019/09/12 15:13:47.000000 5738.5196 243 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "sink", type: 2, owner: ittmp4parser68, object type: GstTask, value: 0x3870d0
8016632 2019/09/12 15:13:47.000000 5738.5545 244 ECU1 CrM API 2424 log info verbose 1 T2424 CgstPlayerApi::gstBusCallback() Got state-changed message: Element "URI Pipeline" state PAUSED --> READY
8016633 2019/09/12 15:13:47.000000 5738.5545 198 ECU1 CrM HMPE 2424 log info verbose 1 T2424 clearNowPlayingMetadata
8016634 2019/09/12 15:13:47.000000 5738.5546 199 ECU1 CrM HMPE 2424 log info verbose 1 T2424 clearNowPlayingMetadata:2115 locking
8016635 2019/09/12 15:13:47.000000 5738.5546 200 ECU1 CrM HMPE 2424 log info verbose 1 T2424 clearNowPlayingMetadata:2117 locked
8016636 2019/09/12 15:13:47.000000 5738.5546 201 ECU1 CrM HMPE 2424 log info verbose 1 T2424 clearNowPlayingMetadata:2126 unlocked
8016637 2019/09/12 15:13:47.000000 5738.5546 202 ECU1 CrM HMPE 2424 log info verbose 1 T2424 updatePlayState mTargetPlayState = Idle, mPlayState = Stopped, oriState=Paused, updateState=0
8016638 2019/09/12 15:13:47.000000 5738.5581 245 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:waitFor() Wait for NULL
8016639 2019/09/12 15:13:47.000000 5738.5582 246 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:waitFor() Obtained the play state:[NULL] pending:[VOID_PENDING] ret = 1
8016640 2019/09/12 15:13:47.000000 5738.5582 203 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CURIPlayer::setNext() set play state to GST_STATE_NULL
8016641 2019/09/12 15:13:47.000000 5738.5582 247 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:waitFor() Wait for NULL
8016642 2019/09/12 15:13:47.000000 5738.5582 248 ECU1 CrM API 2424 log info verbose 1 T2633 CgstPlayerApi:waitFor() Obtained the play state:[NULL] pending:[VOID_PENDING] ret = 1


8794397 2019/09/12 15:24:06.000000 6357.5103 117 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794446 2019/09/12 15:24:06.000000 6357.5205 118 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794463 2019/09/12 15:24:06.000000 6357.5307 119 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794464 2019/09/12 15:24:06.000000 6357.5311 154 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794465 2019/09/12 15:24:06.000000 6357.5311 57 ECU1 CrM FILE 2424 log error verbose 1 T13112 read cover failed; /tmp/USB1-1.3-sdb1/Chris Daughtry- Im going home (2).mp3; Unknown
8794466 2019/09/12 15:24:06.000000 6357.5312 155 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794467 2019/09/12 15:24:06.000000 6357.5312 58 ECU1 CrM FILE 2424 log info verbose 1 T13112 read cover; /tmp/USB1-1.3-sdb1/Chris Daughtry- Im going home (2).mp3; Unknown; 0; -1; 0
8794468 2019/09/12 15:24:06.000000 6357.5312 156 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794469 2019/09/12 15:24:06.000000 6357.5312 157 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794470 2019/09/12 15:24:06.000000 6357.5312 105 ECU1 CrM MOSP 2424 log error verbose 1 T13112 MediaOneScannerDBAudio Mp3CoverReader::getMp3CoverReader failed, Unknown
8794471 2019/09/12 15:24:06.000000 6357.5313 158 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794472 2019/09/12 15:24:06.000000 6357.5313 159 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794473 2019/09/12 15:24:06.000000 6357.5314 59 ECU1 CrM FILE 2424 log info verbose 1 T13112 << readCover; /tmp/USB1-1.3-sdb1/Chris Daughtry- Im going home.mp3;
8794479 2019/09/12 15:24:06.000000 6357.5410 120 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794508 2019/09/12 15:24:06.000000 6357.5512 121 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794509 2019/09/12 15:24:06.000000 6357.5520 160 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794510 2019/09/12 15:24:06.000000 6357.5521 60 ECU1 CrM FILE 2424 log error verbose 1 T13112 read cover failed; /tmp/USB1-1.3-sdb1/Chris Daughtry- Im going home.mp3; Unknown
8794511 2019/09/12 15:24:06.000000 6357.5521 161 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794512 2019/09/12 15:24:06.000000 6357.5521 61 ECU1 CrM FILE 2424 log info verbose 1 T13112 read cover; /tmp/USB1-1.3-sdb1/Chris Daughtry- Im going home.mp3; Unknown; 0; -1; 0
8794513 2019/09/12 15:24:06.000000 6357.5521 162 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794514 2019/09/12 15:24:06.000000 6357.5521 163 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794515 2019/09/12 15:24:06.000000 6357.5522 106 ECU1 CrM MOSP 2424 log error verbose 1 T13112 MediaOneScannerDBAudio Mp3CoverReader::getMp3CoverReader failed, Unknown
8794516 2019/09/12 15:24:06.000000 6357.5522 164 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794517 2019/09/12 15:24:06.000000 6357.5522 165 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794519 2019/09/12 15:24:06.000000 6357.5523 62 ECU1 CrM FILE 2424 log info verbose 1 T13112 << readCover; /tmp/USB1-1.3-sdb1/CHUDIYAN_KHANAK_GAYIEN.MP3;
8794522 2019/09/12 15:24:06.000000 6357.5614 122 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794543 2019/09/12 15:24:06.000000 6357.5697 93 ECU1 CrM MP3C 2424 log error verbose 1 T13112 The file can not be read as ID3.
8794544 2019/09/12 15:24:06.000000 6357.5698 166 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794545 2019/09/12 15:24:06.000000 6357.5698 63 ECU1 CrM FILE 2424 log error verbose 1 T13112 read cover failed; /tmp/USB1-1.3-sdb1/CHUDIYAN_KHANAK_GAYIEN.MP3; Unknown
8794546 2019/09/12 15:24:06.000000 6357.5698 167 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794547 2019/09/12 15:24:06.000000 6357.5698 64 ECU1 CrM FILE 2424 log info verbose 1 T13112 read cover; /tmp/USB1-1.3-sdb1/CHUDIYAN_KHANAK_GAYIEN.MP3; Unknown; 0; -1; 0
8794548 2019/09/12 15:24:06.000000 6357.5699 168 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794549 2019/09/12 15:24:06.000000 6357.5699 169 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794550 2019/09/12 15:24:06.000000 6357.5699 107 ECU1 CrM MOSP 2424 log error verbose 1 T13112 MediaOneScannerDBAudio Mp3CoverReader::getMp3CoverReader failed, Unknown
8794551 2019/09/12 15:24:06.000000 6357.5699 170 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794552 2019/09/12 15:24:06.000000 6357.5699 171 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794565 2019/09/12 15:24:06.000000 6357.5716 123 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794579 2019/09/12 15:24:06.000000 6357.5754 65 ECU1 CrM FILE 2424 log info verbose 1 T13112 << readCover; /tmp/USB1-1.3-sdb1/Chura Ke Dil Mera (Main Khiladi Tu Anari) (2).mp3;
8794580 2019/09/12 15:24:06.000000 6357.5785 169 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x9ac2f8
8794581 2019/09/12 15:24:06.000000 6357.5787 170 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xa6dee3b0, pool 0x9ac2f8
8794582 2019/09/12 15:24:06.000000 6357.5788 124 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: vqueue, object type: GstTask, value: 0x350610
8794586 2019/09/12 15:24:06.000000 6357.5818 125 ECU1 CrM API 2424 log info verbose 1 T9198 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool139> alloc function failed
8794609 2019/09/12 15:24:06.000000 6357.5921 171 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0x9ac228
8794610 2019/09/12 15:24:06.000000 6357.5922 126 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: vpe, object type: GstTask, value: 0x387220
8794611 2019/09/12 15:24:06.000000 6357.5923 172 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xa90343b0, pool 0x9ac228
8794612 2019/09/12 15:24:06.000000 6357.5948 127 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool139> stop failed
8794613 2019/09/12 15:24:06.000000 6357.5975 128 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool139> stop failed
8794619 2019/09/12 15:24:06.000000 6357.5992 172 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794620 2019/09/12 15:24:06.000000 6357.5993 66 ECU1 CrM FILE 2424 log error verbose 1 T13112 read cover failed; /tmp/USB1-1.3-sdb1/Chura Ke Dil Mera (Main Khiladi Tu Anari) (2).mp3; Unknown
8794621 2019/09/12 15:24:06.000000 6357.5993 173 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794622 2019/09/12 15:24:06.000000 6357.5993 67 ECU1 CrM FILE 2424 log info verbose 1 T13112 read cover; /tmp/USB1-1.3-sdb1/Chura Ke Dil Mera (Main Khiladi Tu Anari) (2).mp3; Unknown; 0; -1; 0
8794623 2019/09/12 15:24:06.000000 6357.5993 174 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794624 2019/09/12 15:24:06.000000 6357.5994 175 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794625 2019/09/12 15:24:06.000000 6357.5994 108 ECU1 CrM MOSP 2424 log error verbose 1 T13112 MediaOneScannerDBAudio Mp3CoverReader::getMp3CoverReader failed, Unknown
8794626 2019/09/12 15:24:06.000000 6357.5994 176 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794627 2019/09/12 15:24:06.000000 6357.5994 177 ECU1 CrM CoMN 2424 log info verbose 1 T13112 HResult: Unknown
8794628 2019/09/12 15:24:06.000000 6357.6003 68 ECU1 CrM FILE 2424 log info verbose 1 T13112 << readCover; /tmp/USB1-1.3-sdb1/Chura Ke Dil Mera (Main Khiladi Tu Anari).mp3;
8794630 2019/09/12 15:24:06.000000 6357.6031 52 ECU1 CrM Glue 2424 log info verbose 1 T2656 [Default] BrowseControl processTimeoutThread: normal handled and remove time out cmd: 388, total:1
8794632 2019/09/12 15:24:06.000000 6357.6038 53 ECU1 CrM Glue 2424 log info verbose 1 T2656 [Default] BrowseControl processTimeoutThread: normal handled and remove time out cmd: 389, total:0
8794633 2019/09/12 15:24:06.000000 6357.6046 54 ECU1 CrM Glue 2424 log info verbose 1 T2656 [Default] BrowseControl processTimeoutThread: wating
8794639 2019/09/12 15:24:06.000000 6357.6056 129 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN ducati gstducatividdec.c(571):codec_process:<decoder> err=-1, extendedError=00040000
8794640 2019/09/12 15:24:06.000000 6357.6058 130 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs ERROR ducati gstducati.c(61):gst_ducati_log_extended_error_info: Bit 18 (00040000): stream end
8794641 2019/09/12 15:24:06.000000 6357.6078 131 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src_0", type: 2, owner: multiqueue69, object type: GstTask, value: 0x3876b8
8794642 2019/09/12 15:24:06.000000 6357.6079 132 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src_1", type: 2, owner: multiqueue69, object type: GstTask, value: 0x387568
8794643 2019/09/12 15:24:06.000000 6357.6081 173 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0xa52a80
8794644 2019/09/12 15:24:06.000000 6357.6084 174 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xac8fe3b0, pool 0xa52a80
8794645 2019/09/12 15:24:06.000000 6357.6086 175 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining realtime pool 0xa645d0
8794646 2019/09/12 15:24:06.000000 6357.6085 133 ECU1 CrM API 2424 log debug verbose 1 T2424 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "sink", type: 2, owner: typefind, object type: GstTask, value: 0x3500d0
8794647 2019/09/12 15:24:06.000000 6357.6089 176 ECU1 CrM HMPE 2424 log info verbose 1 T2633 GstP: joining thread success, threadid=0xaad5b3b0, pool 0xa645d0


15289587 2019/09/12 16:26:06.000000 476.5568 129 ECU1 CrM CoMN 2435 log info verbose 1 T2567 CHandlerQueue::addEvent new NORMAL event [handleStop][1730]
15289588 2019/09/12 16:26:06.000000 476.5569 130 ECU1 CrM CoMN 2435 log info verbose 1 T2557 CEventHandlerQueueSample[Default]::processing NORMAL event [handleStop] [1730]
15289589 2019/09/12 16:26:06.000000 476.5570 197 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] << stop Version: 19371 Engine: gstreamer
15289590 2019/09/12 16:26:06.000000 476.5570 198 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] << dequeueAll
15289591 2019/09/12 16:26:06.000000 476.5570 199 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] >> setCurrentMediumIdAttribute: value=0
15289592 2019/09/12 16:26:06.000000 476.5572 200 ECU1 CrM Glue 2435 log info verbose 1 T2557 Clearing metadata due to the playback has been stopped by request.
15289593 2019/09/12 16:26:06.000000 476.5572 201 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] >> setMetadataAttribute: value={please find the details in previous logs}
15289594 2019/09/12 16:26:06.000000 476.5573 202 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] >> setPreMetadataAttribute: value={please find the details in previous logs}
15289595 2019/09/12 16:26:06.000000 476.5573 203 ECU1 CrM Glue 2435 log  verbose 1 T2557 PlayerIf[Default] >> setNextMetadataAttribute: value={please find the details in previous logs}
15289596 2019/09/12 16:26:06.000000 476.5574 43 ECU1 CrM PlEg 2435 log  verbose 1 T2557 PlayEngine[Default] selectMedium[0], Type:[DT_NULL]
15289597 2019/09/12 16:26:06.000000 476.5574 44 ECU1 CrM PlEg 2435 log  verbose 1 T2557 PlayEngine[Default] << activatePlayer(PT_NULL)
15289598 2019/09/12 16:26:06.000000 476.5574 45 ECU1 CrM PlEg 2435 log debug verbose 1 T2557 PlayEngine: Stop the current player first.
15289599 2019/09/12 16:26:06.000000 476.5574 52 ECU1 CrM HMPE 2435 log  verbose 1 T2557 GSTPlayer[Default] << stop
15289600 2019/09/12 16:26:06.000000 476.5574 53 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CURIPlayer::updateFileLastmode. current play state: Paused
15289601 2019/09/12 16:26:06.000000 476.5575 54 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CURIPlayer::updateFileLastmode: Try update current file last mode.
15289602 2019/09/12 16:26:06.000000 476.5575 56 ECU1 CrM Vugs 2435 log  verbose 1 T2557 PlayView[CView] << getCurrentPos
15289603 2019/09/12 16:26:06.000000 476.5575 57 ECU1 CrM Vugs 2435 log  verbose 1 T2557 PlayView[CView] >> getCurrentPos: index=78
15289604 2019/09/12 16:26:06.000000 476.5575 58 ECU1 CrM Vugs 2435 log  verbose 1 T2557 PlayView[CView] << getItem: index=78
15289605 2019/09/12 16:26:06.000000 476.5575 55 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CURIPlayer::updateFileLastmode: Has updated item to playlist. itemid:1234; play position:127733.
15289606 2019/09/12 16:26:06.000000 476.5576 56 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CURIPlayer::commitFileState(void): start update file state. row 914
15289607 2019/09/12 16:26:06.000000 476.5576 57 ECU1 CrM HMPE 2435 log info verbose 1 T2557 Try to update file state; 1234; 127733.
15289608 2019/09/12 16:26:06.000000 476.5576 58 ECU1 CrM HMPE 2435 log info verbose 1 T2557 File: CgstBasePlayer. Function: updateDataBase. row: 1441. update file state to database. 1234; 1; 127733
15289609 2019/09/12 16:26:06.000000 476.5577 90 ECU1 CrM DBES 2435 log info verbose 1 T2557 sqlite execute executeSQL::sqlite3_exec [ 77 ]us, stmt=0x0, sql={ select 1; }
15289610 2019/09/12 16:26:06.000000 476.5577 240 ECU1 CrM DBEC 2435 log info verbose 1 T2557 setAbortHandler: abortHandler = 0x143e4c
15289611 2019/09/12 16:26:06.000000 476.5578 91 ECU1 CrM DBES 2435 log info verbose 1 T2557 setAbortHandler: set abortHandler = 0x143e4c
15289612 2019/09/12 16:26:06.000000 476.5580 92 ECU1 CrM DBES 2435 log info verbose 1 T2557 sqlite execute sqlite3_prepare_v2 [ 75 ]us, stmt=0x3bfb98, sql={ UPDATE files SET playable = ? , lastplaypos = ? WHERE id = ? }
15289613 2019/09/12 16:26:06.000000 476.5586 59 ECU1 CrM HMPE 2435 log info verbose 1 T2557 File: CgstBasePlayer. Function: updateDataBase. row: 1504. execute: succeed.
15289614 2019/09/12 16:26:06.000000 476.5586 60 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CURIPlayer::commitFileState: over update file state. row 947
15289615 2019/09/12 16:26:06.000000 476.6587 127 ECU1 CrM API 2435 log info verbose 1 T2557 CgstPlayerApi:stopAsync() set state to stop
15289616 2019/09/12 16:26:06.000000 476.6637 61 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining realtime pool 0x608100
15289617 2019/09/12 16:26:06.000000 476.6640 62 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining thread success, threadid=0xa60fe3b0, pool 0x608100
15289618 2019/09/12 16:26:06.000000 476.6668 128 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289619 2019/09/12 16:26:06.000000 476.6775 129 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289620 2019/09/12 16:26:06.000000 476.6877 130 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289621 2019/09/12 16:26:06.000000 476.6979 131 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289622 2019/09/12 16:26:06.000000 476.7081 132 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289623 2019/09/12 16:26:06.000000 476.7183 133 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289624 2019/09/12 16:26:06.000000 476.7250 134 ECU1 CrM API 2435 log debug verbose 1 T2435 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "audiosinkringbuffer6", type: 2, owner: asink, object type: GThread, value: (nil)
15289625 2019/09/12 16:26:06.000000 476.7284 135 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289626 2019/09/12 16:26:06.000000 476.7386 136 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289627 2019/09/12 16:26:06.000000 476.7488 137 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289628 2019/09/12 16:26:06.000000 476.7546 138 ECU1 CrM API 2435 log debug verbose 1 T2435 CgstPlayerApi::gstBusCallback()(default) Got stream-status message from Element "src", type: 2, owner: aqueue, object type: GstTask, value: 0xb25ef978
15289629 2019/09/12 16:26:06.000000 476.7590 139 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289630 2019/09/12 16:26:06.000000 476.7692 140 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289631 2019/09/12 16:26:06.000000 476.7794 141 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289632 2019/09/12 16:26:06.000000 476.7897 142 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289633 2019/09/12 16:26:06.000000 476.7999 143 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289634 2019/09/12 16:26:06.000000 476.8102 144 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289635 2019/09/12 16:26:06.000000 476.8205 145 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289636 2019/09/12 16:26:06.000000 476.8306 146 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289637 2019/09/12 16:26:06.000000 476.8408 147 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289638 2019/09/12 16:26:06.000000 476.8510 148 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289639 2019/09/12 16:26:06.000000 476.8612 149 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289640 2019/09/12 16:26:06.000000 476.8714 150 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289641 2019/09/12 16:26:06.000000 476.8816 151 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289642 2019/09/12 16:26:06.000000 476.8919 152 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289643 2019/09/12 16:26:06.000000 476.9021 153 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289644 2019/09/12 16:26:06.000000 476.9123 154 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289645 2019/09/12 16:26:06.000000 476.9225 155 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289646 2019/09/12 16:26:06.000000 476.9327 156 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289647 2019/09/12 16:26:06.000000 476.9429 157 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289648 2019/09/12 16:26:06.000000 476.9531 158 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289649 2019/09/12 16:26:06.000000 476.9633 159 ECU1 CrM API 2435 log info verbose 1 T4428 GSTLogs WARN bufferpool gstbufferpool.c(300):do_alloc_buffer:<vpebufferpool11> alloc function failed
15289650 2019/09/12 16:26:06.000000 476.9679 63 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining realtime pool 0x608098
15289651 2019/09/12 16:26:06.000000 476.9682 64 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining thread success, threadid=0xa68fe3b0, pool 0x608098
15289652 2019/09/12 16:26:06.000000 476.9767 65 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining realtime pool 0x4aeb78
15289653 2019/09/12 16:26:06.000000 476.9770 66 ECU1 CrM HMPE 2435 log info verbose 1 T2557 GstP: joining thread success, threadid=0xa88fe3b0, pool 0x4aeb78
15289654 2019/09/12 16:26:06.000000 476.9781 160 ECU1 CrM API 2435 log info verbose 1 T2557 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool11> stop failed
15289655 2019/09/12 16:26:06.000000 476.9785 161 ECU1 CrM API 2435 log info verbose 1 T2557 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool11> stop failed

We see a lot of "<vpebufferpool131> stop failed" in the log when play next videos, whether that is the root cause? Or any other root cause from IT point of view. Thanks.

More detail from Media POV:
1. Here, stop "Eric Prydz - Pjanoo (Official Music Video).mp4", and "stop failed" error from vpebufferpool reported.
7995832 2019/09/12 15:13:31.000000 5723.0436 200 ECU1 CrM Glue 2424 log info verbose 1 T7895 Metadata=DisplayName=Eric Prydz - Pjanoo (Official Music Video),Path=/tmp/USB1-1.2-sda1/New folder/Vedio song/Eric Prydz - Pjanoo (Official Music Video).mp4,
7996910 2019/09/12 15:13:32.000000 5723.7018 112 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool127> stop failed
7996911 2019/09/12 15:13:32.000000 5723.7029 113 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool127> stop failed

2. Here, stop "FFTitle.mp4", and "stop failed" error from vpebufferpool reported.
8000385 2019/09/12 15:13:34.000000 5726.0915 155 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CgstBasePlayer::playFile. found last play position 0; /tmp/USB1-1.2-sda1/FormatFactory/FFModules/Sample/Video/FFTitle.mp4
8000337 2019/09/12 15:13:34.000000 5726.0412 123 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool129> stop failed
8000338 2019/09/12 15:13:34.000000 5726.0424 124 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool129> stop failed

3. Here, stop "Ford V_S Ford _ Shivjot _ Full Official Video _ Yaar Anmulle Records _ 2014.mp4", and "stop failed" error from vpebufferpool reported.
8002957 2019/09/12 15:13:36.000000 5727.6383 14 ECU1 CrM HMPE 2424 log info verbose 1 T2633 CgstBasePlayer::playFile. found last play position 0; /tmp/USB1-1.2-sda1/New folder/Vedio song/Ford V_S Ford _ Shivjot _ Full Official Video _ Yaar Anmulle Records _ 2014.mp4
8002838 2019/09/12 15:13:36.000000 5727.5690 111 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool131> stop failed
8002862 2019/09/12 15:13:36.000000 5727.5756 112 ECU1 CrM API 2424 log info verbose 1 T2633 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool131> stop failed

......
Here are so many "stop failed" error from vpebufferpool reported.

5. At last, we stop "Katy Perry - Dark Horse (Official) ft. Juicy J_x264.mp4", and the T2557 thread got stuck, issue happened.
14656675 2019/09/12 16:22:32.000000 263.9709 108 ECU1 CrM HMPE 2435 log info verbose 1 T2557 CgstBasePlayer::play. found last play position; id:1234; last play position:0; path:/tmp/USB1-1.2-sda1/Katy Perry - Dark Horse (Official) ft. Juicy J_x264.mp4
15289654 2019/09/12 16:26:06.000000 476.9781 160 ECU1 CrM API 2435 log info verbose 1 T2557 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool11> stop failed
15289655 2019/09/12 16:26:06.000000 476.9785 161 ECU1 CrM API 2435 log info verbose 1 T2557 GSTLogs WARN bufferpool gstbufferpool.c(537):gst_buffer_pool_set_active:<vpebufferpool11> stop failed