[000:143] [2025-01-11 15:51:15 +0000.
776556][19e8] Log to
file:/storage/emulated/0/voip-data/com.android.camera/trace.txt start time:2025-01-
11 15:51:15 +0000
[000:143] [2025-01-11 15:51:15 +0000.776664][19e8] set AppID=50011 old AppID=-1
[000:144] [2025-01-11 15:51:15 +0000.776696][19e8] Init the android object with
jvm:0xb400006ffd221380 env:0x6fdfa39b40 context:0x6f890cb328
[000:144] [2025-01-11 15:51:15 +0000.776742][19e8] SetAndroidMediaCodecObjects
time:115892251 enter.
[000:144] [2025-01-11 15:51:15 +0000.776777][19e8] SetAndroidMediaCodecObjects
leave. expire time = 0 ms
[000:144] [2025-01-11 15:51:15 +0000.776822][19e8] SetAndroidMediaCodecObjects
time:115892251 enter.
[000:144] [2025-01-11 15:51:15 +0000.776862][19e8] SetAndroidMediaCodecObjects:
SetAndroidMediaCodecObjects:: jvm :0xb400006ffd221380
[000:144] [2025-01-11 15:51:15 +0000.776888][19e8] SetAndroidMediaCodecObjects:
SetAndroidMediaCodecObjects success g_jvm_:0xb400006ffd221380 address:0x6f2902e760
[000:144] [2025-01-11 15:51:15 +0000.776926][19e8] SetAndroidMediaCodecObjects:
SetAndroidMediaCodecObjects success g_jvm_encoder_:0xb400006ffd221380
address:0x6f2902e760
[000:144] [2025-01-11 15:51:15 +0000.776967][19e8] SetAndroidMediaCodecObjects
leave. expire time = 0 ms
[000:144] [2025-01-11 15:51:15 +0000.777009][19e8] Set the android objects with
jvm:0xb400006ffd221380 env:0x6fdfa39b40 context:0x6f890cb328
[000:147] [2025-01-11 15:51:15 +0000.779846][19e8] audio jni debug :manager
SetAndroidAudioDeviceObjects env:0x6fdfa39b40
[000:147] [2025-01-11 15:51:15 +0000.779922][19e8] SystemUtil Init done
[000:149] [2025-01-11 15:51:15 +0000.782356][19e8]
Java_com_xiaomi_recordmediaprocess_MediaEffectCamera_ConstructMediaEffectCameraJni:
[000:153] [2025-01-11 15:51:15 +0000.786649][19e8] MediaFilterCamera
[000:154] [2025-01-11 15:51:15 +0000.786748][19e8] RecordingSessionInternalSimple:
Create session internal:720 X 1280 fps:30
[000:154] [2025-01-11 15:51:15 +0000.786909][19e8] createSessionInternal: create
session with video size:720 X 1280 fps:30 bitrate:14680064
[000:154] [2025-01-11 15:51:15 +0000.786960][19e8] Recording session using default
bitrate support by app:14680064
[000:154] [2025-01-11 15:51:15 +0000.786946][2ce6] Error(thread.cc:348): Set the
pthread name failed with error:34 name:Thread 0x0x6f639a7ac0
[000:154] [2025-01-11 15:51:15 +0000.787181][2ce6] setAudioChannelCount_w:
RecordingSessionInternalSimple:set the audio channel count:2
[000:154] [2025-01-11 15:51:15 +0000.787265][19e8] setAudioSampleRate:
RecordingSessionInternalSimple:set the audio bitrate:44100
[000:155] [2025-01-11 15:51:15 +0000.788036][19e8] Error(ini.cc:191): Open the file
failed. error:2
[000:155] [2025-01-11 15:51:15 +0000.788081][19e8]
Error(key_value_pair_store.cc:130): load the data failed from: voip_config.ini
[000:155] [2025-01-11 15:51:15 +0000.788122][19e8] The device model: 220333qag
usingSoftwareAEC value: 0
[000:157] [2025-01-11 15:51:15 +0000.790669][19e8] Error(ini.cc:191): Open the file
failed. error:2
[000:158] [2025-01-11 15:51:15 +0000.790757][19e8]
Error(key_value_pair_store.cc:130): load the data failed from:
/storage/emulated/0/voip-data/com.android.camera/voip_config.ini
[000:158] [2025-01-11 15:51:15 +0000.790797][19e8] The device model: 220333qag
usingHardwareAEC value: 0
[000:158] [2025-01-11 15:51:15 +0000.790822][19e8] The machine device: 220333qag
usingHardwareAEC value: 0
[000:158] [2025-01-11 15:51:15 +0000.790871][19e8] Using Default AEC type: software
AEC
[000:159] [2025-01-11 15:51:15 +0000.791962][19e8] The device model: 220333qag enum
value: 0
[000:159] [2025-01-11 15:51:15 +0000.792588][19e8] GetMachineModel is 220333QAG
[000:159] [2025-01-11 15:51:15 +0000.792664][19e8] GetMachineInfo: 220333QAG
[000:160] [2025-01-11 15:51:15 +0000.792717][19e8] Construct BeautifyReader
begin...
[000:160] [2025-01-11 15:51:15 +0000.792756][19e8] dumpStickerRgba:0
dumpStickerYuv:0
[000:160] [2025-01-11 15:51:15 +0000.792817][19e8] Construct BeautifyReader done.
[000:162] [2025-01-11 15:51:15 +0000.794809][19e8] Error(ini.cc:191): Open the file
failed. error:2
[000:162] [2025-01-11 15:51:15 +0000.794893][19e8]
Error(key_value_pair_store.cc:130): load the data failed from:
/storage/emulated/0/voip-data/com.android.camera/support_resolution.txt
[000:162] [2025-01-11 15:51:15 +0000.795029][19e8] AppDeviceManagerStartCamera::The
result of start thread is: 1 micOn:0
[000:162] [2025-01-11 15:51:15 +0000.795067][19e8] AppDeviceManager telnet server
for statistics started......
[000:162] [2025-01-11 15:51:15 +0000.795064][2ce9] Error(thread.cc:348): Set the
pthread name failed with error:34 name:Thread 0x0x6f42efb7a0
[000:162] [2025-01-11 15:51:15 +0000.795537][19e8] CpuOverloadDetect time:115892269
enter.
[000:162] [2025-01-11 15:51:15 +0000.795633][19e8] CpuOverloadDetect   leave. expire
time = 0 ms
[000:163] [2025-01-11 15:51:15 +0000.795718][2cea] Error(thread.cc:348): Set the
pthread name failed with error:34 name:Thread 0x0x6fec68bbc0
[000:163] [2025-01-11 15:51:15 +0000.796284][19e8] AppDeviceMana
GetOpenslVoiceMode before get setting: 1
[000:163] [2025-01-11 15:51:15 +0000.796320][19e8] AppDeviceMana
GetOpenslVoiceMode after get setting: 1
[000:164] [2025-01-11 15:51:15 +0000.796753][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_SAMPLE_RATE
[000:164] [2025-01-11 15:51:15 +0000.797084][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_FRAMES_PER_BUFFER
[000:164] [2025-01-11 15:51:15 +0000.797599][19e8] CreatePlatformSpecificObjects:
[000:164] [2025-01-11 15:51:15 +0000.797652][19e8] audio device use opensle input
and opensl output
[000:165] [2025-01-11 15:51:15 +0000.797735][19e8] Create OpenSLES output module
with java VM support.
[000:165] [2025-01-11 15:51:15 +0000.797906][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_SAMPLE_RATE
[000:165] [2025-01-11 15:51:15 +0000.798065][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_FRAMES_PER_BUFFER
[000:165] [2025-01-11 15:51:15 +0000.798246][19e8] Create OpenSLES output module
with buffer num:1
[000:165] [2025-01-11 15:51:15 +0000.798405][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_SAMPLE_RATE
[000:165] [2025-01-11 15:51:15 +0000.798539][19e8] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_FRAMES_PER_BUFFER
[000:166] [2025-01-11 15:51:15 +0000.798693][19e8] Create OpenSLES input module
with java VM support.
[000:166] [2025-01-11 15:51:15 +0000.798734][19e8] Create OpenSLES input module
with buffer num:1
[000:166] [2025-01-11 15:51:15 +0000.798813][19e8] Error(trace.cc:40): Init the
output and input for audio device template.
[000:166] [2025-01-11 15:51:15 +0000.798855][19e8] Init the output device.
[000:166] [2025-01-11     15:51:15 +0000.798891][19e8] Init the opensl input.
[000:166] [2025-01-11     15:51:15 +0000.798936][19e8] AppDeviceManager get setting
samplerate: 0
[000:166] [2025-01-11     15:51:15 +0000.799343][19e8] The device model: 220333qag enum
value: 0
[000:167] [2025-01-11     15:51:15 +0000.799948][19e8] The device model: 220333qag enum
value: 0
[000:167] [2025-01-11     15:51:15 +0000.799986][19e8] Set the sample rate to be:48000
[000:167] [2025-01-11     15:51:15   +0000.800020][19e8]   Enable the recording stereo:0
[000:167] [2025-01-11     15:51:15   +0000.800058][19e8]   Set the playout as stereo:1
[000:167] [2025-01-11     15:51:15   +0000.800092][19e8]   Init the microphone.
[000:167] [2025-01-11     15:51:15   +0000.800558][19e8]   Update the recording sample rate
to be: 48000
[000:167] [2025-01-11     15:51:15 +0000.800602][19e8] Init the speaker.
[000:167] [2025-01-11     15:51:15 +0000.800644][19e8] Low latency buffer size:144 low
sample rate:48000
[000:168] [2025-01-11     15:51:15 +0000.800922][19e8] Using 1 x 10ms for an audio
frame.
[000:171]   [2025-01-11   15:51:15 +0000.804243][19e8] Error(splitting_filter.cc:26):
Splitting   filter only   support two or three bands now.
[000:171]   [2025-01-11   15:51:15 +0000.804406][19e8] Error(splitting_filter.cc:26):
Splitting   filter only   support two or three bands now.
[000:171]   [2025-01-11   15:51:15 +0000.804461][19e8] Error(splitting_filter.cc:26):
Splitting   filter only   support two or three bands now.
[000:176]   [2025-01-11   15:51:15 +0000.809261][19e8] The device model:220333qag
[000:176] [2025-01-11 15:51:15 +0000.809341][19e8] speaker output factor:100
defaultFactor: 100
[000:176] [2025-01-11 15:51:15 +0000.809379][19e8] headset output factor:100
defaultFactor: 100
[000:177]   [2025-01-11 15:51:15 +0000.809898][19e8] AppDeviceManagerStart finished.
[000:177]   [2025-01-11 15:51:15 +0000.809950][19e8] AppDeviceManagerStartMic::mic
status is   off
[000:177]   [2025-01-11 15:51:15 +0000.810014][19e8] AppDeviceManagerStartMic update
aec type:   2
[000:177] [2025-01-11 15:51:15 +0000.810052][19e8] AppDeviceManagerStartMic update
voiceChangeMode: 4
[000:177] [2025-01-11 15:51:15 +0000.810097][19e8]
AudioDeviceDataHandler::setVoiceChangeMode, mode : 4
[000:177] [2025-01-11 15:51:15 +0000.810138][19e8]
AudioDeviceDataHandler::StartMicProcess
[000:177] [2025-01-11 15:51:15 +0000.810262][19e8] Try to start webrtc recording
device.
[000:177] [2025-01-11 15:51:15 +0000.810307][19e8] StartRecording. threadID:6632
[000:177] [2025-01-11 15:51:15 +0000.810314][2ceb] Error(thread.cc:348): Set the
pthread name failed with error:34 name:AudioDeviceDataHandler gapProcessThread
[000:177] [2025-01-11 15:51:15 +0000.810395][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[000:178] [2025-01-11 15:51:15 +0000.810708][19e8] The machine model:220333QAG
[000:178] [2025-01-11 15:51:15 +0000.810778][19e8] default mode: Set the recording
mode to voice communication to enable HW AEC .
[000:178] [2025-01-11 15:51:15 +0000.810813][19e8] Set the recording mode before
getSetting: 4
[000:178] [2025-01-11 15:51:15 +0000.810843][19e8] Set the recording mode after
getSetting: 1
[000:225] [2025-01-11 15:51:15 +0000.858180][19e8] StartRecording finished.
[000:225] [2025-01-11 15:51:15 +0000.858272][19e8] audio: start the audio mic
checker timer.
[000:226] [2025-01-11 15:51:15 +0000.858895][19e8] machine model :redmi
[000:226] [2025-01-11 15:51:15 +0000.858958][19e8] Support this machine
model :redmi
[000:232] [2025-01-11 15:51:15 +0000.864925][19e8] global inited! current sdk
version: 12-14-d8558ab
[000:298] [2025-01-11 15:51:15 +0000.930820][2cec] webrtc device:Handle the data
from samplesPerSec recording sample rate:48000 nSamples:480 nBytesPerSample:#
nChannels:#
[000:298] [2025-01-11 15:51:15 +0000.930924][2cec] _deviceSampleRate:48000
[000:300] [2025-01-11 15:51:15 +0000.932747][2ceb] Run AGC when audioType = 0
[000:300] [2025-01-11 15:51:15 +0000.932847][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[000:676] [2025-01-11 15:51:16 +0000.309496][2b95]
Java_com_xiaomi_recordmediaprocess_OpenGlRender_SetCurrentGLContextJni:
[000:676] [2025-01-11 15:51:16 +0000.309730][2ce7] MediaFilterCamera, recording
file context:0x6fec648b80 input texture:12
[000:677] [2025-01-11 15:51:16 +0000.309790][2ce7] GLESContext:
[000:677] [2025-01-11 15:51:16 +0000.309850][2ce7] The result of eglGetDisplay is:
0x1
[000:678] [2025-01-11 15:51:16 +0000.310939][2ce7] The result of creation:
0x6f50e79e80
[000:678] [2025-01-11 15:51:16 +0000.311038][2ce7] The result of
eglCreatePbufferSurface is: 0x6f335e7a00
[000:678] [2025-01-11 15:51:16 +0000.311137][2b95]
Java_com_xiaomi_recordmediaprocess_OpenGlRender_SetCurrentGLContextJni:
g_eglcontext: 0x6fec648b80 input_texture: 12
[001:923] [2025-01-11 15:51:17 +0000.556434][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[002:297] [2025-01-11 15:51:17 +0000.929965][2ceb] Run AGC when audioType = 0
[003:640] [2025-01-11 15:51:19 +0000.273380][2ceb] haveEnoughProcessedData
internalAudioStream.length: 10
[004:230] [2025-01-11 15:51:19 +0000.862726][2ce1]
monitorTime:4005totalDuration:3940
[004:298] [2025-01-11 15:51:19 +0000.931282][2ceb] Run AGC when audioType = 0
[004:298] [2025-01-11 15:51:19 +0000.931452][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[005:377] [2025-01-11 15:51:21 +0000.10095][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[006:300] [2025-01-11 15:51:21 +0000.933246][2ceb] Run AGC when audioType = 0
[007:110] [2025-01-11 15:51:22 +0000.743323][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[007:969] [2025-01-11 15:51:23 +0000.602470][1972] SetOrientation:
MediaFilterCamera:: SetOrientation: 90
[007:969] [2025-01-11 15:51:23 +0000.602587][1972]
Java_com_xiaomi_recordmediaprocess_MediaEffectCamera_SetOrientationJni: media
filter camera orientation: 90
[007:970] [2025-01-11 15:51:23 +0000.603653][1972]
Java_com_xiaomi_recordmediaprocess_MediaEffectCamera_StartRecordingJni:
[007:971] [2025-01-11 15:51:23 +0000.603867][2ce7] operator() time:115900078
enter.
[007:971] [2025-01-11 15:51:23 +0000.603968][2ce7] MediaFilterCamera, recording
file context:0x6fec648b80 input texture:12
[007:973] [2025-01-11 15:51:23 +0000.606312][2ce7] MicSource: Mic source
created:10x6fdfb16c80
[007:974] [2025-01-11 15:51:23 +0000.607375][2ce7] received external audio stream
[007:974] [2025-01-11 15:51:23 +0000.607515][2ce7] alloc thread: 0x6f509cea00
name:worker2 thread id:477118721216 to Mic_source
[007:974] [2025-01-11 15:51:23 +0000.607577][2ce7] audio: start the audio sender
thread.
[007:975] [2025-01-11 15:51:23   +0000.607705][2ce7] construct OpenGLRenderProgram
0x6f3383f198
[007:975] [2025-01-11 15:51:23   +0000.607757][2ce7]   CropFilter::SetOrientation
orientation: 90
[007:975] [2025-01-11 15:51:23   +0000.608057][2ce7] filter: filter pipeline
construtc this: 0x6fed3e2e40
[007:975] [2025-01-11 15:51:23   +0000.608114][2ce7] filter: filter pipeline2 update
pattern: 1
[007:975] [2025-01-11 15:51:23   +0000.608242][2ce7] CameraSource::source_id_:1
[007:975] [2025-01-11 15:51:23   +0000.608294][2ce7] RecordAudioSource::source_id_:2
[007:975] [2025-01-11 15:51:23   +0000.608640][2ce7] BindInputFilter 0x6f50b63240
this 0x6f3383f3c0
[007:976] [2025-01-11 15:51:23   +0000.608732][2ce7]   BindInputFilter 0x6f50b63fd8
this 0x6f50ba4450
[007:976] [2025-01-11 15:51:23   +0000.608795][2ce7]   BindInputFilter 0x6f3383f3c0
this 0x6fed3e2ee0
[007:976] [2025-01-11 15:51:23   +0000.608896][2ce7] setParamentForFilters:
speed:0.500000
[007:976] [2025-01-11 15:51:23   +0000.608946][2ce7] SetParamForFilter:
AF_SpeedFilter params:, speed_   0.5
[007:976] [2025-01-11 15:51:23   +0000.608997][2ce7] SetParamForFilter time:115900083
enter.
[007:976] [2025-01-11 15:51:23 +0000.609054][2ce7] Error(crop_filter.cc:263):
SetParamForFilter: Not found modetype param, using default modetype citytravel!
[007:976] [2025-01-11 15:51:23 +0000.609115][2ce7] SetParamForFilter: Params video
index is: 0
[007:976] [2025-01-11 15:51:23 +0000.609171][2ce7] Error(crop_filter.cc:283):
SetParamForFilter: PhotoFilter params, donnot find index
[007:976] [2025-01-11 15:51:23 +0000.609226][2ce7] Error(crop_filter.cc:291):
SetParamForFilter: PhotoFilter params, donnot find intensity
[007:976] [2025-01-11 15:51:23 +0000.609282][2ce7] crop filter mode tpye:
citytravelvideo index:0
[007:983] [2025-01-11 15:51:23 +0000.616549][2ceb] On audio frame the broadcast
with timestamp:115900090 samples:480. (1000 frame for one log)
[007:985] [2025-01-11 15:51:23 +0000.617864][2d14] Get data in mic source, which
has byte length:1764micAudioStream->length():10
[007:985] [2025-01-11 15:51:23 +0000.618534][2d14] The output timestamp from audio
mixer change:115900090
[007:985] [2025-01-11 15:51:23 +0000.618628][2d14] Error(MicSource.cpp:254): There
is no output connect to mic source, discard the frame.
[007:991] [2025-01-11 15:51:23 +0000.623950][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624074][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624121][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624164][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624211][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624267][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624323][2ce7]
Warning(parse_template_json.cc:45): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624432][2ce7]
Warning(parse_template_json.cc:81): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624490][2ce7]
Warning(parse_template_json.cc:81): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624546][2ce7]
Warning(parse_template_json.cc:67): json parament no found the parament
[007:991] [2025-01-11 15:51:23 +0000.624601][2ce7]
Warning(parse_template_json.cc:93): json parament no found the duration
[007:991] [2025-01-11 15:51:23 +0000.624667][2ce7] SetParamForFilter leave. expire
time = 16 ms
[007:992] [2025-01-11 15:51:23 +0000.625022][2ce7] OnOutputSizeChanged width 1280
height 720
[007:992] [2025-01-11 15:51:23 +0000.625112][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625185][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625261][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625325][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625380][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625436][2ce7] OpenGLFrameBuffer: width:1280
height:720
[007:992] [2025-01-11 15:51:23 +0000.625494][2ce7] construct TrivalImageDrawer
0x6fed215640
[007:992] [2025-01-11 15:51:23 +0000.625542][2ce7] GPUImageProcessing construct:
frame resolution is: 1280 X 720 drawer 0x6fed215640 context 0x6f3369ac00
[007:992] [2025-01-11 15:51:23 +0000.625601][2ce7] setupGLES:
[007:993] [2025-01-11 15:51:23 +0000.626491][2ce7] OpenGLRenderProgram::Initialize.
[007:993] [2025-01-11 15:51:23 +0000.626579][2ce7] The version of the OpenGL:
OpenGL ES GLSL ES 3.20
[007:993] [2025-01-11 15:51:23 +0000.626640][2ce7] The extension of the OpenGL:
GL_OES_EGL_image GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_half_float
GL_OES_framebuffer_object GL_OES_rgb8_rgba8 GL_OES_compressed_ETC1_RGB8_texture
GL_AMD_compressed_ATC_texture GL_KHR_texture_compression_astc_ldr
GL_OES_texture_npot GL_EXT_texture_filter_anisotropic
GL_EXT_texture_format_BGRA8888 GL_EXT_read_format_bgra GL_OES_texture_3D
GL_EXT_color_buffer_float GL_EXT_color_buffer_half_float GL_QCOM_alpha_test
GL_OES_depth24 GL_OES_packed_depth_stencil GL_OES_depth_texture
GL_OES_depth_texture_cube_map GL_EXT_sRGB GL_OES_texture_float
GL_OES_texture_float_linear GL_OES_texture_half_float
GL_OES_texture_half_float_linear GL_EXT_texture_type_2_10_10_10_REV
GL_EXT_texture_sRGB_decode GL_EXT_texture_format_sRGB_override
GL_OES_element_index_uint GL_EXT_copy_image GL_EXT_geometry_shader
GL_EXT_tessellation_shader GL_OES_texture_stencil8 GL_EXT_shader_io_blocks
GL_OES_shader_image_atomic GL_OES_sample_variables GL_EXT_texture_border_clamp
GL_EXT_EGL_image_external_wrap_modes GL_EXT_multisampled_render_to_texture
GL_EXT_multisampled_render_to_texture2 GL_OES_shader_multisample_interpolation
GL_EXT_texture_cube_map_array GL_EXT_draw_buffers_indexed GL_EXT_gpu_shader5
GL_EXT_robustness GL_EXT_texture_buffer GL_EXT_shader_framebuffer_fetch
GL_ARM_shader_framebuffer_fetch_depth_stencil
GL_OES_texture_storage_multisample_2d_array GL_OES_sample_shading
GL_OES_get_program_binary GL_EXT_debug_label GL_KHR_blend_equation_advanced
GL_KHR_blend_equation_advanced_coherent GL_QCOM_tiled_rendering
GL_ANDROID_extension_pack_es31a GL_EXT_primitive_bounding_box
GL_OES_standard_derivatives GL_OES_vertex_array_object GL_EXT_disjoint_timer_query
GL_KHR_debug GL_EXT_YUV_target GL_EXT_sRGB_write_control GL_EXT_texture_norm16
GL_EXT_discard_framebuffer GL_OES_surfaceless_context GL_OVR_multiview
GL_OVR_multiview2 GL_EXT_texture_sRGB_R8 GL_KHR_no_error GL_EXT_debug_marker
GL_OES_EGL_image_external_essl3 GL_OVR_multiview_multisampled_render_to_texture
GL_EXT_buffer_storage GL_EXT_external_buffer GL_EXT_blit_framebuffer_params
GL_EXT_clip_cull_distance GL_EXT_protected_textures
GL_EXT_shader_non_constant_global_initializers GL_QCOM_texture_foveated
GL_QCOM_texture_foveated_subsampled_layout
GL_QCOM_shader_framebuffer_fetch_noncoherent GL_QCOM_shader_framebuffer_fetch_rate
GL_EXT_memory_object GL_EXT_memory_object_fd GL_EXT_EGL_image_array
GL_NV_shader_noperspective_interpolation GL_KHR_robust_buffer_access_behavior
GL_EXT_EGL_image_storage GL_EXT_blend_func_extended GL_EXT_clip_control
GL_OES_texture_view GL_EXT_fragment_invocation_density
GL_QCOM_validate_shader_binary GL_QCOM_YUV_texture_gather
[007:994] [2025-01-11 15:51:23 +0000.626754][2ce7] Init open gl render:
0x6f3383f198
[007:994] [2025-01-11 15:51:23 +0000.626831][2ce7] Fragement shader path:
[007:994] [2025-01-11 15:51:23 +0000.626896][2ce7] Fragement shader path:
[007:994] [2025-01-11 15:51:23 +0000.626951][2ce7] create opengl program object.
[007:994] [2025-01-11 15:51:23 +0000.627005][2ce7] create shader: vertex
[008:097] [2025-01-11 15:51:23 +0000.730218][2ce7] create shader: fragment
[008:214] [2025-01-11 15:51:23 +0000.847632][2ce7] Have not extra filter filename!
[008:215] [2025-01-11 15:51:23 +0000.847742][2ce7] Error(crop_filter.cc:368): Init
the extra yuv texture failed.
[008:215] [2025-01-11 15:51:23 +0000.847782][2ce7] OpenGLRenderProgram::Initialize
success.
[008:215] [2025-01-11 15:51:23 +0000.847870][2ce7] Setup the OpenGL result:1
[008:215] [2025-01-11 15:51:23 +0000.848132][2ce6] start recording, file
path:/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[008:215] [2025-01-11 15:51:23 +0000.848291][2ce6] MP4 session params: fps:30
width:1280 height:720 file
name:/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
fileFd: -1 audio_timescale:44100 video_timescale:90000
[008:215] [2025-01-11 15:51:23 +0000.848319][2d1a] Error(thread.cc:348): Set the
pthread name failed with error:34 name:Thread 0x0x6fec763098
[008:215] [2025-01-11 15:51:23 +0000.848356][2ce6] MP4Recorder AddDisplayRotate:0
c:1 s:0 matrix[0]:256 matrix[1]:0 matrix[2]:0 matrix[3]:0 matrix[4]:256
matrix[8]:64
[008:215] [2025-01-11 15:51:23 +0000.848406][2ce6] MP4 recorder params: fps:30
width:1280 height:720 audio time scale:44100 video time scale:90000 file
name:/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
fileFD: -1
[008:216] [2025-01-11 15:51:23 +0000.848872][2ce6] MP4
file:/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
is opened successfully
[008:216] [2025-01-11 15:51:23 +0000.848920][2ce6] setupMediaProcessers:
[008:216] [2025-01-11 15:51:23 +0000.848953][2ce6]
RecordingSessionInternalSimple:setup media processers.
[008:216] [2025-01-11 15:51:23 +0000.848987][2ce6] construct AAC encoder
[008:217] [2025-01-11 15:51:23 +0000.850142][2ce6] H264Encoder creating:
superfast011818
[008:217] [2025-01-11 15:51:23 +0000.850200][2ce6] H264Encode::H264Encoder creating
with width:1280 height:720 m_fps:30 m_bitrate:14680064 useHWEncoder:0
[008:218] [2025-01-11 15:51:23 +0000.851313][2ce6] H264Encode::GetMachineModel is
220333QAG
[008:218] [2025-01-11 15:51:23 +0000.851437][2ce6] H264Encoder machine_mode
is:220333QAG
[008:218] [2025-01-11 15:51:23 +0000.851473][2ce6] H264Encode::machine size :9
machine_mode:220333QAG
[008:218] [2025-01-11 15:51:23 +0000.851508][2ce6] H264Encode::machine array
size :22 machine_mode:220333QAG
[008:218] [2025-01-11 15:51:23 +0000.851554][2ce6] H264Encode::current machine
is :220333QAG auto check hw codec decide to use hw codec
[008:218] [2025-01-11 15:51:23 +0000.851598][2ce6] setupCompressionSession:
[008:218] [2025-01-11 15:51:23 +0000.851630][2ce6] H264Encode::creating hareware
Encoder
[008:219] [2025-01-11 15:51:23 +0000.851673][2ce6] AndroidHwAvcEncoder
time:115900326 enter.
[008:219] [2025-01-11 15:51:23 +0000.851875][2ce6] AndroidHwAvcEncoder:
g_jvm_encoder_ :0xb400006ffd221380 address:0x6f2902e760
[008:219] [2025-01-11 15:51:23 +0000.852109][2ce6] AndroidMediaCodecVideoEncoder
construct jni_init_jfields: 1, 1
[008:219] [2025-01-11 15:51:23 +0000.852171][2ce6] The video avc hw codec address
is 0x6fec61f5b8
[008:219] [2025-01-11 15:51:23 +0000.852264][2ce6] AndroidHwAvcEncoder leave.
expire time = 0 ms
[008:219] [2025-01-11 15:51:23 +0000.852342][2ce6] RegisterEncodeCompleteCallback
time:115900326 enter.
[008:219] [2025-01-11 15:51:23 +0000.852442][2ce6] RegisterEncodeCompleteCallback
leave. expire time = 0 ms
[008:219] [2025-01-11 15:51:23 +0000.852501][2ce6] InitEncode time:115900326
enter.
[008:219] [2025-01-11 15:51:23 +0000.852578][2ce6] InitEncode:
MediaCodecVideoEncoder::Release _inited:1
[008:219] [2025-01-11 15:51:23 +0000.852617][2ce6] Create time:115900326   enter.
[008:219] [2025-01-11 15:51:23 +0000.852678][2ce6] H264Encoder encoderCreate
width:1280 height:720 fps:30 bitrate:14680064 usingVbrMode:1
[008:220] [2025-01-11 15:51:23 +0000.852729][2ce6]
AndroidMediaCodecVideoEncoder::initEncoder, codec: 0, width: 1280, height: 720,
framerate: 30, bitrate: 14680064, usingVbr: 1, VideoEncoder: 0x6f63840c80
[008:223] [2025-01-11 15:51:23 +0000.855737][2ce6]
supported_type:image/vnd.android.heic
[008:223] [2025-01-11 15:51:23 +0000.855802][2ce6] supported_type:video/avc
[008:223] [2025-01-11 15:51:23 +0000.855837][2ce6] name:OMX.qcom.video.encoder.avc
mime:video/avc
[008:223] [2025-01-11 15:51:23 +0000.855900][2ce6] hwCodecPrefix :OMX.qcom.
[008:223] [2025-01-11 15:51:23 +0000.855936][2ce6] Found target codecer
OMX.qcom.video.encoder.avccodecColorFormat:21
[008:223] [2025-01-11 15:51:23 +0000.855974][2ce6] mediacodec_createCodecByName
time:115900330 enter.
[008:223] [2025-01-11 15:51:23 +0000.856008][2ce6] WrapAndroidMediaCodec
time:115900330 enter.
[008:226] [2025-01-11 15:51:23 +0000.858685][2ce6] WrapAndroidMediaCodec    leave.
expire time = 3 ms
[008:248] [2025-01-11 15:51:23 +0000.881475][2ce6] mediacodec_createCodecByName
leave. expire time = 25 ms
[008:248] [2025-01-11 15:51:23 +0000.881589][2ce6] WrapAndroidMediaFromat
time:115900355 enter.
[008:249] [2025-01-11 15:51:23 +0000.881751][2ce6] WrapAndroidMediaFromat    leave.
expire time = 1 ms
[008:298] [2025-01-11 15:51:23 +0000.930710][2ceb] Run AGC when audioType = 0
[008:298] [2025-01-11 15:51:23 +0000.930895][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[008:298] [2025-01-11 15:51:23 +0000.931091][2ce6] Destroy time:115900405 enter.
[008:298] [2025-01-11 15:51:23 +0000.931183][2ce6] WrapAndroidMediaFromat::
Destroy
[008:298] [2025-01-11 15:51:23 +0000.931229][2ce6] Destroy leave. expire time = 0
ms
[008:298] [2025-01-11 15:51:23 +0000.931275][2ce6] ~WrapAndroidMediaFromat
time:115900405 enter.
[008:298] [2025-01-11 15:51:23 +0000.931321][2ce6] ~WrapAndroidMediaFromat   leave.
expire time = 0 ms
[008:298] [2025-01-11 15:51:23 +0000.931368][2ce6] Create   leave. expire time = 79
ms
[008:298] [2025-01-11 15:51:23 +0000.931443][2ce6] isSemiPlanarSupport
time:115900405 enter.
[008:298] [2025-01-11 15:51:23 +0000.931487][2ce6] H264Encoder isSemiPlanarSupport
[008:298] [2025-01-11 15:51:23 +0000.931543][2ce6] isSemiPlanarSupport leave.
expire time = 0 ms
[008:298] [2025-01-11 15:51:23 +0000.931600][2ce6] creat hw H264Encoder success,
isSemiPlanar:1
[008:299] [2025-01-11 15:51:23 +0000.931808][2ce6] InitEncode leave. expire time =
80 ms
[008:299] [2025-01-11 15:51:23 +0000.931858][2ce6] H264Encode::created   Encoder
[008:299] [2025-01-11 15:51:23 +0000.931900][2ce6] H264Encode:: H264 encoder
created.
[008:299] [2025-01-11 15:51:23 +0000.931941][2ce6] H264 encoder created.
[008:299] [2025-01-11 15:51:23 +0000.931982][2ce6]
RecordingSessionInternalSimple:created avc encoder avc: 0x6f639b40c0
[008:299] [2025-01-11 15:51:23 +0000.932026][2ce6] setOutput:
[008:299] [2025-01-11 15:51:23 +0000.932067][2ce6]
RecordingSessionInternalSimple:encoder set output to muxer!
[008:299] [2025-01-11 15:51:23 +0000.932108][2ce6] request a key frame
[008:299] [2025-01-11 15:51:23 +0000.932162][2ce6] H264Encode:: request a key frame
[008:299] [2025-01-11 15:51:23 +0000.932329][2ce6] setDisplayRotate :90
[008:299] [2025-01-11 15:51:23 +0000.932392][2ce6] MP4Recorder AddDisplayRotate:90
c:6.12323e-17 s:-1 matrix[0]:0 matrix[1]:256 matrix[2]:0 matrix[3]:65535
matrix[4]:0 matrix[8]:64
[008:299] [2025-01-11 15:51:23 +0000.932503][2ce7] StartRecording:
[008:299] [2025-01-11 15:51:23 +0000.932642][2d14] operator(): Set the recording
session in mic source. recording session: 0x6f50b63e80
[008:300] [2025-01-11 15:51:23 +0000.933022][2d11] AF_SpeedFilter audio
0x6f50ba4300 PullFrame
[008:300] [2025-01-11 15:51:23 +0000.932804][2ce7] operator() leave. expire time =
329 ms
[008:300] [2025-01-11 15:51:23 +0000.933117][2d11]
RecordAudioSource::PullAudioFrame
[008:300] [2025-01-11 15:51:23 +0000.933162][2d11] AF_SpeedFilter::PullFrame eof4
[008:302] [2025-01-11 15:51:23 +0000.935315][2d11]
Warning(media_filter_camera.cc:548): media pull audio frame failed:4
[008:306] [2025-01-11 15:51:23 +0000.939661][2d11] AF_SpeedFilter audio time,
0x6f50ba4300 rcv time: 115900405
[008:307] [2025-01-11 15:51:23 +0000.939767][2d11] AF_SpeedFilter audio time,
0x6f50ba4300 rcv time: 115900405in_nSamples:441
[008:307] [2025-01-11 15:51:23 +0000.939904][2d11] resampled_data_size:0
[008:352] [2025-01-11 15:51:23 +0000.985255][2ce7]
MediaFilterCamera::NeedProcessTexture
[008:352] [2025-01-11 15:51:23 +0000.985361][2ce7] begine recode to first frame
spend time:381
[008:352] [2025-01-11 15:51:23 +0000.985392][2ce7] CameraSource::PushExtraFrame
pkt_dts: 0, width: 1280, height: 720, stride(kYPlane): 0, stride(kUPlane): 0,
stride(KVPlane): 0, this:0x6f50b63100
[008:352] [2025-01-11 15:51:23 +0000.985443][2ce7] blur_distance_:0
[008:352] [2025-01-11 15:51:23 +0000.985471][2ce7] blur_direction_angle_:-90
[008:352] [2025-01-11 15:51:23 +0000.985520][2ce7]
GPUImageProcessing::SetOrientation orientation: 90
[008:352] [2025-01-11 15:51:23 +0000.985552][2ce7] webrtc common_video
I420VideoFrame::CopyFrame (1/1000)
[008:352] [2025-01-11 15:51:23 +0000.985628][2ce7] GPUImageProcessing: process
video frame with resolution 1280 X 720
[008:352] [2025-01-11 15:51:23 +0000.985665][2ce7] GPUImageProcessing: input frame
width1280 X 720
[008:356] [2025-01-11 15:51:23 +0000.988815][2ce7] drawYUV420 render
OpenGLRenderProgram:0x6f3383f198
[008:358] [2025-01-11 15:51:23 +0000.991642][2ce7] The next ready buffer:-1
m_nextAvaiablePixelBuffer:0
[008:359] [2025-01-11 15:51:23 +0000.991932][2ce7] CropFilter::OnVideoFrame
outputtexture :14
[008:359] [2025-01-11 15:51:23 +0000.991966][2ce7] The next ready buffer:-1
m_nextAvaiablePixelBuffer:0
[008:365] [2025-01-11 15:51:23 +0000.998481][2ce7] The read pixel from buffer take:
6 width:1280 height:720 frameType:0
[008:389] [2025-01-11 15:51:24 +0000.22290][2ce7] drawYUV420 render
OpenGLRenderProgram:0x6f3383f198
[008:398] [2025-01-11 15:51:24 +0000.31103][2ce7] The convert RGB to YUV take 4
[008:399] [2025-01-11 15:51:24 +0000.31836][2ce7] FilterPipeline2::OnVideoFrame pts
34 last_video_timestamp_ 34
[008:399] [2025-01-11 15:51:24 +0000.31951][2ce7]
MediaFilterCamera::OnReceiveVideoFrame output_video_timestamp_:0
[008:399] [2025-01-11 15:51:24 +0000.32053][2d19]
MediaFilterCamera::WriteVideoFrametoMP4_w -> pushExtraYUVFrametimestamp:0
[008:399] [2025-01-11 15:51:24 +0000.32108][2d19] RecordingSessionInternalSimple:
pushExtraYUVFrame. width:1280 height:720input pixel type:0 dataType:0 stride:1280
streamId:1 timestamp:0
[008:399] [2025-01-11 15:51:24 +0000.32148][2d19] push video frame to H264 encoder.
(100 frame for one log)
[008:399] [2025-01-11 15:51:24 +0000.32206][2d19] push video frame to H264 encoder.
(100 frame for one log) used width:1280 height:720
[008:399] [2025-01-11 15:51:24 +0000.32239][2d19] H264Encode::push video frame to
H264 encoder, using hw H264:1 width:1280 height:720 pts:0(100 frame for one log)
[008:399] [2025-01-11 15:51:24 +0000.32285][2d19] Push an pts:0 to dts queue.
[008:399] [2025-01-11 15:51:24 +0000.32323][2d19] DTS queue for
Generator:0x6fec7632b8 0
[008:399] [2025-01-11 15:51:24 +0000.32348][2d19] The input pts:0 fps:30
[008:401] [2025-01-11 15:51:24 +0000.34360][2d19]
AndroidMediaCodecVideoEncoder::encodeFrameInput force a key frame
[008:423] [2025-01-11 15:51:24 +0000.56154][2ce7] drawYUV420 render
OpenGLRenderProgram:0x6f3383f198
[008:453] [2025-01-11 15:51:24 +0000.85839][2d11] AF_SpeedFilter audio time 222,
0x6f50ba4300 out time: 115900405 org time: 115900405
[008:453] [2025-01-11 15:51:24 +0000.85988][2d11] FilterPipeline2::OnAudioFrame pts
10 last_audio_timestamp_ 10
[008:453] [2025-01-11 15:51:24 +0000.86052][2d11]
MediaFilterCamera::OnReceiveAudioFrame timestamp: 115900405
[008:453] [2025-01-11 15:51:24 +0000.86108][2d11]
MediaFilterCamera::OnReceiveAudioFrame timestamp: sample_rate_hz_:44100
num_channels_:2 samples_per_channel_:441
[008:453] [2025-01-11 15:51:24 +0000.86164][2d11] AF_SpeedFilter audio time
[008:453] [2025-01-11 15:51:24 +0000.86302][2d11] AF_SpeedFilter audio time 111,
0x6f50ba4300 out time: 115900415 org time: 115900415
[008:455] [2025-01-11 15:51:24 +0000.88250][2d18]
MediaFilterCamera::WriteAudioFrametoMP4_w timestamp: 0queue_audio_frame_:3
[008:455] [2025-01-11 15:51:24 +0000.88662][2d1a] add aac audio track with id:1
[008:456] [2025-01-11 15:51:24 +0000.89007][2d1a] audio start ts:0
[008:456] [2025-01-11 15:51:24 +0000.89108][2d1a] the audio frame has been written
into MP4 file
[008:492] [2025-01-11 15:51:24 +0000.124850][2d19] h264 add frame header cdr flag
[008:492] [2025-01-11 15:51:24 +0000.125310][2d19] h264 add frame header cdr flag
[008:492] [2025-01-11 15:51:24 +0000.125558][2d19] SendFrame
encodedImage._timeStamp:0encodedImage->_length:52495
[008:492] [2025-01-11 15:51:24 +0000.125621][2d19] Received key frame:0
[008:493] [2025-01-11 15:51:24 +0000.125776][2d19] last dts:0 currentPts:0
ppsOrSPS:0 queue length:4
[008:493] [2025-01-11 15:51:24 +0000.125899][2d19] H264Encode::H264SWEncoder get
callback, length:52495 type:0 nalType:7length:18
[008:493] [2025-01-11 15:51:24 +0000.126008][2d19] H264Encode:: get a sps pps
header!headerSize:31
[008:493] [2025-01-11 15:51:24 +0000.126082][2d19] H264Encode::H264 encoder push
frame to next component(100 times for one log) with dts:0 pts:0
[008:493] [2025-01-11 15:51:24 +0000.126474][2d1a] get the H.264 SPS parameter
[008:494] [2025-01-11 15:51:24 +0000.126943][2d1a] add video track with id:2
[008:494] [2025-01-11 15:51:24 +0000.127455][2d1a] get the H.264 PPS parameter
[008:495] [2025-01-11 15:51:24 +0000.127843][2d1a] get the H.264 IDR frame
[008:495] [2025-01-11 15:51:24 +0000.127928][2d1a] resss:true
[008:495] [2025-01-11 15:51:24 +0000.127972][2d1a] video start ts:0
[008:495] [2025-01-11 15:51:24 +0000.128086][2d1a] the video frame has been written
into MP4 file
[008:832] [2025-01-11 15:51:24 +0000.464829][2ceb] haveEnoughProcessedData
internalAudioStream.length: 10
[008:846] [2025-01-11 15:51:24 +0000.479459][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[008:885] [2025-01-11 15:51:24 +0000.518377][2ce7] The convert RGB to YUV take 4
[008:987] [2025-01-11 15:51:24 +0000.620532][2d14] Error(MicSource.cpp:254): There
is no output connect to mic source, discard the frame.
[009:221] [2025-01-11 15:51:24 +0000.854335][2d19] DTS queue for
Generator:0x6fec7632b8 1578 1652
[009:347] [2025-01-11 15:51:24 +0000.979893][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[009:382] [2025-01-11 15:51:25 +0000.15447][2ce7] The convert RGB to YUV take 3
[009:647] [2025-01-11 15:51:25 +0000.279823][2d11] resampled_data_size:13824
[009:649] [2025-01-11 15:51:25 +0000.282114][2d18]
MediaFilterCamera::WriteAudioFrametoMP4_w timestamp: 2321queue_audio_frame_:10
[009:702] [2025-01-11 15:51:25 +0000.335377][2d11] AF_SpeedFilter audio
0x6f50ba4300 PullFrame
[009:733] [2025-01-11 15:51:25 +0000.366452][2d19] Push an pts:2674 to dts queue.
[009:789] [2025-01-11 15:51:25 +0000.421920][2d19] last dts:2616 currentPts:2674
ppsOrSPS:0 queue length:3
[009:846] [2025-01-11 15:51:25 +0000.478874][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[009:882] [2025-01-11 15:51:25 +0000.515568][2ce7] The convert RGB to YUV take 4
[009:986] [2025-01-11 15:51:25 +0000.619492][2d14] Error(MicSource.cpp:254): There
is no output connect to mic source, discard the frame.
[010:061] [2025-01-11 15:51:25 +0000.693754][2d19] DTS queue for
Generator:0x6fec7632b8 3254 3324
[010:119] [2025-01-11 15:51:25 +0000.752070][2d11]
RecordAudioSource::PullAudioFrame
[010:298] [2025-01-11 15:51:25 +0000.930967][2cec] webrtc device:Handle the data
from samplesPerSec recording sample rate:48000 nSamples:480 nBytesPerSample:#
nChannels:#
[010:298] [2025-01-11 15:51:25 +0000.931547][2cec] _deviceSampleRate:48000
[010:302] [2025-01-11 15:51:25 +0000.935187][2ceb] Run AGC when audioType = 0
[010:349] [2025-01-11 15:51:25 +0000.982462][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[010:399] [2025-01-11 15:51:26 +0000.32165][2ce7] The convert RGB to YUV take 4
[010:412] [2025-01-11 15:51:26 +0000.45246][2ce7] webrtc common_video plane
old ..... (1/1000) new: 1001 delete: 980 diff: 21
[010:453] [2025-01-11 15:51:26 +0000.85847][2d19] h264 add frame header cdr flag
[010:453] [2025-01-11 15:51:26 +0000.86432][2d19] Received key frame:4016
[010:485] [2025-01-11 15:51:26 +0000.118027][2d1a] get the H.264 IDR frame
[010:524] [2025-01-11 15:51:26 +0000.157162][2d11] AF_SpeedFilter::PullFrame eof4
[010:526] [2025-01-11 15:51:26 +0000.159403][2d11]
Warning(media_filter_camera.cc:548): media pull audio frame failed:4
[010:529] [2025-01-11 15:51:26 +0000.162273][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[010:783] [2025-01-11 15:51:26 +0000.416527][2d18]
MediaFilterCamera::WriteAudioFrametoMP4_w timestamp: 4643queue_audio_frame_:4
[010:862] [2025-01-11 15:51:26 +0000.494777][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[010:872] [2025-01-11 15:51:26 +0000.505040][2d19] DTS queue for
Generator:0x6fec7632b8 4950
[010:894] [2025-01-11 15:51:26 +0000.527441][2ce7] The convert RGB to YUV take 7
[010:895] [2025-01-11 15:51:26 +0000.527768][2d11] resampled_data_size:10240
[010:986] [2025-01-11 15:51:26 +0000.619688][2d14] Error(MicSource.cpp:254): There
is no output connect to mic source, discard the frame.
[011:054] [2025-01-11 15:51:26 +0000.686802][2d19] Push an pts:5320 to dts queue.
[011:071] [2025-01-11 15:51:26 +0000.704075][2d11] AF_SpeedFilter audio
0x6f50ba4300 PullFrame
[011:124] [2025-01-11 15:51:26 +0000.756978][2d19] last dts:5252 currentPts:5320
ppsOrSPS:0 queue length:3
[011:345] [2025-01-11 15:51:26 +0000.977896][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[011:388] [2025-01-11 15:51:27 +0000.21124][2ce7] The convert RGB to YUV take 7
[011:653] [2025-01-11 15:51:27 +0000.286171][2ce7] drawYUV420 render
OpenGLRenderProgram:0x6f3383f198
[011:717] [2025-01-11 15:51:27 +0000.349998][2ce7] GPUImageProcessing: process
video frame with resolution 1280 X 720
[011:717] [2025-01-11 15:51:27 +0000.350107][2ce7] GPUImageProcessing: input frame
width1280 X 720
[011:718] [2025-01-11 15:51:27 +0000.350767][2ce7] The next ready buffer:0
m_nextAvaiablePixelBuffer:1
[011:718] [2025-01-11 15:51:27 +0000.350939][2ce7] The next ready buffer:0
m_nextAvaiablePixelBuffer:1
[011:723] [2025-01-11 15:51:27 +0000.356175][2d19]
MediaFilterCamera::WriteVideoFrametoMP4_w -> pushExtraYUVFrametimestamp:6662
[011:723] [2025-01-11 15:51:27 +0000.356343][2d19] DTS queue for
Generator:0x6fec7632b8 6532 6614 6662
[011:749] [2025-01-11 15:51:27 +0000.381970][2d19] RecordingSessionInternalSimple:
pushExtraYUVFrame. width:1280 height:720input pixel type:0 dataType:0 stride:1280
streamId:1 timestamp:6716
[011:749] [2025-01-11 15:51:27 +0000.382141][2d19] push video frame to H264
encoder. (100 frame for one log)
[011:749] [2025-01-11 15:51:27 +0000.382219][2d19] push video frame to H264
encoder. (100 frame for one log) used width:1280 height:720
[011:749] [2025-01-11 15:51:27 +0000.382285][2d19] H264Encode::push video frame to
H264 encoder, using hw H264:1 width:1280 height:720 pts:6716(100 frame for one
log)
[011:749] [2025-01-11 15:51:27 +0000.382357][2d19] The input pts:6716 fps:30
[011:752] [2025-01-11 15:51:27 +0000.385114][2d19] H264Encode::H264 encoder push
frame to next component(100 times for one log) with dts:6614 pts:6614
[011:789] [2025-01-11 15:51:27 +0000.422276][2d19] SendFrame
encodedImage._timeStamp:6662encodedImage->_length:90736
[011:790] [2025-01-11 15:51:27 +0000.423401][2d19] H264Encode::H264SWEncoder get
callback, length:101152 type:1 nalType:1length:101148
[011:852] [2025-01-11 15:51:27 +0000.485182][2ce7] The read pixel from buffer take:
0 width:1280 height:720 frameType:0
[011:893] [2025-01-11 15:51:27 +0000.526270][2ce7] The convert RGB to YUV take 7
[011:946] [2025-01-11 15:51:27 +0000.579650][2d11]
RecordAudioSource::PullAudioFrame
[011:970] [2025-01-11 15:51:27 +0000.603678][2d18]
MediaFilterCamera::WriteAudioFrametoMP4_w timestamp: 6965queue_audio_frame_:11
[011:987] [2025-01-11 15:51:27 +0000.619889][2d14] Error(MicSource.cpp:254): There
is no output connect to mic source, discard the frame.
[012:147] [2025-01-11 15:51:27 +0000.779878][2d11] resampled_data_size:6656
[012:231] [2025-01-11 15:51:27 +0000.863902][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[012:251] [2025-01-11 15:51:27 +0000.883962][1972]
Java_com_xiaomi_recordmediaprocess_MediaEffectCamera_StopRecordingJni:
[012:251] [2025-01-11 15:51:27 +0000.884332][2ce7] operator(): Stop
[012:251] [2025-01-11 15:51:27 +0000.884389][2ce7] StopPullFrameAndWriteMp4
time:115904358 enter.
[012:251] [2025-01-11 15:51:27 +0000.884437][2ce7] StopRecording:
[012:256] [2025-01-11 15:51:27 +0000.888934][2d11] resampled_data_size:512
[012:256] [2025-01-11 15:51:27 +0000.889093][2d11] AF_SpeedFilter audio time 222,
0x6f50ba4300 out time: 115908025 org time: 115908025
[012:256] [2025-01-11 15:51:27 +0000.889327][2d11] PullAudioFrame: PullFrame audio
finished.
[012:256] [2025-01-11 15:51:27 +0000.889368][2d11]
Warning(media_filter_camera.cc:543): media pull audio frame EOF, so need stop
Timer!
[012:259] [2025-01-11 15:51:27 +0000.892550][2d19]
MediaFilterCamera::WriteVideoFrametoMP4_wwrite mp4 video sum frames:117
[012:262] [2025-01-11 15:51:27 +0000.895290][2ce6] stop recording
[012:262] [2025-01-11 15:51:27 +0000.895351][2ce6]
RecordingSessionInternalSimple:destroy media processers
[012:262] [2025-01-11 15:51:27 +0000.895379][2ce6] H264Encode::H264Encoder flush
frames, m_useHWEncoder:1
[012:262] [2025-01-11 15:51:27 +0000.895404][2ce6] H264Encode::flushing hareware
Encoder
[012:262] [2025-01-11 15:51:27 +0000.895566][2ce6] Flush time:115904369 enter.
[012:262] [2025-01-11 15:51:27 +0000.895612][2ce6]   H264Encoder encoder flush
[012:262] [2025-01-11 15:51:27 +0000.895641][2ce6]   flush output queue
[012:302] [2025-01-11 15:51:27 +0000.935385][2ceb]   Run AGC when audioType = 0
[012:302] [2025-01-11 15:51:27 +0000.935529][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[012:580] [2025-01-11 15:51:28 +0000.213478][2ce6]   Flush   leave. expire time = 318
ms
[012:581] [2025-01-11 15:51:28   +0000.213872][2ce6] ~H264EncodeWrap:
[012:581] [2025-01-11 15:51:28   +0000.214022][2ce6] ~H264Encode:
[012:581] [2025-01-11 15:51:28   +0000.214408][2ce6] teardownCompressionSession:
H264Encode::destroying Encoder
[012:581] [2025-01-11 15:51:28   +0000.214489][2ce6] Release time:115904688   enter.
[012:582] [2025-01-11 15:51:28 +0000.215310][2ce6] Release:
MediaCodecVideoEncoder::Release _inited:1
[012:582] [2025-01-11 15:51:28 +0000.215457][2ce6] Destroy time:115904689     enter.
[012:583] [2025-01-11 15:51:28 +0000.215844][2ce6] H264Encoder encoderDestroy
[012:583] [2025-01-11 15:51:28 +0000.215968][2ce6]
AndroidMediaCodecVideoEncoder::release env: 0x6f639b45c0
[012:583] [2025-01-11 15:51:28 +0000.216042][2ce6]
AndroidMediaCodecVideoEncoder::Destroy
[012:616] [2025-01-11 15:51:28 +0000.248869][2ce6] Destroy leave. expire time = 34
ms
[012:617] [2025-01-11 15:51:28 +0000.250151][2ce6] Release     leave. expire time = 36
ms
[012:617] [2025-01-11 15:51:28 +0000.250289][2ce6] ~AndroidHwAvcEncoder
time:115904724 enter.
[012:617] [2025-01-11 15:51:28 +0000.250616][2ce6] Release time:115904724     enter.
[012:618] [2025-01-11 15:51:28 +0000.250764][2ce6] Release:
MediaCodecVideoEncoder:: has been released before, just return
[012:618] [2025-01-11 15:51:28 +0000.250831][2ce6] Release leave. expire time = 1
ms
[012:618] [2025-01-11 15:51:28 +0000.250883][2ce6] ~AndroidMediaCodecVideoEncoder
[012:618] [2025-01-11 15:51:28 +0000.250930][2ce6] ~WrapAndroidMediaCodec
time:115904725 enter.
[012:618] [2025-01-11 15:51:28 +0000.251027][2ce6] ~WrapAndroidMediaCodec     leave.
expire time = 0 ms
[012:618] [2025-01-11 15:51:28 +0000.251093][2ce6] video hevc hw codec
destructor:0x6fec61f5b8
[012:618] [2025-01-11 15:51:28 +0000.251278][2ce6] ~AndroidHwAvcEncoder     leave.
expire time = 1 ms
[012:618] [2025-01-11 15:51:28 +0000.251387][2ce6] teardownCompressionSession:
H264Encode::destroyed Encoder
[012:618] [2025-01-11 15:51:28 +0000.251452][2ce6]
RecordingSessionInternalSimple:destroy aac encoder output!
[012:618] [2025-01-11 15:51:28 +0000.251499][2ce6] AACSWEncode destructor
[012:630] [2025-01-11 15:51:28 +0000.262928][2d1a] MP4 close file successfully
[012:630] [2025-01-11 15:51:28 +0000.263097][2d1a] Recording session Internal:
notify recording ok
[012:630] [2025-01-11 15:51:28 +0000.263176][2d1a] onRecordingOk: Recording Ok
averagefps 0,
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4,
output_video_timestamp_:7716 last_video_pts_:7716
[012:630] [2025-01-11 15:51:28 +0000.263279][2d1a] OnRecordFinish:
[012:634] [2025-01-11 15:51:28 +0000.267667][2d1a] onRecordingOk: net seek
pos:0record video duration:7749record audio duration:7732
[012:635] [2025-01-11 15:51:28 +0000.268627][2d14] destructMicSource_w:
[012:636] [2025-01-11 15:51:28 +0000.268710][2d14] setOutput_w:
MicSource::setOutput() 0x0
[012:636] [2025-01-11 15:51:28 +0000.268784][2d14] close external audio stream
[012:636] [2025-01-11 15:51:28 +0000.269269][2ce7] releaseThread 0x6f509cea00
[012:636] [2025-01-11 15:51:28 +0000.269338][2ce7] ~MicSource: Mic source
destroyed:0x6fdfb16c80
[012:637] [2025-01-11 15:51:28 +0000.270024][2ce7] StopPullFrameAndWriteMp4 leave.
expire time = 386 ms
[012:637] [2025-01-11 15:51:28 +0000.270102][2ce7] DestroyPipelineAndFilters
time:115904744 enter.
[012:637] [2025-01-11   15:51:28 +0000.270275][2ce7]   UnbindInputFilter 0x6f50b63240
this 0x6f3383f3c0
[012:637] [2025-01-11   15:51:28 +0000.270339][2ce7]   UnbindInputFilter 0x6f50b63fd8
this 0x6f50ba4450
[012:637] [2025-01-11   15:51:28 +0000.270419][2ce7]   UnbindInputFilter 0x6f3383f3c0
this 0x6fed3e2ee0
[012:637] [2025-01-11   15:51:28 +0000.270470][2ce7] filter: filter pipeline destroy
this: 0x6fed3e2e40
[012:638] [2025-01-11   15:51:28 +0000.270759][2ce7] ClearVideoOfSources: recodring
total frames:0
[012:638] [2025-01-11   15:51:28 +0000.270832][2ce7] ClearVideoOfSources:
[012:638] [2025-01-11   15:51:28 +0000.270873][2ce7] GPUImageProcessing destruct.
[012:638] [2025-01-11   15:51:28 +0000.270957][2ce7] destroy TrivalImageDrawer
0x6fed215640
[012:638] [2025-01-11   15:51:28 +0000.271002][2ce7] The shader has been initialize,
clean up them.
[012:638] [2025-01-11   15:51:28   +0000.271088][2ce7]   ~OpenGLFrameBuffer:
[012:638] [2025-01-11   15:51:28   +0000.271351][2ce7]   ~OpenGLFrameBuffer:
[012:638] [2025-01-11   15:51:28   +0000.271509][2ce7]   ~OpenGLFrameBuffer:
[012:638] [2025-01-11   15:51:28   +0000.271661][2ce7]   ~OpenGLFrameBuffer:
[012:639] [2025-01-11   15:51:28   +0000.271807][2ce7]   ~OpenGLFrameBuffer:
[012:639] [2025-01-11   15:51:28   +0000.271940][2ce7]   ~OpenGLFrameBuffer:
[012:639] [2025-01-11   15:51:28   +0000.272193][2ce7]   GPUImageProcessing destruct
finish.
[012:639] [2025-01-11   15:51:28 +0000.272269][2ce7] GPURGBAFrame destruct.
[012:639] [2025-01-11   15:51:28 +0000.272342][2ce7] destroy OpenGLRenderProgram
0x6f3383f198
[012:639] [2025-01-11   15:51:28 +0000.272391][2ce7] ~CameraSource time:115904746
enter.
[012:639] [2025-01-11 15:51:28 +0000.272493][2ce7] ~CameraSource        leave. expire
time = 0 ms
[012:639] [2025-01-11 15:51:28 +0000.272543][2ce7] ~RecordAudioSource
time:115904746 enter.
[012:639] [2025-01-11 15:51:28 +0000.272585][2ce7] ~RecordAudioSource        leave.
expire time = 0 ms
[012:639] [2025-01-11 15:51:28 +0000.272631][2ce7] DestroyPipelineAndFilters
leave. expire time = 2 ms
[012:664] [2025-01-11 15:51:28 +0000.297104][1972]
Java_com_xiaomi_recordmediaprocess_MediaEffectGraph_ConstructMediaEffectGraphJni:
[012:665] [2025-01-11 15:51:28 +0000.298156][1972] machine model :redmi
[012:665] [2025-01-11 15:51:28 +0000.298222][1972] Support this machine
model :redmi
[012:665] [2025-01-11 15:51:28 +0000.298290][1972] global inited! current sdk
version: 12-14-d8558ab
[012:665] [2025-01-11 15:51:28 +0000.298531][1972] filter: filter pipeline
construtc this: 0x6f50b62200
[012:665] [2025-01-11 15:51:28 +0000.298601][1972] MediaFilterGraph:
MediaFilterGraph construct
[012:665] [2025-01-11 15:51:28 +0000.298646][1972] GLESContext:
[012:666] [2025-01-11 15:51:28 +0000.298736][1972] The result of eglGetDisplay is:
0x1
[012:667] [2025-01-11 15:51:28 +0000.300586][1972] The result of creation:
0x6f3366cf00
[012:668] [2025-01-11 15:51:28 +0000.300754][1972] The result of
eglCreatePbufferSurface is: 0x6f3367f300
[012:669] [2025-01-11 15:51:28 +0000.301945][1972] The source 0
path:/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[012:669] [2025-01-11 15:51:28 +0000.302032][1972] The source 0 speed:0.5
[012:669] [2025-01-11 15:51:28 +0000.302096][1972] AddVideoSource: add video
source:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4,
delay: 0
[012:669] [2025-01-11 15:51:28 +0000.302167][1972] CreateDecoderByVideoSource
time:115904776 enter.
[012:669] [2025-01-11 15:51:28 +0000.302482][1972] running_decoder_count:0max
count:2
[012:669] [2025-01-11 15:51:28 +0000.302547][1972] Start decoding file
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4,
sync_mode: 1, seek_pos: 0, delay: 0, this:0x6f3362b400
[012:686] [2025-01-11 15:51:28 +0000.319609][2d21] FFMpeg=>
[mov,mp4,m4a,3gp,3g2,mj2 @ 0x6f47b42400] Current FFmpeg version git-2021-06-16-
97cea6f
[012:813] [2025-01-11 15:51:28   +0000.446501][2d21] OnStreamInfoFound:   video
duration_: 7749
[012:815] [2025-01-11 15:51:28   +0000.448059][1972] start decoder
[012:815] [2025-01-11 15:51:28   +0000.448040][2d22] NO get frame from decoder
[012:815] [2025-01-11 15:51:28   +0000.448160][1972] CreateDecoderByVideoSource
leave. expire time = 146 ms
[012:815] [2025-01-11 15:51:28 +0000.448228][1972] BindInputFilter 0x6fdfcc9e20
this 0x6fdfa03998
[012:815] [2025-01-11 15:51:28 +0000.448272][1972] BindInputFilter 0x6fdfa03998
this 0x6f33646dd8
[012:815] [2025-01-11 15:51:28 +0000.448314][1972] BindInputFilter 0x6fdfcc9e20
this 0x6f33646e98
[012:815] [2025-01-11 15:51:28 +0000.448353][1972] BindInputFilter 0x6f33646e98
this 0x6f33646f58
[012:815] [2025-01-11 15:51:28 +0000.448379][1972] AddVideoSource: source filter
list is empty, add first:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[012:815] [2025-01-11 15:51:28 +0000.448431][1972] BindInputFilter 0x6f33646dd8
this 0x6f33647018
[012:815] [2025-01-11 15:51:28 +0000.448470][1972] BindInputFilter 0x6f33647018
this 0x6f50b622a0
[012:815] [2025-01-11 15:51:28 +0000.448509][1972] BindInputFilter 0x6f33646f58
this 0x6f336470d8
[012:815] [2025-01-11 15:51:28 +0000.448559][1972] AddVideoSource: END add video
source:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[012:815] [2025-01-11 15:51:28 +0000.448648][2d22] NO get frame from decoder
[012:816] [2025-01-11 15:51:28 +0000.449433][2d22] NO get frame from decoder
[012:817] [2025-01-11 15:51:28 +0000.450475][2d22] NO get frame from decoder
[012:818] [2025-01-11 15:51:28 +0000.451191][2d22] NO get frame from decoder
[012:820] [2025-01-11 15:51:28 +0000.452751][2d22] NO get frame from decoder
[012:821] [2025-01-11 15:51:28 +0000.454508][2d22] NO get frame from decoder
[012:823] [2025-01-11 15:51:28 +0000.456085][2d22] NO get frame from decoder
[012:825] [2025-01-11 15:51:28 +0000.458049][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_ConstructMediaPlayerJni:
[012:826] [2025-01-11 15:51:28 +0000.458749][1972] machine model :redmi
[012:826] [2025-01-11 15:51:28 +0000.458897][1972] Support this machine
model :redmi
[012:826] [2025-01-11 15:51:28 +0000.458929][1972] global inited! current sdk
version: 12-14-d8558ab
[012:826] [2025-01-11 15:51:28 +0000.459028][1972] GetPipeline:
-----------------------------------------
[012:826] [2025-01-11 15:51:28 +0000.459098][1972] GetPipeline: source:
0x6fdfcc9c00
[012:826] [2025-01-11 15:51:28 +0000.459164][1972] GetPipeline: filter: NULLFilter
[012:826] [2025-01-11 15:51:28 +0000.459190][1972] GetPipeline: filter: NULLFilter
[012:826] [2025-01-11 15:51:28 +0000.459216][1972] GetPipeline: filter: NULLFilter
[012:826] [2025-01-11 15:51:28 +0000.459240][1972] GetPipeline: audio filter:
AuidoNULLFilter
[012:826] [2025-01-11 15:51:28 +0000.459263][1972] GetPipeline: audio filter:
AuidoNULLFilter
[012:826] [2025-01-11 15:51:28 +0000.459286][1972] GetPipeline: audio filter:
AudioNUllFilter
[012:826] [2025-01-11 15:51:28 +0000.459310][1972] GetPipeline:
------------------------------------------
[012:826] [2025-01-11 15:51:28 +0000.459339][1972] filter: filter pipeline2 update
pattern: 0
[012:827] [2025-01-11 15:51:28 +0000.460411][1972] Reset: volume estimator reset
frequency : 44100 history size(ms): -1
[012:828] [2025-01-11 15:51:28 +0000.460797][1972] Reset: volume estimator reset
frequency : 44100 history size(ms): -1
[012:828] [2025-01-11 15:51:28 +0000.460893][1972] Reset: volume estimator reset
frequency : 44100 history size(ms): -1
[012:828] [2025-01-11 15:51:28 +0000.461106][1972] DeviceInit:
[012:828] [2025-01-11 15:51:28 +0000.461524][1972] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_SAMPLE_RATE
[012:829] [2025-01-11 15:51:28 +0000.462146][1972] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_FRAMES_PER_BUFFER
[012:830] [2025-01-11 15:51:28 +0000.463172][1972] CreatePlatformSpecificObjects:
[012:830] [2025-01-11 15:51:28 +0000.463273][1972] audio device use null audio
input and opensl output
[012:830] [2025-01-11 15:51:28 +0000.463309][1972] Create OpenSLES output module
with java VM support.
[012:830] [2025-01-11 15:51:28 +0000.463547][1972] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_SAMPLE_RATE
[012:831] [2025-01-11 15:51:28 +0000.464030][1972] AudioManagerAndroid
getProperty:android.media.property.OUTPUT_FRAMES_PER_BUFFER
[012:832] [2025-01-11 15:51:28 +0000.464757][1972] Create OpenSLES output module
with buffer num:2
[012:832] [2025-01-11 15:51:28 +0000.464965][1972] Error(trace.cc:40): Init the
output and input for audio device template.
[012:832] [2025-01-11 15:51:28 +0000.465046][1972] Init the output device.
[012:832] [2025-01-11 15:51:28 +0000.465084][1972] Set the playout as stereo:1
[012:832] [2025-01-11 15:51:28 +0000.465117][1972] Set the sample rate to be:44100
[012:832] [2025-01-11 15:51:28 +0000.465215][1972] Low latency buffer size:144 low
sample rate:48000
[012:832] [2025-01-11 15:51:28 +0000.465270][1972] DeviceInit: Done
[012:832] [2025-01-11 15:51:28 +0000.465544][1972] UpdateRate: lipsync_debug:
Update framerate from 0 to 15
[012:834] [2025-01-11 15:51:28 +0000.466986][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_SetPlayerNotifyJni:
[012:834] [2025-01-11 15:51:28 +0000.467171][1972]
MediaFilterPlayer::SetPlayerNotify 0x6f33a22a60
[012:835] [2025-01-11 15:51:28 +0000.467803][1972]
Error(media_filter_player.cc:392): SetPlayLoop: SetPlayLoop error for
media_filter_graph_ GetCurrentSource null
[012:835] [2025-01-11 15:51:28 +0000.467868][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_SetPlayLoopJni:
EffectMediaPlayer SetPlayLoop #
[012:836] [2025-01-11 15:51:28 +0000.468705][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_SetGravityJni: gravity: 1
width: 720, height: 1280
[012:836] [2025-01-11 15:51:28 +0000.469333][1972] SetGraphLoop: SetGraphLoop 1
[012:836] [2025-01-11 15:51:28 +0000.469406][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_SetGraphLoopJni:
EffectMediaPlayer SetGraphLoop 1
[012:837] [2025-01-11 15:51:28 +0000.469963][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_EnableUserAdjustRotatePlayJni:
[012:837] [2025-01-11 15:51:28 +0000.470431][2d2e] Error(thread.cc:348): Set the
pthread name failed with error:34 name:Thread 0x0x6f2e916018
[012:841] [2025-01-11 15:51:28 +0000.473930][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_SetViewSurfaceJni:
[012:842] [2025-01-11 15:51:28 +0000.474968][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_StartPreViewJni:
[012:848] [2025-01-11 15:51:28 +0000.481620][2d30] Start:
[012:851] [2025-01-11 15:51:28 +0000.484405][2d2f] operator(): video render
started.
[012:851] [2025-01-11 15:51:28 +0000.484531][2d2f] DriveRender working... (1/200)
[012:851] [2025-01-11 15:51:28 +0000.484632][2d2f] Warning(video_render.cc:390): No
picture to display (1/50)
[012:852] [2025-01-11 15:51:28 +0000.485272][2d30] Start:
[012:852] [2025-01-11 15:51:28 +0000.485498][2d30] Reset: volume estimator reset
frequency : 44100 history size(ms): 5000
[012:852] [2025-01-11 15:51:28 +0000.485551][2d30] Reset: history total size: 98
[012:852] [2025-01-11 15:51:28 +0000.485586][2d30] DeviceStart:
audioDevice_:0x6f33bc2000
[012:852] [2025-01-11 15:51:28 +0000.485617][2d30] DeviceStart: time profile
playout init before
[012:852] [2025-01-11 15:51:28 +0000.485645][2d30] DeviceStart: time profile
playout init end
[012:852] [2025-01-11 15:51:28 +0000.485668][2d30] OpenSlesOutput:Start playout
[012:853] [2025-01-11 15:51:28 +0000.486556][2d30] Create the opensl output player.
streamtype before:0
[012:853] [2025-01-11 15:51:28 +0000.486607][2d30] Create the opensl output player.
streamtype after:3
[012:870] [2025-01-11 15:51:28 +0000.502822][2d30] Enqueue opensl buffer size:1764
[012:946] [2025-01-11 15:51:28 +0000.578954][2d30] DeviceStart: time profile
playout all end
[012:946] [2025-01-11 15:51:28 +0000.578993][2d33]
PlayerSimpleBufferQueueCallbackHandler function
[012:946] [2025-01-11 15:51:28 +0000.579042][2d30] operator(): Audio playback
started.
[012:946] [2025-01-11 15:51:28 +0000.579104][2d33] NeedMorePlayData No enough
buffers. nSamples: 441(1/100)
[012:946] [2025-01-11 15:51:28 +0000.579148][2d30] operator(): Started now!
[012:946] [2025-01-11 15:51:28 +0000.579230][2d31] Pull audio failed.(wait video!)
[012:946] [2025-01-11 15:51:28 +0000.579269][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[012:946] [2025-01-11 15:51:28 +0000.579307][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[012:947] [2025-01-11 15:51:28 +0000.580492][2d32] (1/100)OnReceiveVideoFrame,
key_frame: 1, pkt_pts: 900pts ms:10000
[012:946] [2025-01-11 15:51:28 +0000.579148][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[012:951] [2025-01-11 15:51:28 +0000.583748][2d31] audio_frame_list_ empty
[012:951] [2025-01-11 15:51:28 +0000.584380][2d32] OnVideoFrameReady(1/200)
[012:952] [2025-01-11 15:51:28 +0000.585171][2d2f] operator() frame->pts-
9223372036854775808 queue_ size: 1 (1/200)
[012:953] [2025-01-11 15:51:28 +0000.586485][2d2f] lipsync_debug:displaying video
ts:900 The current displayed video timestamp is:115905060
[012:995] [2025-01-11 15:51:28 +0000.627995][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 0
[012:998] [2025-01-11 15:51:28 +0000.631645][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[012:999] [2025-01-11 15:51:28 +0000.632221][2d31] audio frame wait video
frame.last_video_timestamp:132 last_audio_timestamp:254.921
[013:000] [2025-01-11 15:51:28 +0000.633458][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[013:001] [2025-01-11 15:51:28 +0000.634404][2d31] audio frame wait video
frame.last_video_timestamp:132 last_audio_timestamp:254.921
[013:002] [2025-01-11 15:51:28 +0000.635642][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[013:003] [2025-01-11 15:51:28 +0000.636533][2d31] audio frame wait video
frame.last_video_timestamp:132 last_audio_timestamp:254.921
[013:005] [2025-01-11 15:51:28 +0000.637810][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[013:006] [2025-01-11 15:51:28 +0000.638713][2d31] audio frame wait video
frame.last_video_timestamp:132 last_audio_timestamp:254.921
[013:007] [2025-01-11 15:51:28 +0000.639987][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[013:008] [2025-01-11 15:51:28 +0000.640875][2d31] audio frame wait video
frame.last_video_timestamp:132 last_audio_timestamp:254.921
[013:009] [2025-01-11 15:51:28 +0000.641813][2d30] Create the audio converter 44100
Hz format:1 channels:2 old channels: 0 Hz: 0 format:-1
[013:009] [2025-01-11 15:51:28 +0000.641985][2d30] The audio playback speed:100
(1/100)
[013:009] [2025-01-11 15:51:28 +0000.642024][2d30] lipsync_debug: The current
played audio timestamp is:0(1/50)
[013:009] [2025-01-11 15:51:28 +0000.642051][2d30] First audio frame rendering
[013:013] [2025-01-11 15:51:28 +0000.646361][2d2f] lipsync_debug: render a video
frame
[013:013] [2025-01-11 15:51:28 +0000.646587][2d2f] lipsync_debug: First video frame
rendering.
[013:023] [2025-01-11 15:51:28 +0000.655922][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:23 timeDelta:70 last
video ts:900 frame->pts:2970 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[013:023] [2025-01-11 15:51:28 +0000.656174][2d2f] lipsync_debug: tsDelta -
timeDelta = -47
[013:023] [2025-01-11 15:51:28 +0000.656226][2d2f] lipsync_debug:result remaining
time:0
[013:023] [2025-01-11 15:51:28 +0000.656257][2d2f] dynamic updated lipsync delay:0
[013:053] [2025-01-11 15:51:28 +0000.686527][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:22
[013:053] [2025-01-11 15:51:28 +0000.686663][2d2f] lipsync_debug:result remaining
time:10
[013:125] [2025-01-11 15:51:28 +0000.757810][2d32] webrtc common_video plane
old ..... (1/1000) new: 2001 delete: 1992 diff: 9
[013:140] [2025-01-11 15:51:28 +0000.773006][2d32] video frame wait audio frame:
last_video_timestamp:462 last_audio_timestamp:347.891
[013:140] [2025-01-11 15:51:28 +0000.773162][2d32]
Warning(media_filter_player.cc:442): media pull video error, PullFrameStatus is 4
[013:603] [2025-01-11 15:51:29 +0000.236445][2d2f] lipsync_debug:displaying video
ts:59400 The current displayed video timestamp is:115905710
[013:907] [2025-01-11 15:51:29 +0000.539859][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[013:937] [2025-01-11 15:51:29 +0000.570478][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[013:947] [2025-01-11 15:51:29 +0000.579852][2d30] do work DoLipsync!
[013:947] [2025-01-11 15:51:29 +0000.580012][2d30] total delay:154 videoTsMS:990
audioTsMS:836 diff:154
[013:947] [2025-01-11 15:51:29 +0000.580064][2d30] Set the lipsync delay:154
[014:006] [2025-01-11 15:51:29 +0000.639361][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[014:301] [2025-01-11 15:51:29 +0000.933920][2ceb] Run AGC when audioType = 0
[014:417] [2025-01-11 15:51:30 +0000.49936][2d2f] lipsync_debug:displaying video
ts:118800 The current displayed video timestamp is:115906524
[014:595] [2025-01-11 15:51:30 +0000.228012][2d2f] DriveRender working... (1/200)
[014:685] [2025-01-11 15:51:30 +0000.317915][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:33 timeDelta:4 last video
ts:142560 frame->pts:145530 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[014:685] [2025-01-11 15:51:30 +0000.318082][2d2f] lipsync_debug: tsDelta -
timeDelta = 29
[014:930] [2025-01-11 15:51:30 +0000.563485][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[015:049] [2025-01-11 15:51:30 +0000.682177][2d2f] lipsync_debug:displaying video
ts:178200 The current displayed video timestamp is:115907156
[015:092] [2025-01-11 15:51:30 +0000.725082][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 102324
[015:092] [2025-01-11 15:51:30 +0000.725288][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[015:176] [2025-01-11 15:51:30 +0000.809024][2d30] The audio playback speed:100
(1/100)
[015:176] [2025-01-11 15:51:30 +0000.809410][2d30] lipsync_debug: The current
played audio timestamp is:102324(1/50)
[015:218] [2025-01-11 15:51:30 +0000.851328][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:29
[015:218] [2025-01-11 15:51:30 +0000.851535][2d2f] lipsync_debug:result remaining
time:10
[015:354] [2025-01-11 15:51:30 +0000.987467][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[015:643] [2025-01-11 15:51:31 +0000.276445][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[015:709] [2025-01-11 15:51:31 +0000.342192][2d2f] lipsync_debug:displaying video
ts:237600 The current displayed video timestamp is:115907816
[015:894] [2025-01-11 15:51:31 +0000.527665][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[015:969] [2025-01-11 15:51:31 +0000.602061][2d32] (1/100)OnReceiveVideoFrame,
key_frame: 0, pkt_pts: 297000pts ms:3.3e+06
[015:971] [2025-01-11 15:51:31 +0000.604402][2d32] OnVideoFrameReady(1/200)
[015:972] [2025-01-11 15:51:31 +0000.604992][2d2f] operator() frame->pts-
9223372036854775808 queue_ size: 13 (1/200)
[016:254] [2025-01-11 15:51:31 +0000.887109][2d2f] DriveRender working... (1/200)
[016:298] [2025-01-11 15:51:31 +0000.931048][2ceb] Run AGC when audioType = 0
[016:298] [2025-01-11 15:51:31 +0000.931327][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[016:342] [2025-01-11 15:51:31 +0000.975341][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:33 timeDelta:3 last video
ts:291060 frame->pts:294030 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[016:342] [2025-01-11 15:51:31 +0000.975521][2d2f] lipsync_debug: tsDelta -
timeDelta = 30
[016:405] [2025-01-11 15:51:32 +0000.38253][2d2f] lipsync_debug:displaying video
ts:297000 The current displayed video timestamp is:115908512
[016:409] [2025-01-11 15:51:32 +0000.42550][2d2f] lipsync_debug: render a video
frame
[016:438] [2025-01-11 15:51:32 +0000.71157][2d2f] lipsync_debug:result remaining
time:0
[016:438] [2025-01-11 15:51:32 +0000.71296][2d2f] dynamic updated lipsync delay:0
[016:696] [2025-01-11 15:51:32 +0000.329456][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[016:927] [2025-01-11 15:51:32 +0000.559726][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[017:025] [2025-01-11 15:51:32 +0000.657833][2d2f] lipsync_debug:displaying video
ts:356400 The current displayed video timestamp is:115909132
[017:187] [2025-01-11 15:51:32 +0000.820301][2d32] webrtc common_video plane
old ..... (1/1000) new: 3001 delete: 2992 diff: 9
[017:346] [2025-01-11 15:51:32 +0000.979108][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[017:407] [2025-01-11 15:51:33 +0000.40320][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 204692
[017:407] [2025-01-11 15:51:33 +0000.40460][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[017:441] [2025-01-11 15:51:33 +0000.73901][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:6
[017:441] [2025-01-11 15:51:33 +0000.74110][2d2f] lipsync_debug:result remaining
time:6
[017:491] [2025-01-11 15:51:33 +0000.124179][2d30] The audio playback speed:100
(1/100)
[017:491] [2025-01-11 15:51:33 +0000.124513][2d30] lipsync_debug: The current
played audio timestamp is:204692(1/50)
[017:647] [2025-01-11 15:51:33 +0000.280281][2d31] FilterPipeline2::OnAudioFrame
pts 214964 last_audio_timestamp_ 4874.47
[017:678] [2025-01-11 15:51:33 +0000.311650][2d2f] lipsync_debug:displaying video
ts:415800 The current displayed video timestamp is:115909785
[017:905] [2025-01-11 15:51:33 +0000.538285][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[017:914] [2025-01-11 15:51:33 +0000.546890][2d2f] DriveRender working... (1/200)
[018:006] [2025-01-11 15:51:33 +0000.638865][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:16 tsDelta:49 timeDelta:15 last
video ts:442530 frame->pts:445500 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[018:006] [2025-01-11 15:51:33 +0000.639027][2d2f] lipsync_debug: tsDelta -
timeDelta = 34
[018:063] [2025-01-11 15:51:33 +0000.696580][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[018:302] [2025-01-11 15:51:33 +0000.934758][2ceb] Run AGC when audioType = 0
[018:389] [2025-01-11 15:51:34 +0000.22491][2d2f] lipsync_debug:displaying video
ts:475200 The current displayed video timestamp is:115910496
[018:692] [2025-01-11 15:51:34 +0000.325463][2d22] av_read_frame AVERROR_EOF
[018:927] [2025-01-11 15:51:34 +0000.559914][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[019:016] [2025-01-11 15:51:34 +0000.650131][2d2f] lipsync_debug:displaying video
ts:534600 The current displayed video timestamp is:115911123
[019:053] [2025-01-11 15:51:34 +0000.686155][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[019:248] [2025-01-11 15:51:34 +0000.881194][2d22] OnReceiveEOF:
[019:248] [2025-01-11 15:51:34 +0000.881399][2d22] OnDecoderEOF time:115911355
enter.
[019:248] [2025-01-11 15:51:34 +0000.881390][2d32] (1/100)OnReceiveVideoFrame,
key_frame: 0, pkt_pts: 594000pts ms:6.6e+06
[019:248] [2025-01-11 15:51:34 +0000.881492][2d22] OnDecoderEOF   leave. expire time
= 0 ms
[019:248] [2025-01-11 15:51:34 +0000.881542][2d22] OnDecoderFinished:
OnDecoderFinished total decoder frames:117 this:0x6fdfcc9c00
[019:248] [2025-01-11 15:51:34 +0000.881565][2d20] CleanUp time:115911355    enter.
[019:252] [2025-01-11 15:51:34 +0000.884710][2d32] OnVideoFrameReady(1/200)
[019:252] [2025-01-11 15:51:34 +0000.884926][2d2f] operator() frame->pts-
9223372036854775808 queue_ size: 13 (1/200)
[019:254] [2025-01-11 15:51:34 +0000.886891][2d20] CleanUp leave. expire time = 6
ms
[019:254] [2025-01-11 15:51:34 +0000.886972][2d20] clean up
[019:254] [2025-01-11 15:51:34 +0000.887069][2d20] running_decoder_count:0max
count:2
[019:416] [2025-01-11 15:51:35 +0000.48784][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[019:547] [2025-01-11 15:51:35 +0000.179937][2d2f] DriveRender working... (1/200)
[019:624] [2025-01-11 15:51:35 +0000.257667][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:18
[019:625] [2025-01-11 15:51:35 +0000.258029][2d2f] lipsync_debug:result remaining
time:10
[019:642] [2025-01-11 15:51:35 +0000.275321][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:33 timeDelta:33 last
video ts:588060 frame->pts:591030 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[019:642] [2025-01-11 15:51:35 +0000.275460][2d2f] lipsync_debug: tsDelta -
timeDelta = 0
[019:675] [2025-01-11 15:51:35 +0000.308347][2d2f] lipsync_debug:displaying video
ts:594000 The current displayed video timestamp is:115911782
[019:680] [2025-01-11 15:51:35 +0000.313373][2d2f] lipsync_debug: render a video
frame
[019:708] [2025-01-11 15:51:35 +0000.341236][2d2f] lipsync_debug:result remaining
time:0
[019:708] [2025-01-11 15:51:35 +0000.341386][2d2f] dynamic updated lipsync delay:0
[019:727] [2025-01-11 15:51:35 +0000.359728][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 307060
[019:727] [2025-01-11 15:51:35 +0000.359856][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[019:817] [2025-01-11 15:51:35 +0000.450047][2d30] The audio playback speed:100
(1/100)
[019:817] [2025-01-11 15:51:35 +0000.450498][2d30] lipsync_debug: The current
played audio timestamp is:307060(1/50)
[019:837] [2025-01-11 15:51:35 +0000.470489][2d32] video frame wait audio frame:
last_video_timestamp:7161 last_audio_timestamp:7055.78
[019:838] [2025-01-11 15:51:35 +0000.470677][2d32]
Warning(media_filter_player.cc:442): media pull video error, PullFrameStatus is 4
[019:902] [2025-01-11 15:51:35 +0000.535201][2d33]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[020:296] [2025-01-11 15:51:35 +0000.929418][2cec] webrtc device:Handle the data
from samplesPerSec recording sample rate:48000 nSamples:480 nBytesPerSample:#
nChannels:#
[020:296] [2025-01-11 15:51:35 +0000.929602][2cec] _deviceSampleRate:48000
[020:300] [2025-01-11 15:51:35 +0000.933183][2ceb] Run AGC when audioType = 0
[020:300] [2025-01-11 15:51:35 +0000.933324][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[020:365] [2025-01-11 15:51:35 +0000.997850][2d2f] lipsync_debug:displaying video
ts:653400 The current displayed video timestamp is:115912472
[020:527] [2025-01-11 15:51:36 +0000.160541][2d31] PullAudioFrame: PullFrame audio
finished.
[020:527] [2025-01-11 15:51:36 +0000.160662][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:558] [2025-01-11 15:51:36 +0000.191066][2d31] Pull finished audio filter.
[020:558] [2025-01-11 15:51:36 +0000.191238][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:589] [2025-01-11 15:51:36 +0000.221742][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:619] [2025-01-11 15:51:36 +0000.252034][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:650] [2025-01-11 15:51:36 +0000.282715][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:680] [2025-01-11 15:51:36 +0000.313141][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:711] [2025-01-11 15:51:36 +0000.344147][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:741] [2025-01-11 15:51:36 +0000.374630][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:759] [2025-01-11 15:51:36 +0000.392672][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[020:772] [2025-01-11 15:51:36 +0000.405468][2d31] media player notify EOF, audio
PullFrameStatus is EOF
[020:795] [2025-01-11 15:51:36 +0000.427947][2d32] OnVideoTimer: media player
notify EOF, PullFrameStatus is EOF and render size is 0
[020:796] [2025-01-11 15:51:36 +0000.429312][2d32] Stop:
[020:798] [2025-01-11 15:51:36 +0000.431317][2d2f] operator(): Video Render
stopped.
[020:799] [2025-01-11 15:51:36 +0000.432355][2d32] Stop:
[020:800] [2025-01-11 15:51:36 +0000.433213][2d30] DeviceStop:
audioDevice_:0x6f33bc2000
[020:800] [2025-01-11 15:51:36 +0000.433289][2d30] OpenSlesOutput:Stop playout
[020:803] [2025-01-11 15:51:36 +0000.435918][2d30]   DeviceStop: Done
[020:803] [2025-01-11 15:51:36 +0000.436315][2d30]   Warning(audio_playback.cc:730):
operator(): Audio playback stopped.
[020:804] [2025-01-11 15:51:36 +0000.436878][2d32]    to reset filter graph
[020:804] [2025-01-11 15:51:36 +0000.436955][2d32]   RemoveVideoBackGroud: hava not
add video backgroud
[020:804] [2025-01-11 15:51:36 +0000.437030][2d32]    UnbindInputFilter 0x6f33647018
this 0x6f50b622a0
[020:804] [2025-01-11 15:51:36 +0000.437111][2d32]   ClearAllSource: remove source
filter, sourceid: 0x6fdfcc9c00
[020:804] [2025-01-11 15:51:36 +0000.437167][2d32]   DestoryDecoderByVideoSource
time:115912911 enter.
[020:804] [2025-01-11 15:51:36 +0000.437234][2d32] ~VideoBindDecoder time:115912911
enter.
[020:804] [2025-01-11 15:51:36 +0000.437399][2d32] CleanUp time:115912911    enter.
[020:805] [2025-01-11 15:51:36 +0000.437793][2d32] CleanUp    leave. expire time = 1
ms
[020:805] [2025-01-11 15:51:36 +0000.437859][2d32] CleanUp time:115912912    enter.
[020:805] [2025-01-11 15:51:36 +0000.438385][2d32] CleanUp   leave. expire time = 0
ms
[020:806] [2025-01-11 15:51:36 +0000.439358][2d32] ~VideoBindDecoder   leave. expire
time = 2 ms
[020:806] [2025-01-11 15:51:36 +0000.439451][2d32] DestoryDecoderByVideoSource
leave. expire time = 2 ms
[020:806] [2025-01-11 15:51:36 +0000.439522][2d32] UnbindInputFilter 0x6f33646dd8
this 0x6f33647018
[020:806] [2025-01-11 15:51:36 +0000.439592][2d32] UnbindInputFilter 0x6fdfa03998
this 0x6f33646dd8
[020:807] [2025-01-11 15:51:36 +0000.439673][2d32] UnbindInputFilter 0x6fdfcc9e20
this 0x6fdfa03998
[020:807] [2025-01-11 15:51:36 +0000.439753][2d32] UnbindInputFilter 0x6f33646f58
this 0x6f336470d8
[020:807] [2025-01-11 15:51:36 +0000.439844][2d32] UnbindInputFilter 0x6f33646e98
this 0x6f33646f58
[020:807] [2025-01-11 15:51:36 +0000.439909][2d32] UnbindInputFilter 0x6fdfcc9e20
this 0x6f33646e98
[020:807] [2025-01-11 15:51:36 +0000.439987][2d32] to reset filter graph clear end
[020:807] [2025-01-11 15:51:36 +0000.440035][2d32] AddVideoSource: add video
source:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4,
delay: 0
[020:807] [2025-01-11 15:51:36 +0000.440110][2d32] CreateDecoderByVideoSource
time:115912914 enter.
[020:807] [2025-01-11 15:51:36 +0000.440426][2d32] running_decoder_count:0max
count:2
[020:807] [2025-01-11 15:51:36 +0000.440484][2d32] Start decoding file
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4,
sync_mode: 1, seek_pos: 0, delay: 0, this:0x6fec75fc00
[020:812] [2025-01-11 15:51:36 +0000.445383][2d35] FFMpeg=>
[mov,mp4,m4a,3gp,3g2,mj2 @ 0x6f637ea000] Current FFmpeg version git-2021-06-16-
97cea6f
[020:917] [2025-01-11 15:51:36 +0000.550425][2d35] OnStreamInfoFound: video
duration_: 7749
[020:919] [2025-01-11 15:51:36 +0000.551878][2d32] start decoder
[020:919] [2025-01-11 15:51:36 +0000.551951][2d32] CreateDecoderByVideoSource
leave. expire time = 112 ms
[020:919] [2025-01-11 15:51:36 +0000.552000][2d32] BindInputFilter 0x6fec6b0720
this 0x6f47ae9618
[020:919] [2025-01-11 15:51:36 +0000.552037][2d32] BindInputFilter 0x6f47ae9618
this 0x6f47ae96d8
[020:919] [2025-01-11 15:51:36 +0000.552059][2d36] NO get frame from decoder
[020:919] [2025-01-11 15:51:36 +0000.552075][2d32] BindInputFilter 0x6fec6b0720
this 0x6f47ae9798
[020:919] [2025-01-11 15:51:36 +0000.552167][2d32] BindInputFilter 0x6f47ae9798
this 0x6f47ae9858
[020:919] [2025-01-11 15:51:36 +0000.552206][2d32] AddVideoSource: source filter
list is empty, add first:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[020:919] [2025-01-11 15:51:36 +0000.552246][2d32] BindInputFilter 0x6f47ae96d8
this 0x6f47ae9918
[020:919] [2025-01-11 15:51:36 +0000.552301][2d32] BindInputFilter 0x6f47ae9918
this 0x6f50b622a0
[020:919] [2025-01-11 15:51:36 +0000.552339][2d32] BindInputFilter 0x6f47ae9858
this 0x6f47ae99d8
[020:919] [2025-01-11 15:51:36 +0000.552387][2d32] AddVideoSource: END add video
source:
/data/user/0/com.android.camera/no_backup/milive//record_video_115900322.mp4
[020:919] [2025-01-11 15:51:36 +0000.552417][2d32] to reset filter graph end
[020:919] [2025-01-11 15:51:36 +0000.552453][2d32] Start:
[020:919] [2025-01-11 15:51:36 +0000.552532][2d2f] operator(): video render
started.
[020:919] [2025-01-11 15:51:36 +0000.552595][2d32] Start:
[020:920] [2025-01-11 15:51:36 +0000.552808][2d36] NO get frame from decoder
[020:920] [2025-01-11 15:51:36 +0000.552822][2d30] Reset: volume estimator reset
frequency : 44100 history size(ms): 5000
[020:920] [2025-01-11 15:51:36 +0000.552891][2d30] Reset: history total size: 98
[020:920] [2025-01-11 15:51:36 +0000.552922][2d30] DeviceStart:
audioDevice_:0x6f33bc2000
[020:920] [2025-01-11 15:51:36 +0000.552958][2d30] DeviceStart: time profile
playout init before
[020:920] [2025-01-11 15:51:36 +0000.552989][2d30] DeviceStart: time profile
playout init end
[020:920] [2025-01-11 15:51:36 +0000.553027][2d30] OpenSlesOutput:Start playout
[020:920] [2025-01-11 15:51:36 +0000.553705][2d30] Create the opensl output player.
streamtype before:0
[020:921] [2025-01-11 15:51:36 +0000.553795][2d30] Create the opensl output player.
streamtype after:3
[020:921] [2025-01-11 15:51:36 +0000.554374][2d36] NO get frame from decoder
[020:923] [2025-01-11 15:51:36 +0000.555722][2d36] NO get frame from decoder
[020:925] [2025-01-11 15:51:36 +0000.558049][2d36] NO get frame from decoder
[020:927] [2025-01-11 15:51:36 +0000.560180][2d36] NO get frame from decoder
[020:929] [2025-01-11 15:51:36 +0000.561819][2d36] NO get frame from decoder
[020:930] [2025-01-11 15:51:36 +0000.563460][2d36] NO get frame from decoder
[020:941] [2025-01-11 15:51:36 +0000.574444][2d30] Enqueue opensl buffer size:1764
[021:051] [2025-01-11 15:51:36 +0000.683833][2d30] DeviceStart: time profile
playout all end
[021:051] [2025-01-11 15:51:36 +0000.684000][2d30] operator(): Audio playback
started.
[021:054] [2025-01-11 15:51:36 +0000.687367][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[021:086] [2025-01-11 15:51:36 +0000.719470][2d30] First audio frame rendering
[021:087] [2025-01-11 15:51:36 +0000.720003][2d2f] lipsync_debug: First video frame
rendering.
[021:088] [2025-01-11 15:51:36 +0000.720854][2d31] audio frame wait video
frame.last_video_timestamp:33 last_audio_timestamp:138.957
[021:088] [2025-01-11 15:51:36 +0000.721224][2d31]
Warning(media_filter_player.cc:409): media pull audio frame failed:4
[021:126] [2025-01-11 15:51:36 +0000.759635][2d40]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[021:184] [2025-01-11 15:51:36 +0000.817530][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[021:270] [2025-01-11 15:51:36 +0000.903322][2d2f] lipsync_debug:displaying video
ts:17820 The current displayed video timestamp is:115913377
[021:360] [2025-01-11 15:51:36 +0000.992920][2d2f] DriveRender working... (1/200)
[021:601] [2025-01-11 15:51:37 +0000.234686][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:33 timeDelta:33 last
video ts:44550 frame->pts:47520 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[021:602] [2025-01-11 15:51:37 +0000.234815][2d2f] lipsync_debug: tsDelta -
timeDelta = 0
[021:704] [2025-01-11 15:51:37 +0000.337140][2d32] webrtc common_video plane
old ..... (1/1000) new: 4001 delete: 3992 diff: 9
[021:932] [2025-01-11 15:51:37 +0000.564993][2d2f] lipsync_debug:displaying video
ts:77220 The current displayed video timestamp is:115914039
[022:132] [2025-01-11 15:51:37 +0000.765131][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:46
[022:132] [2025-01-11 15:51:37 +0000.765262][2d2f] lipsync_debug:result remaining
time:10
[022:165] [2025-01-11 15:51:37 +0000.798518][2d40]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[022:299] [2025-01-11 15:51:37 +0000.932637][2ceb] Run AGC when audioType = 0
[022:410] [2025-01-11 15:51:38 +0000.43119][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 67540
[022:410] [2025-01-11 15:51:38 +0000.43282][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[022:465] [2025-01-11 15:51:38 +0000.98362][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[022:478] [2025-01-11 15:51:38 +0000.110775][2d30] The audio playback speed:100
(1/100)
[022:478] [2025-01-11 15:51:38 +0000.110912][2d30] lipsync_debug: The current
played audio timestamp is:67540(1/50)
[022:502] [2025-01-11 15:51:38 +0000.135283][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[022:714] [2025-01-11 15:51:38 +0000.347020][2d2f] lipsync_debug:displaying video
ts:136620 The current displayed video timestamp is:115914821
[022:972] [2025-01-11 15:51:38 +0000.604910][2d32] (1/100)OnReceiveVideoFrame,
key_frame: 0, pkt_pts: 196020pts ms:2.178e+06
[022:974] [2025-01-11 15:51:38 +0000.607483][2d32] OnVideoFrameReady(1/200)
[022:975] [2025-01-11 15:51:38 +0000.607725][2d2f] operator() frame->pts-
9223372036854775808 queue_ size: 13 (1/200)
[023:045] [2025-01-11 15:51:38 +0000.677756][2d2f] DriveRender working... (1/200)
[023:134] [2025-01-11 15:51:38 +0000.766688][2d40]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[023:278] [2025-01-11 15:51:38 +0000.911616][2d2f]
lipsync_debug:lipsync_threadhold: lipsync_delta:0 tsDelta:33 timeDelta:24 last
video ts:184140 frame->pts:187110 pkt_dts:-9223372036854775808 pkt_pts:-
9223372036854775808
[023:279] [2025-01-11 15:51:38 +0000.911812][2d2f] lipsync_debug: tsDelta -
timeDelta = 9
[023:387] [2025-01-11 15:51:39 +0000.20455][2d2f] lipsync_debug:displaying video
ts:196020 The current displayed video timestamp is:115915494
[023:399] [2025-01-11 15:51:39 +0000.32267][2d2f] lipsync_debug: render a video
frame
[023:452] [2025-01-11 15:51:39 +0000.85002][2d2f] lipsync_debug:result remaining
time:0
[023:452] [2025-01-11 15:51:39 +0000.85086][2d2f] dynamic updated lipsync delay:0
[023:857] [2025-01-11 15:51:39 +0000.490160][2d32] Error(video_source.cc:430): wait
decoder a frame: 0
[024:046] [2025-01-11 15:51:39 +0000.679459][2d2f] lipsync_debug:displaying video
ts:255420 The current displayed video timestamp is:115916153
[024:166] [2025-01-11 15:51:39 +0000.798817][2d40]
Warning(audio_device_generic.cc:206): No Audio Processing is done for output datas.
[024:170] [2025-01-11 15:51:39 +0000.802896][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[024:299] [2025-01-11 15:51:39 +0000.932071][2ceb] Run AGC when audioType = 0
[024:299] [2025-01-11 15:51:39 +0000.932215][2ceb]
AudioDeviceDataHandler::MixAndSendOutFrame
[024:337] [2025-01-11 15:51:39 +0000.970708][2d2f] lipsync_debug:remaining time:10
tsDelta - timeDelta:9
[024:338] [2025-01-11 15:51:39 +0000.971076][2d2f] lipsync_debug:result remaining
time:9
[024:710] [2025-01-11 15:51:40 +0000.342751][2d2f] lipsync_debug:displaying video
ts:314820 The current displayed video timestamp is:115916817
[024:712] [2025-01-11 15:51:40 +0000.345572][2d2f] DriveRender working... (1/200)
[024:736] [2025-01-11 15:51:40 +0000.368757][2d31] (1/100)OnReceiveAudioFrame,
key_frame: 1, pkt_pts: 169908
[024:736] [2025-01-11 15:51:40 +0000.369536][2d31] OnAudioFrameReady frame->pts=-
9223372036854775808 (1/100)
[024:767] [2025-01-11 15:51:40 +0000.400165][1972]
Java_com_xiaomi_recordmediaprocess_EffectMediaPlayer_DestructMediaPlayerJni:
[024:768] [2025-01-11 15:51:40 +0000.400696][2d30] Stop:
[024:768] [2025-01-11 15:51:40 +0000.400861][2d2f] operator(): Video Render
stopped.
[024:768] [2025-01-11 15:51:40 +0000.400998][2d30] Stop:
[024:768] [2025-01-11 15:51:40 +0000.401045][2d30] DeviceStop:
audioDevice_:0x6f33bc2000
[024:768] [2025-01-11 15:51:40 +0000.401077][2d30] OpenSlesOutput:Stop playout
[024:770] [2025-01-11 15:51:40 +0000.402862][2d30] DeviceStop: Done
[024:770] [2025-01-11 15:51:40 +0000.403191][2d30] Warning(audio_playback.cc:730):
operator(): Audio playback stopped.
[024:770] [2025-01-11 15:51:40 +0000.403258][2d30] operator(): Stop now!
[024:770] [2025-01-11 15:51:40 +0000.403602][1972] Stop:
[024:771] [2025-01-11 15:51:40 +0000.403673][2d30] Warning(audio_playback.cc:715):
operator(): Audio playback not started. Operation cancelled.
[024:771] [2025-01-11 15:51:40 +0000.403789][1972] DeviceRelease:
audioDevice_:0x6f33bc2000
[024:771] [2025-01-11 15:51:40 +0000.403826][1972] OpenSlesOutput:Stop playout
[024:771] [2025-01-11 15:51:40 +0000.403866][1972] DeviceRelease: Release the
webrtc device object.
[024:771] [2025-01-11 15:51:40 +0000.404043][1972] OpenSlesOutput destruct.
[024:771] [2025-01-11 15:51:40 +0000.404118][1972] DeviceRelease: Done
[024:773] [2025-01-11 15:51:40 +0000.406133][1972] Stop:
[024:773] [2025-01-11 15:51:40 +0000.406242][2d2f] Warning(video_render.cc:349):
operator(): video render not started. Operation cancelled.
[024:776] [2025-01-11 15:51:40 +0000.409072][1972] global uninited!
[024:782] [2025-01-11 15:51:40 +0000.414888][1972]
Java_com_xiaomi_recordmediaprocess_MediaEffectGraph_DestructMediaEffectGraphJni:
[024:782] [2025-01-11 15:51:40 +0000.415012][1972] ~MediaFilterGraph:
MediaFilterGraph destruct
[024:782] [2025-01-11 15:51:40 +0000.415046][1972] RemoveVideoBackGroud: hava not
add video backgroud
[024:782] [2025-01-11 15:51:40 +0000.415093][1972] UnbindInputFilter 0x6f47ae9918
this 0x6f50b622a0
[024:782] [2025-01-11 15:51:40 +0000.415135][1972] ClearAllSource: remove source
filter, sourceid: 0x6fec6b0500
[024:782] [2025-01-11 15:51:40 +0000.415161][1972] DestoryDecoderByVideoSource
time:115916889 enter.
[024:782] [2025-01-11 15:51:40 +0000.415193][1972] ~VideoBindDecoder time:115916889
enter.
[024:782] [2025-01-11 15:51:40 +0000.415352][1972] CleanUp time:115916889   enter.
[024:785] [2025-01-11 15:51:40 +0000.418192][1972] CleanUp   leave. expire time = 3
ms
[024:785] [2025-01-11 15:51:40 +0000.418446][1972] CleanUp time:115916892    enter.
[024:786] [2025-01-11 15:51:40 +0000.418854][1972] CleanUp   leave. expire time = 1
ms
[024:786] [2025-01-11 15:51:40 +0000.419224][1972] ~VideoBindDecoder   leave. expire
time = 4 ms
[024:786] [2025-01-11 15:51:40 +0000.419263][1972] DestoryDecoderByVideoSource
leave. expire time = 4 ms
[024:786] [2025-01-11 15:51:40 +0000.419310][1972] UnbindInputFilter 0x6f47ae96d8
this 0x6f47ae9918
[024:786] [2025-01-11 15:51:40 +0000.419368][1972] UnbindInputFilter 0x6f47ae9618
this 0x6f47ae96d8
[024:786] [2025-01-11 15:51:40 +0000.419422][1972] UnbindInputFilter 0x6fec6b0720
this 0x6f47ae9618
[024:786] [2025-01-11 15:51:40 +0000.419480][1972] UnbindInputFilter 0x6f47ae9858
this 0x6f47ae99d8
[024:786] [2025-01-11 15:51:40 +0000.419517][1972] UnbindInputFilter 0x6f47ae9798
this 0x6f47ae9858
[024:786] [2025-01-11 15:51:40 +0000.419572][1972] UnbindInputFilter 0x6fec6b0720
this 0x6f47ae9798
[024:787] [2025-01-11 15:51:40 +0000.420460][1972] ~GLESContext:
[024:788] [2025-01-11 15:51:40 +0000.421045][1972] GLESContext destruct finish.
[024:788] [2025-01-11 15:51:40 +0000.421134][1972] filter: filter pipeline destroy
this: 0x6f50b62200
[025:882] [2025-01-11 15:51:41 +0000.515159][2ceb] haveEnoughProcessedData
internalAudioStream.length: 0
[026:300] [2025-01-11 15:51:41 +0000.932803][2ceb] Run AGC when audioType = 0
[026:683] [2025-01-11 15:51:42 +0000.315806][19e8]
Java_com_xiaomi_recordmediaprocess_MediaEffectCamera_DestructMediaEffectCameraJni:
[026:683] [2025-01-11 15:51:42 +0000.315975][19e8] ~MediaFilterCamera
time:115918790 enter.
[026:683] [2025-01-11 15:51:42 +0000.316023][19e8] StopPullFrameAndWriteMp4
time:115918790 enter.
[026:683] [2025-01-11 15:51:42 +0000.316429][2ce6] stop recording
[026:683] [2025-01-11 15:51:42 +0000.316478][2ce6] we have stopped recording before
[026:683] [2025-01-11 15:51:42 +0000.316562][19e8] StopPullFrameAndWriteMp4 leave.
expire time = 0 ms
[026:684] [2025-01-11 15:51:42 +0000.317028][19e8] DestroyPipelineAndFilters
time:115918791 enter.
[026:684] [2025-01-11 15:51:42 +0000.317077][19e8] DestroyPipelineAndFilters
leave. expire time = 0 ms
[026:684] [2025-01-11 15:51:42 +0000.317127][19e8] AppDeviceManagerStopMic
[026:684] [2025-01-11 15:51:42 +0000.317188][19e8] AppDeviceManagerStopMic::mic
status is on
[026:684] [2025-01-11 15:51:42 +0000.317222][19e8] Try to stop webrtc device.
[026:684] [2025-01-11 15:51:42 +0000.317250][19e8] Set record state to
stopped.threadID:6632
[026:717] [2025-01-11 15:51:42 +0000.350664][19e8] Try to destory the
recorder.threadID:6632
[026:918] [2025-01-11 15:51:42 +0000.550817][19e8] DestroyAudioRecorder:
[026:918] [2025-01-11 15:51:42 +0000.550896][19e8] Cleanup the buffer queue in the
recorder.
[026:918] [2025-01-11 15:51:42 +0000.550923][19e8] Destroy the recorder.
[026:918] [2025-01-11 15:51:42 +0000.551577][19e8] StopRecording finished.
[026:918] [2025-01-11 15:51:42 +0000.551627][19e8] The stop recording success.
[026:918] [2025-01-11 15:51:42 +0000.551652][19e8]
AudioDeviceDataHandler::StopMicProcess
[026:923] [2025-01-11 15:51:42 +0000.555719][19e8] AppDeviceManager stop.
[026:923] [2025-01-11 15:51:42 +0000.555853][19e8] Rlease the webrtc device object.
[026:923] [2025-01-11 15:51:42 +0000.556144][19e8] OpenSlesInput destruct.
[026:923] [2025-01-11 15:51:42 +0000.556211][19e8] OpenSlesOutput destruct.
[026:947] [2025-01-11 15:51:42 +0000.580646][19e8] EventDispatchServerScope
destruct
[026:948] [2025-01-11 15:51:42 +0000.580760][19e8]
Warning(physicalsocketserver.cc:1150): PhysicalSocketServer destruct
[026:948] [2025-01-11 15:51:42 +0000.580836][19e8] ~AudioDeviceDataHandler
[026:948] [2025-01-11 15:51:42 +0000.581342][19e8] AppDeviceManagerDestruct.
[026:948] [2025-01-11 15:51:42 +0000.581380][19e8] ~AppDeviceManager:
[026:948] [2025-01-11 15:51:42 +0000.581444][19e8] ~CpuOverloadDetect
time:115919055 enter.
[026:949] [2025-01-11 15:51:42 +0000.581746][19e8] ~CpuOverloadDetect    leave.
expire time = 1 ms
[026:963] [2025-01-11 15:51:42 +0000.595737][19e8] Destruct BeautifyReader begin...
[026:963] [2025-01-11 15:51:42 +0000.595821][19e8] Destruct BeautifyReader done.
[026:963] [2025-01-11 15:51:42 +0000.595860][19e8] ~MediaFilterCamera leave.
expire time = 280 ms
[026:963] [2025-01-11 15:51:42 +0000.596024][19e8] ~GLESContext:
[026:965] [2025-01-11 15:51:42 +0000.598102][19e8] GLESContext destruct finish.
[026:965] [2025-01-11 15:51:42 +0000.598265][19e8] ~RecordingSessionInternalSimple:
RecordingSessionInternalSimple::~RecordingSessionInternalSimple
[026:968] [2025-01-11 15:51:42 +0000.601664][19e8] global uninited!
[026:972] [2025-01-11 15:51:42 +0000.604920][19e8] clear AppID 50011
[026:972] [2025-01-11 15:51:42 +0000.605012][19e8] UnRegister JVM for Xplatform
util
[026:972] [2025-01-11 15:51:42 +0000.605066][19e8] Dispose::unregister webrtc video
engine
[026:972] [2025-01-11 15:51:42 +0000.605093][19e8] Init the android object with
jvm:0x0 env:0x0 context:0x0
[026:972] [2025-01-11 15:51:42 +0000.605126][19e8] SetAndroidMediaCodecObjects
time:115919079 enter.