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