search

chenxiaolong / BCR

A Basic Call Recorder for rooted Android devices
GNU General Public License v3.0
1.73k stars 113 forks source link

BUG: BCR forces save files into Android/data/com.chiller3.bcr/files despite the settings #584

Closed ChipZhang closed 1 month ago

ChipZhang commented 2 months ago

My system is Android AOSP 14.

Go to BCR settings => Output directory => Change directory => Pick a custom path in the internal storage => Close BCR => Make a call => The recordings are still saved in Android/data/com.chiller3.bcr/files

chenxiaolong commented 2 months ago

Please enable debug logging by long pressing BCR's version number, make a call, and then upload the log file it creates in the output directory (/sdcard/Android/data/com.chiller3.bcr/files in your case).

For performance reasons, BCR always first records to the /sdcard/Android/data/com.chiller3.bcr/files and the moves it to the selected directory after the call completes. I'm guessing the file move is failing on your device for some reason.

iwconfig commented 2 months ago

I've not enabled debug logging yet but i noticed that, for me at least, only longer recordings fail to move both the audio and the json metadata. No problem with short recordings. As of the last test I did, 441kB worked, but 2.68MB did not.

LineageOS 21.

iwconfig commented 2 months ago

Here's the log file. It's quite long because i did not restart bcr before running in debug mode I guess. Anyway you see at the end there it says something about inability to efficiently move the files. The log file moves/writes to the custom directory without issue though.

The last recording, which did fail to move:

and the recording before it, which did not fail to move:

--------- beginning of system
09-03 14:26:23.972  3288  3288 I ActivityThread: Removing dead content provider:android.content.ContentProviderProxy@6f249e5
--------- beginning of main
09-03 14:40:39.616  3288  3288 D VRI[SettingsActivity]: visibilityChanged oldVisibility=true newVisibility=false
09-03 14:40:43.269  3288  3288 D RecorderInCallService: onCallAdded: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_RESPOND_VIA_TEXT CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:40:43.270  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_RESPOND_VIA_TEXT CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:40:44.844  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:40:44.844  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:40:44.895  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:40:44.895  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: RINGING, details: [id: TC@12, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:40:44.901  3288  3288 D RecorderInCallService: onStateChanged: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 4
09-03 14:40:44.901  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 4, 4
09-03 14:40:44.910  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:40:44.910  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:40:44.913  3288  3288 I OutputFilenameGenerator: Filename template: {date}[_{direction}|][_sim{sim_slot}|][_{phone_number}|][_[{contact_name}|{caller_name}|{call_log_name}]|]
09-03 14:40:44.914  3288  3288 I RecorderThread/58: Created thread for call: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:40:44.915  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.922  3288  3288 D RecorderThread/58: Holding state updated: false
09-03 14:40:44.922  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:40:44.922  3288 15679 D RecorderThread/58: Starting log file (1.69)
09-03 14:40:44.926  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:40:44.926  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:40:44.926  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 4
09-03 14:40:44.926  3288 15679 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.927  3288  3288 D RecorderThread/58: Holding state updated: false
09-03 14:40:44.927  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)>.log with MIME type text/plain in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:40:44.927  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.928  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.928  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.928  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:40:44.932  3288 15679 I RecorderThread/58: Recording thread started
09-03 14:40:44.932  3288 15679 I RecorderThread/58: Evaluating record rules for 1 phone number(s)
09-03 14:40:44.933  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:40:44.933  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:40:44.933  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: ACTIVE, details: [id: TC@12, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 4
09-03 14:40:44.933  3288  3288 D RecorderThread/58: Holding state updated: false
09-03 14:40:44.933  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.935  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.936  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.944  3288 15679 D RecorderThread/58: Keep state updated: true
09-03 14:40:44.945  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.945  3288 15679 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:44.945  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)> with MIME type audio/ogg in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:40:44.946  3288 15679 D RecorderThread/58: AudioRecord minimum buffer size: 1280
09-03 14:40:44.948  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:40:45.171  3288 15679 D RecorderThread/58: AudioRecord initial buffer size: 7680
09-03 14:40:45.171  3288 15679 D RecorderThread/58: AudioRecord format: AudioFormat: props=7 enc=2 chan=0x10 chan_index=0x0 rate=16000
09-03 14:40:45.216  3288 15679 D MediaCodecEncoder: Audio encoder: c2.android.opus.encoder
09-03 14:40:45.221  3288 15718 D CCodec  : allocate(c2.android.opus.encoder)
09-03 14:40:45.224  3288 15718 I CCodec  : Created component [c2.android.opus.encoder]
09-03 14:40:45.224  3288 15718 D CCodecConfig: read media type: audio/opus
09-03 14:40:45.226  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
09-03 14:40:45.227  3288 15718 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
09-03 14:40:45.229  3288 15718 I CCodecConfig: query failed after returning 9 values (BAD_INDEX)
09-03 14:40:45.229  3288 15718 D CCodecConfig: c2 config diff is Dict {
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 algo.bitrate-mode.value = 3
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 algo.complexity.value = 10
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 coded.bitrate.value = 128000
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 input.buffers.max-size.value = 3840
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 input.delay.value = 0
09-03 14:40:45.229  3288 15718 D CCodecConfig:   string input.media-type.value = "audio/raw"
09-03 14:40:45.229  3288 15718 D CCodecConfig:   string output.media-type.value = "audio/opus"
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 raw.channel-count.value = 1
09-03 14:40:45.229  3288 15718 D CCodecConfig:   c2::u32 raw.sample-rate.value = 48000
09-03 14:40:45.229  3288 15718 D CCodecConfig: }
09-03 14:40:45.231  3288 15679 D MediaCodec: shapeMediaFormat: deltas(0): AMessage(what = 0x00000000) = {
09-03 14:40:45.231  3288 15679 D MediaCodec:   }
09-03 14:40:45.231  3288 15718 D CCodec  : [c2.android.opus.encoder] buffers are bound to CCodec for this session
09-03 14:40:45.231  3288 15718 D CCodecConfig: no c2 equivalents for x-frame-size-in-bytes
09-03 14:40:45.231  3288 15718 D CCodecConfig: no c2 equivalents for flags
09-03 14:40:45.231  3288 15718 D CCodecConfig: no c2 equivalents for encoder
09-03 14:40:45.232  3288 15718 D CCodecConfig: c2 config diff is   c2::u32 coded.bitrate.value = 48000
09-03 14:40:45.232  3288 15718 D CCodecConfig:   c2::u32 raw.sample-rate.value = 16000
09-03 14:40:45.232  3288 15718 W Codec2Client: query -- param skipped: index = 1107298332.
09-03 14:40:45.232  3288 15718 D CCodec  : encoding statistics level = 0
09-03 14:40:45.232  3288 15718 D CCodec  : setup formats input: AMessage(what = 0x00000000) = {
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t channel-count = 1
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t flac-compression-level = 10
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t max-input-size = 3840
09-03 14:40:45.232  3288 15718 D CCodec  :   string mime = "audio/raw"
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t sample-rate = 16000
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t android._config-pcm-encoding = 2
09-03 14:40:45.232  3288 15718 D CCodec  : }
09-03 14:40:45.232  3288 15718 D CCodec  : setup formats output: AMessage(what = 0x00000000) = {
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t bitrate = 48000
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t bitrate-mode = 1
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t channel-count = 1
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t flac-compression-level = 10
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t max-bitrate = 48000
09-03 14:40:45.232  3288 15718 D CCodec  :   string mime = "audio/opus"
09-03 14:40:45.232  3288 15718 D CCodec  :   int32_t sample-rate = 16000
09-03 14:40:45.232  3288 15718 D CCodec  : }
09-03 14:40:45.232  3288 15718 I CCodecConfig: query failed after returning 9 values (BAD_INDEX)
09-03 14:40:45.234  3288 15718 W Codec2Client: query -- param skipped: index = 1342179345.
09-03 14:40:45.234  3288 15718 W Codec2Client: query -- param skipped: index = 2415921170.
09-03 14:40:45.234  3288 15718 W Codec2Client: query -- param skipped: index = 3254792206.
09-03 14:40:45.234  3288 15718 W Codec2Client: query -- param skipped: index = 3254792194.
09-03 14:40:45.235  3288 15718 D CCodecBufferChannel: [c2.android.opus.encoder#227] Created input block pool with allocatorID 16 => poolID 21 - OK (0)
09-03 14:40:45.237  3288 15718 I CCodecBufferChannel: [c2.android.opus.encoder#227] Created output block pool with allocatorID 16 => poolID 87 - OK
09-03 14:40:45.238  3288 15718 D CCodecBufferChannel: [c2.android.opus.encoder#227] Configured output block pool ids 87 => OK
09-03 14:40:45.244  3288 15679 D RecorderThread/58: Buffer is 2560 bytes, 1280 frames, 80000000ns
09-03 14:40:45.332  3288 15679 D MediaCodecEncoder: Output format changed to: {max-bitrate=48000, sample-rate=16000, mime=audio/opus, channel-count=1, bitrate-mode=1, bitrate=48000, flac-compression-level=10, csd-0=java.nio.HeapByteBuffer[pos=0 lim=83 cap=83]}
09-03 14:40:45.333  3288 15679 I MetaDataBase: srte: (int32_t) 16000
09-03 14:40:45.333  3288 15679 I MetaDataBase: ospr: (unknown type 0, size 8)
09-03 14:40:45.333  3288 15679 I MetaDataBase: 00000000:  00 b4 c4 04 00 00 00 00                           ........
09-03 14:40:45.333  3288 15679 I MetaDataBase: ohdr: (unknown type 0, size 19)
09-03 14:40:45.333  3288 15679 I MetaDataBase: 00000000:  4f 70 75 73 48 65 61 64  01 01 68 00 80 3e 00 00  OpusHead..h..>..
09-03 14:40:45.333  3288 15679 I MetaDataBase: 00000010:  00 00 00                                          ...
09-03 14:40:45.333  3288 15679 I MetaDataBase: ocod: (unknown type 0, size 8)
09-03 14:40:45.333  3288 15679 I MetaDataBase: 00000000:  a0 2e 63 00 00 00 00 00                           ..c.....
09-03 14:40:45.333  3288 15679 I MetaDataBase: mxBr: (int32_t) 48000
09-03 14:40:45.333  3288 15679 I MetaDataBase: mime: (char*) audio/opus
09-03 14:40:45.333  3288 15679 I MetaDataBase: csd0: no type, size 83)
09-03 14:40:45.333  3288 15679 I MetaDataBase: brte: (int32_t) 48000
09-03 14:40:45.333  3288 15679 I MetaDataBase: #chn: (int32_t) 1
09-03 14:40:50.414  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 106/111 (recycle/alloc) - 6/213 (fetch/transfer)
09-03 14:40:55.665  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 215/220 (recycle/alloc) - 6/431 (fetch/transfer)
09-03 14:41:00.846  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 323/328 (recycle/alloc) - 6/647 (fetch/transfer)
09-03 14:41:05.930  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 429/434 (recycle/alloc) - 6/859 (fetch/transfer)
09-03 14:41:11.264  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 540/545 (recycle/alloc) - 7/1081 (fetch/transfer)
09-03 14:41:16.425  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 648/653 (recycle/alloc) - 8/1298 (fetch/transfer)
09-03 14:41:21.649  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 757/762 (recycle/alloc) - 8/1515 (fetch/transfer)
09-03 14:41:26.830  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 865/870 (recycle/alloc) - 8/1731 (fetch/transfer)
09-03 14:41:32.070  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 974/979 (recycle/alloc) - 9/1949 (fetch/transfer)
09-03 14:41:37.250  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1082/1087 (recycle/alloc) - 9/2165 (fetch/transfer)
09-03 14:41:42.496  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1191/1196 (recycle/alloc) - 10/2383 (fetch/transfer)
09-03 14:41:47.685  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1299/1304 (recycle/alloc) - 11/2599 (fetch/transfer)
09-03 14:41:52.828  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1406/1411 (recycle/alloc) - 11/2813 (fetch/transfer)
09-03 14:41:57.996  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1514/1519 (recycle/alloc) - 11/3029 (fetch/transfer)
09-03 14:42:03.194  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1622/1627 (recycle/alloc) - 11/3245 (fetch/transfer)
09-03 14:42:08.332  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1729/1734 (recycle/alloc) - 12/3459 (fetch/transfer)
09-03 14:42:13.489  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1837/1842 (recycle/alloc) - 12/3675 (fetch/transfer)
09-03 14:42:18.617  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1943/1948 (recycle/alloc) - 12/3888 (fetch/transfer)
09-03 14:42:23.805  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2052/2057 (recycle/alloc) - 14/4105 (fetch/transfer)
09-03 14:42:28.947  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2159/2164 (recycle/alloc) - 14/4319 (fetch/transfer)
09-03 14:42:34.163  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2267/2272 (recycle/alloc) - 14/4535 (fetch/transfer)
09-03 14:42:39.263  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2374/2379 (recycle/alloc) - 14/4749 (fetch/transfer)
09-03 14:42:44.483  3288 15718 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2482/2487 (recycle/alloc) - 15/4965 (fetch/transfer)
09-03 14:42:47.206  3288  3288 D RecorderInCallService: onStateChanged: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 7
09-03 14:42:47.206  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 7, 7
09-03 14:42:47.206  3288  3288 D RecorderThread/58: Requested cancellation
09-03 14:42:47.207  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:42:47.207  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 7
09-03 14:42:47.207  3288  3288 D RecorderInCallService: onCallDestroyed: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:42:47.207  3288  3288 D RecorderInCallService: onCallRemoved: Call [id: TC@12, state: DISCONNECTED, details: [id: TC@12, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:42:47.235  3288 15679 D RecorderThread/58: Sending EOF to encoder
09-03 14:42:47.236  3288 15679 D MediaCodecEncoder: On final buffer; submitting EOF
09-03 14:42:47.241  3288 15679 D MediaCodecEncoder: Received EOF; fully flushed
09-03 14:42:47.242  3288 15679 D RecorderThread/58: Input complete: Total: 1950720 frames (121,9s), Encoded: 1950720 frames (121,9s), Sample rate: 16000, Channel count: 1, Buffer frames: 1280, Buffer overruns: 0, Was ever paused: false, Was ever holding: false
09-03 14:42:47.242  3288 15718 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:42:47.242  3288 15718 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:42:47.247  3288 15718 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:42:47.250  3288 15733 W OggWriter: failed to read next buffer
09-03 14:42:47.251  3288 15718 I hw-BpHwBinder: onLastStrongRef automatically unlinking death recipients
09-03 14:42:47.321  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:42:47.343  3288 15679 D CallMetadataCollector: [Attempt #1 @ 0ms] Found call log entry
09-03 14:42:47.343  3288 15679 D CallMetadataCollector: [Attempt #1 @ 0ms] Found call log phone number
09-03 14:42:47.450  3288 15679 D CallMetadataCollector: [Attempt #2 @ 113ms] Found call log entry
09-03 14:42:47.556  3288 15679 D CallMetadataCollector: [Attempt #3 @ 220ms] Found call log entry
09-03 14:42:47.668  3288 15679 D CallMetadataCollector: [Attempt #4 @ 326ms] Found call log entry
09-03 14:42:47.777  3288 15679 D CallMetadataCollector: [Attempt #5 @ 438ms] Found call log entry
09-03 14:42:47.894  3288 15679 D CallMetadataCollector: [Attempt #6 @ 548ms] Found call log entry
09-03 14:42:48.008  3288 15679 D CallMetadataCollector: [Attempt #7 @ 666ms] Found call log entry
09-03 14:42:48.123  3288 15679 D CallMetadataCollector: [Attempt #8 @ 780ms] Found call log entry
09-03 14:42:48.237  3288 15679 D CallMetadataCollector: [Attempt #9 @ 894ms] Found call log entry
09-03 14:42:48.354  3288 15679 D CallMetadataCollector: [Attempt #10 @ 1009ms] Found call log entry
09-03 14:42:48.475  3288 15679 D CallMetadataCollector: [Attempt #11 @ 1126ms] Found call log entry
09-03 14:42:48.591  3288 15679 D CallMetadataCollector: [Attempt #12 @ 1247ms] Found call log entry
09-03 14:42:48.711  3288 15679 D CallMetadataCollector: [Attempt #13 @ 1362ms] Found call log entry
09-03 14:42:48.825  3288 15679 D CallMetadataCollector: [Attempt #14 @ 1482ms] Found call log entry
09-03 14:42:48.939  3288 15679 D CallMetadataCollector: [Attempt #15 @ 1596ms] Found call log entry
09-03 14:42:49.053  3288 15679 D CallMetadataCollector: [Attempt #16 @ 1710ms] Found call log entry
09-03 14:42:49.167  3288 15679 D CallMetadataCollector: [Attempt #17 @ 1825ms] Found call log entry
09-03 14:42:49.308  3288 15679 D CallMetadataCollector: [Attempt #18 @ 1939ms] Found call log entry
09-03 14:42:49.410  3288 15679 D CallMetadataCollector: Incomplete call log details after all 18 attempts
09-03 14:42:49.411  3288 15679 D CallMetadataCollector: Performing manual contact lookup
09-03 14:42:49.459  3288 15679 D CallMetadataCollector: Contact not found via manual lookup
09-03 14:42:49.459  3288 15679 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:42:49.461  3288 15679 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.oga cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:42:49.462  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)> with MIME type audio/ogg in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:42:49.532  3288 15679 I OutputDirUtils: Successfully moved file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.oga to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings/20240903_144043.044+0200_in_<phone number(s)>.oga
09-03 14:42:49.533  3288 15679 I RecorderThread/58: Writing metadata file
09-03 14:42:49.538  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)> with MIME type application/json in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:42:49.542  3288 15679 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.json cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:42:49.542  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)> with MIME type application/json in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:42:49.672  3288 15679 I OutputDirUtils: Successfully moved file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.json to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings/20240903_144043.044+0200_in_<phone number(s)>.json
09-03 14:42:49.675  3288 15679 I RecorderThread/58: Keeping all existing files
09-03 14:42:49.675  3288 15679 I RecorderThread/58: Recording thread completed
09-03 14:42:49.675  3288 15679 D RecorderThread/58: Stopping log file
09-03 14:42:50.678  3288 15679 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:42:50.682  3288 15679 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.log.txt cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:42:50.683  3288 15679 D OutputDirUtils: Creating 20240903_144043.044+0200_in_<phone number(s)>.log with MIME type text/plain in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:42:50.751  3288 15679 I OutputDirUtils: Successfully moved file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144043.044+0200_in_<phone number(s)>.log.txt to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings/20240903_144043.044+0200_in_<phone number(s)>.log.txt
09-03 14:42:50.752  3288 15679 I RecorderInCallService: Recording completed: 58: content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings/20240903_144043.044+0200_in_<phone number(s)>.oga
09-03 14:42:50.752  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144043.044+0200_in_<phone number(s)>
09-03 14:42:51.278  3288  3541 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 0(0 size) total buffers - 0(0 size) used buffers - 2540/2545 (recycle/alloc) - 15/5080 (fetch/transfer)
09-03 14:42:51.278  3288  3541 D BufferPoolAccessor2.0: evictor expired: 1, evicted: 1
09-03 14:43:57.043  3288  3288 D RecorderInCallService: onCallAdded: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_RESPOND_VIA_TEXT CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:43:57.043  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_RESPOND_VIA_TEXT CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:43:57.176  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a2e1c8 : 0(0 size) total buffers - 0(0 size) used buffers - 2540/2545 (recycle/alloc) - 15/5080 (fetch/transfer)
09-03 14:43:57.177  3288  3542 D BufferPoolAccessor2.0: Destruction - bufferpool2 0xb400006f98a2e1c8 cached: 0/0M, 0/0% in use; allocs: 2545, 100% recycled; transfers: 5080, 100% unfetched
09-03 14:43:58.870  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:43:58.870  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:43:58.935  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:43:58.936  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: RINGING, details: [id: TC@13, state: RINGING, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 2
09-03 14:43:58.955  3288  3288 D RecorderInCallService: onStateChanged: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 4
09-03 14:43:58.955  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 4, 4
09-03 14:43:58.978  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:43:58.978  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:43:58.982  3288  3288 I OutputFilenameGenerator: Filename template: {date}[_{direction}|][_sim{sim_slot}|][_{phone_number}|][_[{contact_name}|{caller_name}|{call_log_name}]|]
09-03 14:43:58.986  3288  3288 I RecorderThread/63: Created thread for call: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:43:58.987  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:58.995  3288  3288 D RecorderThread/63: Holding state updated: false
09-03 14:43:58.995  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:43:58.995  3288 17366 D RecorderThread/63: Starting log file (1.69)
09-03 14:43:59.003  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:43:59.003  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:43:59.003  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 4
09-03 14:43:59.004  3288  3288 D RecorderThread/63: Holding state updated: false
09-03 14:43:59.004  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.004  3288 17366 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.005  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.005  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.005  3288 17366 D OutputDirUtils: Creating 20240903_144356.677+0200_in_<phone number(s)>.log with MIME type text/plain in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:43:59.006  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:43:59.011  3288  3288 D CallMetadataCollector: Call log lookup is disabled for this invocation
09-03 14:43:59.011  3288  3288 D CallMetadataCollector: Manual contact lookup is disabled for this invocation
09-03 14:43:59.011  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: ACTIVE, details: [id: TC@13, state: ACTIVE, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 4
09-03 14:43:59.011  3288  3288 D RecorderThread/63: Holding state updated: false
09-03 14:43:59.011  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.012  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.020  3288 17366 I RecorderThread/63: Recording thread started
09-03 14:43:59.020  3288 17366 I RecorderThread/63: Evaluating record rules for 1 phone number(s)
09-03 14:43:59.021  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.038  3288 17366 D RecorderThread/63: Keep state updated: true
09-03 14:43:59.039  3288 17366 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.039  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.039  3288 17366 D OutputDirUtils: Creating 20240903_144356.677+0200_in_<phone number(s)> with MIME type audio/ogg in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:43:59.045  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:43:59.046  3288 17366 D RecorderThread/63: AudioRecord minimum buffer size: 1280
09-03 14:43:59.275  3288 17366 D RecorderThread/63: AudioRecord initial buffer size: 7680
09-03 14:43:59.275  3288 17366 D RecorderThread/63: AudioRecord format: AudioFormat: props=7 enc=2 chan=0x10 chan_index=0x0 rate=16000
09-03 14:43:59.303  3288 17366 D MediaCodecEncoder: Audio encoder: c2.android.opus.encoder
09-03 14:43:59.310  3288 17402 D CCodec  : allocate(c2.android.opus.encoder)
09-03 14:43:59.317  3288 17402 I CCodec  : Created component [c2.android.opus.encoder]
09-03 14:43:59.318  3288 17402 D CCodecConfig: read media type: audio/opus
09-03 14:43:59.325  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
09-03 14:43:59.327  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
09-03 14:43:59.327  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
09-03 14:43:59.328  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
09-03 14:43:59.328  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
09-03 14:43:59.329  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
09-03 14:43:59.330  3288 17402 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
09-03 14:43:59.337  3288 17402 I CCodecConfig: query failed after returning 9 values (BAD_INDEX)
09-03 14:43:59.337  3288 17402 D CCodecConfig: c2 config diff is Dict {
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 algo.bitrate-mode.value = 3
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 algo.complexity.value = 10
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 coded.bitrate.value = 128000
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 input.buffers.max-size.value = 3840
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 input.delay.value = 0
09-03 14:43:59.337  3288 17402 D CCodecConfig:   string input.media-type.value = "audio/raw"
09-03 14:43:59.337  3288 17402 D CCodecConfig:   string output.media-type.value = "audio/opus"
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 raw.channel-count.value = 1
09-03 14:43:59.337  3288 17402 D CCodecConfig:   c2::u32 raw.sample-rate.value = 48000
09-03 14:43:59.337  3288 17402 D CCodecConfig: }
09-03 14:43:59.342  3288 17366 D MediaCodec: shapeMediaFormat: deltas(0): AMessage(what = 0x00000000) = {
09-03 14:43:59.342  3288 17366 D MediaCodec:   }
09-03 14:43:59.343  3288 17402 D CCodec  : [c2.android.opus.encoder] buffers are bound to CCodec for this session
09-03 14:43:59.343  3288 17402 D CCodecConfig: no c2 equivalents for x-frame-size-in-bytes
09-03 14:43:59.343  3288 17402 D CCodecConfig: no c2 equivalents for flags
09-03 14:43:59.343  3288 17402 D CCodecConfig: no c2 equivalents for encoder
09-03 14:43:59.343  3288 17402 D CCodecConfig: c2 config diff is   c2::u32 coded.bitrate.value = 48000
09-03 14:43:59.343  3288 17402 D CCodecConfig:   c2::u32 raw.sample-rate.value = 16000
09-03 14:43:59.344  3288 17402 W Codec2Client: query -- param skipped: index = 1107298332.
09-03 14:43:59.344  3288 17402 D CCodec  : encoding statistics level = 0
09-03 14:43:59.344  3288 17402 D CCodec  : setup formats input: AMessage(what = 0x00000000) = {
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t channel-count = 1
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t flac-compression-level = 10
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t max-input-size = 3840
09-03 14:43:59.344  3288 17402 D CCodec  :   string mime = "audio/raw"
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t sample-rate = 16000
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t android._config-pcm-encoding = 2
09-03 14:43:59.344  3288 17402 D CCodec  : }
09-03 14:43:59.344  3288 17402 D CCodec  : setup formats output: AMessage(what = 0x00000000) = {
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t bitrate = 48000
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t bitrate-mode = 1
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t channel-count = 1
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t flac-compression-level = 10
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t max-bitrate = 48000
09-03 14:43:59.344  3288 17402 D CCodec  :   string mime = "audio/opus"
09-03 14:43:59.344  3288 17402 D CCodec  :   int32_t sample-rate = 16000
09-03 14:43:59.344  3288 17402 D CCodec  : }
09-03 14:43:59.344  3288 17402 I CCodecConfig: query failed after returning 9 values (BAD_INDEX)
09-03 14:43:59.346  3288 17402 W Codec2Client: query -- param skipped: index = 1342179345.
09-03 14:43:59.346  3288 17402 W Codec2Client: query -- param skipped: index = 2415921170.
09-03 14:43:59.347  3288 17402 W Codec2Client: query -- param skipped: index = 3254792206.
09-03 14:43:59.347  3288 17402 W Codec2Client: query -- param skipped: index = 3254792194.
09-03 14:43:59.347  3288 17402 D CCodecBufferChannel: [c2.android.opus.encoder#227] Created input block pool with allocatorID 16 => poolID 22 - OK (0)
09-03 14:43:59.348  3288 17402 I CCodecBufferChannel: [c2.android.opus.encoder#227] Created output block pool with allocatorID 16 => poolID 92 - OK
09-03 14:43:59.349  3288 17402 D CCodecBufferChannel: [c2.android.opus.encoder#227] Configured output block pool ids 92 => OK
09-03 14:43:59.356  3288 17366 D RecorderThread/63: Buffer is 2560 bytes, 1280 frames, 80000000ns
09-03 14:43:59.442  3288 17366 D MediaCodecEncoder: Output format changed to: {max-bitrate=48000, sample-rate=16000, mime=audio/opus, channel-count=1, bitrate-mode=1, bitrate=48000, flac-compression-level=10, csd-0=java.nio.HeapByteBuffer[pos=0 lim=83 cap=83]}
09-03 14:43:59.443  3288 17366 I MetaDataBase: srte: (int32_t) 16000
09-03 14:43:59.446  3288 17366 I MetaDataBase: ospr: (unknown type 0, size 8)
09-03 14:43:59.446  3288 17366 I MetaDataBase: 00000000:  00 b4 c4 04 00 00 00 00                           ........
09-03 14:43:59.447  3288 17366 I MetaDataBase: ohdr: (unknown type 0, size 19)
09-03 14:43:59.447  3288 17366 I MetaDataBase: 00000000:  4f 70 75 73 48 65 61 64  01 01 68 00 80 3e 00 00  OpusHead..h..>..
09-03 14:43:59.447  3288 17366 I MetaDataBase: 00000010:  00 00 00                                          ...
09-03 14:43:59.447  3288 17366 I MetaDataBase: ocod: (unknown type 0, size 8)
09-03 14:43:59.447  3288 17366 I MetaDataBase: 00000000:  a0 2e 63 00 00 00 00 00                           ..c.....
09-03 14:43:59.447  3288 17366 I MetaDataBase: mxBr: (int32_t) 48000
09-03 14:43:59.447  3288 17366 I MetaDataBase: mime: (char*) audio/opus
09-03 14:43:59.447  3288 17366 I MetaDataBase: csd0: no type, size 83)
09-03 14:43:59.448  3288 17366 I MetaDataBase: brte: (int32_t) 48000
09-03 14:43:59.448  3288 17366 I MetaDataBase: #chn: (int32_t) 1
09-03 14:44:02.179  3288  3541 D BufferPoolAccessor2.0: evictor expired: 1, evicted: 0
09-03 14:44:04.515  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 106/111 (recycle/alloc) - 8/213 (fetch/transfer)
09-03 14:44:09.726  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 214/219 (recycle/alloc) - 9/430 (fetch/transfer)
09-03 14:44:14.994  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 324/329 (recycle/alloc) - 9/649 (fetch/transfer)
09-03 14:44:20.187  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 432/437 (recycle/alloc) - 9/865 (fetch/transfer)
09-03 14:44:25.398  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 541/546 (recycle/alloc) - 9/1083 (fetch/transfer)
09-03 14:44:30.576  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 649/654 (recycle/alloc) - 9/1300 (fetch/transfer)
09-03 14:44:41.031  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 866/871 (recycle/alloc) - 9/1734 (fetch/transfer)
09-03 14:44:46.187  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 974/979 (recycle/alloc) - 9/1949 (fetch/transfer)
09-03 14:44:51.461  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1084/1089 (recycle/alloc) - 9/2169 (fetch/transfer)
09-03 14:44:56.708  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1193/1198 (recycle/alloc) - 9/2387 (fetch/transfer)
09-03 14:45:01.871  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1301/1306 (recycle/alloc) - 9/2603 (fetch/transfer)
09-03 14:45:07.110  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1410/1415 (recycle/alloc) - 9/2821 (fetch/transfer)
09-03 14:45:12.392  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1520/1525 (recycle/alloc) - 10/3041 (fetch/transfer)
09-03 14:45:17.683  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1630/1635 (recycle/alloc) - 10/3261 (fetch/transfer)
09-03 14:45:22.859  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1738/1743 (recycle/alloc) - 10/3477 (fetch/transfer)
09-03 14:45:27.950  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1844/1849 (recycle/alloc) - 11/3690 (fetch/transfer)
09-03 14:45:33.143  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 1952/1957 (recycle/alloc) - 11/3905 (fetch/transfer)
09-03 14:45:38.381  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2061/2066 (recycle/alloc) - 11/4123 (fetch/transfer)
09-03 14:45:43.553  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2169/2174 (recycle/alloc) - 12/4339 (fetch/transfer)
09-03 14:45:48.772  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2278/2283 (recycle/alloc) - 12/4557 (fetch/transfer)
09-03 14:45:53.901  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2385/2390 (recycle/alloc) - 12/4772 (fetch/transfer)
09-03 14:45:59.147  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2494/2499 (recycle/alloc) - 12/4990 (fetch/transfer)
09-03 14:46:04.342  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2602/2607 (recycle/alloc) - 12/5205 (fetch/transfer)
09-03 14:46:09.526  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2710/2715 (recycle/alloc) - 12/5421 (fetch/transfer)
09-03 14:46:14.738  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2819/2824 (recycle/alloc) - 12/5640 (fetch/transfer)
09-03 14:46:19.903  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 2926/2931 (recycle/alloc) - 12/5853 (fetch/transfer)
09-03 14:46:25.024  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3033/3038 (recycle/alloc) - 12/6067 (fetch/transfer)
09-03 14:46:30.209  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3141/3146 (recycle/alloc) - 12/6283 (fetch/transfer)
09-03 14:46:35.421  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3250/3255 (recycle/alloc) - 12/6501 (fetch/transfer)
09-03 14:46:40.622  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3358/3363 (recycle/alloc) - 13/6717 (fetch/transfer)
09-03 14:46:45.794  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3466/3471 (recycle/alloc) - 13/6934 (fetch/transfer)
09-03 14:46:50.996  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3574/3579 (recycle/alloc) - 14/7149 (fetch/transfer)
09-03 14:46:56.172  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3682/3687 (recycle/alloc) - 14/7365 (fetch/transfer)
09-03 14:47:01.327  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3789/3794 (recycle/alloc) - 14/7580 (fetch/transfer)
09-03 14:47:06.577  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 3899/3904 (recycle/alloc) - 14/7799 (fetch/transfer)
09-03 14:47:11.762  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4007/4012 (recycle/alloc) - 15/8015 (fetch/transfer)
09-03 14:47:17.087  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4118/4123 (recycle/alloc) - 16/8237 (fetch/transfer)
09-03 14:47:22.269  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4226/4231 (recycle/alloc) - 16/8453 (fetch/transfer)
09-03 14:47:27.446  3288  3542 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4333/4338 (recycle/alloc) - 16/8668 (fetch/transfer)
09-03 14:47:32.645  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4442/4447 (recycle/alloc) - 16/8885 (fetch/transfer)
09-03 14:47:37.827  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4550/4555 (recycle/alloc) - 16/9101 (fetch/transfer)
09-03 14:47:43.023  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4658/4663 (recycle/alloc) - 16/9317 (fetch/transfer)
09-03 14:47:48.271  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 5(19200 size) total buffers - 4(15360 size) used buffers - 4767/4772 (recycle/alloc) - 16/9535 (fetch/transfer)
09-03 14:47:53.404  3288  3288 D RecorderInCallService: onStateChanged: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 7
09-03 14:47:53.404  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], 7, 7
09-03 14:47:53.404  3288  3288 D RecorderThread/63: Requested cancellation
09-03 14:47:53.404  3288  3288 D RecorderInCallService: onDetailsChanged: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]
09-03 14:47:53.404  3288  3288 D RecorderInCallService: handleStateChange: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]], null, 7
09-03 14:47:53.404  3288  3288 D RecorderInCallService: onCallDestroyed: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:47:53.406  3288  3288 D RecorderInCallService: onCallRemoved: Call [id: TC@13, state: DISCONNECTED, details: [id: TC@13, state: DISCONNECTED, pa: ComponentInfo{com.android.phone/com.android.services.telephony.TelephonyConnectionService}, 1, UserHandle{0}, hdl: tel:**********09, hdlPres: 1, videoState: Audio Only, caps: [Capabilities: CAPABILITY_HOLD CAPABILITY_SUPPORT_HOLD CAPABILITY_MUTE CAPABILITY_SUPPORTS_VT_REMOTE_RX CAPABILITY_SUPPORTS_VT_REMOTE_TX CAPABILITY_SUPPORTS_VT_REMOTE_BIDIRECTIONAL], props: [Properties: PROPERTY_HIGH_DEF_AUDIO]]]
09-03 14:47:53.439  3288 17366 D RecorderThread/63: Sending EOF to encoder
09-03 14:47:53.440  3288 17366 D MediaCodecEncoder: On final buffer; submitting EOF
09-03 14:47:53.446  3288 17366 D MediaCodecEncoder: Received EOF; fully flushed
09-03 14:47:53.446  3288 17366 D RecorderThread/63: Input complete: Total: 3744000 frames (234,0s), Encoded: 3744000 frames (234,0s), Sample rate: 16000, Channel count: 1, Buffer frames: 1280, Buffer overruns: 0, Was ever paused: false, Was ever holding: false
09-03 14:47:53.447  3288 17402 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:47:53.447  3288 17402 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:47:53.447  3288 17402 D CCodecBufferChannel: [c2.android.opus.encoder#227] MediaCodec discarded an unknown buffer
09-03 14:47:53.452  3288 17422 W OggWriter: failed to read next buffer
09-03 14:47:53.452  3288 17402 I hw-BpHwBinder: onLastStrongRef automatically unlinking death recipients
09-03 14:47:53.454  3288 17402 D BufferPoolAccessor2.0: bufferpool2 0xb400006f98a349d8 : 0(0 size) total buffers - 0(0 size) used buffers - 4875/4880 (recycle/alloc) - 16/9750 (fetch/transfer)
09-03 14:47:53.503  3288  3288 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:47:53.513  3288 17366 D CallMetadataCollector: [Attempt #1 @ 0ms] Found call log entry
09-03 14:47:53.513  3288 17366 D CallMetadataCollector: [Attempt #1 @ 0ms] Found call log phone number
09-03 14:47:53.623  3288 17366 D CallMetadataCollector: [Attempt #2 @ 108ms] Found call log entry
09-03 14:47:53.737  3288 17366 D CallMetadataCollector: [Attempt #3 @ 219ms] Found call log entry
09-03 14:47:53.849  3288 17366 D CallMetadataCollector: [Attempt #4 @ 333ms] Found call log entry
09-03 14:47:53.963  3288 17366 D CallMetadataCollector: [Attempt #5 @ 444ms] Found call log entry
09-03 14:47:54.076  3288 17366 D CallMetadataCollector: [Attempt #6 @ 559ms] Found call log entry
09-03 14:47:54.189  3288 17366 D CallMetadataCollector: [Attempt #7 @ 672ms] Found call log entry
09-03 14:47:54.300  3288 17366 D CallMetadataCollector: [Attempt #8 @ 785ms] Found call log entry
09-03 14:47:54.414  3288 17366 D CallMetadataCollector: [Attempt #9 @ 896ms] Found call log entry
09-03 14:47:54.531  3288 17366 D CallMetadataCollector: [Attempt #10 @ 1010ms] Found call log entry
09-03 14:47:54.648  3288 17366 D CallMetadataCollector: [Attempt #11 @ 1128ms] Found call log entry
09-03 14:47:54.764  3288 17366 D CallMetadataCollector: [Attempt #12 @ 1245ms] Found call log entry
09-03 14:47:54.877  3288 17366 D CallMetadataCollector: [Attempt #13 @ 1361ms] Found call log entry
09-03 14:47:54.989  3288 17366 D CallMetadataCollector: [Attempt #14 @ 1473ms] Found call log entry
09-03 14:47:55.102  3288 17366 D CallMetadataCollector: [Attempt #15 @ 1584ms] Found call log entry
09-03 14:47:55.216  3288 17366 D CallMetadataCollector: [Attempt #16 @ 1699ms] Found call log entry
09-03 14:47:55.331  3288 17366 D CallMetadataCollector: [Attempt #17 @ 1813ms] Found call log entry
09-03 14:47:55.444  3288 17366 D CallMetadataCollector: [Attempt #18 @ 1926ms] Found call log entry
09-03 14:47:55.548  3288 17366 D CallMetadataCollector: Incomplete call log details after all 18 attempts
09-03 14:47:55.549  3288 17366 D CallMetadataCollector: Performing manual contact lookup
09-03 14:47:55.581  3288 17366 D CallMetadataCollector: Contact not found via manual lookup
09-03 14:47:55.582  3288 17366 I OutputFilenameGenerator: Generated filename: 20240903_144356.677+0200_in_<phone number(s)>
09-03 14:47:55.587  3288 17366 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.oga cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:47:55.587  3288 17366 D OutputDirUtils: Creating 20240903_144356.677+0200_in_<phone number(s)> with MIME type audio/ogg in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.593  3288 17366 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 744)
09-03 14:47:55.595  3288 17366 W DocumentsContract: Failed to create document
09-03 14:47:55.595  3288 17366 W DocumentsContract: java.lang.NullPointerException: Attempt to invoke virtual method 'java.lang.Object android.os.Bundle.getParcelable(java.lang.String, java.lang.Class)' on a null object reference
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at android.provider.DocumentsContract.createDocument(DocumentsContract.java:1382)
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at androidx.documentfile.provider.TreeDocumentFile.createFile(Unknown Source:9)
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:93)
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:51)
09-03 14:47:55.595  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.run(Unknown Source:214)
09-03 14:47:55.595  3288 17366 W OutputDirUtils: Failed to move file; using fallback path
09-03 14:47:55.595  3288 17366 W OutputDirUtils: java.io.IOException: Failed to create file 20240903_144356.677+0200_in_<phone number(s)> in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.595  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:124)
09-03 14:47:55.595  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.595  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:51)
09-03 14:47:55.595  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.RecorderThread.run(Unknown Source:214)
09-03 14:47:55.596  3288 17366 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.oga cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:47:55.596  3288 17366 D OutputDirUtils: Creating ERROR_20240903_144356.677+0200_in_<phone number(s)> with MIME type audio/ogg in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.597  3288 17366 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 756)
09-03 14:47:55.600  3288 17366 W DocumentsContract: Failed to create document
09-03 14:47:55.600  3288 17366 W DocumentsContract: java.lang.NullPointerException: Attempt to invoke virtual method 'java.lang.Object android.os.Bundle.getParcelable(java.lang.String, java.lang.Class)' on a null object reference
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at android.provider.DocumentsContract.createDocument(DocumentsContract.java:1382)
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at androidx.documentfile.provider.TreeDocumentFile.createFile(Unknown Source:9)
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:93)
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:94)
09-03 14:47:55.600  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.run(Unknown Source:214)
09-03 14:47:55.601  3288 17366 E OutputDirUtils: Failed to move file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.oga to androidx.documentfile.provider.TreeDocumentFile@24aa20d
09-03 14:47:55.601  3288 17366 E OutputDirUtils: java.io.IOException: Failed to create file ERROR_20240903_144356.677+0200_in_<phone number(s)> in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.601  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:124)
09-03 14:47:55.601  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.601  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:94)
09-03 14:47:55.601  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.RecorderThread.run(Unknown Source:214)
09-03 14:47:55.601  3288 17366 I RecorderThread/63: Writing metadata file
09-03 14:47:55.609  3288 17366 D OutputDirUtils: Creating 20240903_144356.677+0200_in_<phone number(s)> with MIME type application/json in file:///storage/emulated/0/Android/data/com.chiller3.bcr/files
09-03 14:47:55.617  3288 17366 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.json cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:47:55.617  3288 17366 D OutputDirUtils: Creating 20240903_144356.677+0200_in_<phone number(s)> with MIME type application/json in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.618  3288 17366 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 760)
09-03 14:47:55.619  3288 17366 W DocumentsContract: Failed to create document
09-03 14:47:55.619  3288 17366 W DocumentsContract: java.lang.NullPointerException: Attempt to invoke virtual method 'java.lang.Object android.os.Bundle.getParcelable(java.lang.String, java.lang.Class)' on a null object reference
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at android.provider.DocumentsContract.createDocument(DocumentsContract.java:1382)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at androidx.documentfile.provider.TreeDocumentFile.createFile(Unknown Source:9)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:93)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:51)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.writeMetadataFile(Unknown Source:293)
09-03 14:47:55.619  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.run(Unknown Source:223)
09-03 14:47:55.620  3288 17366 W OutputDirUtils: Failed to move file; using fallback path
09-03 14:47:55.620  3288 17366 W OutputDirUtils: java.io.IOException: Failed to create file 20240903_144356.677+0200_in_<phone number(s)> in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.620  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:124)
09-03 14:47:55.620  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.620  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:51)
09-03 14:47:55.620  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.RecorderThread.writeMetadataFile(Unknown Source:293)
09-03 14:47:55.620  3288 17366 W OutputDirUtils:    at com.chiller3.bcr.RecorderThread.run(Unknown Source:223)
09-03 14:47:55.620  3288 17366 W OutputDirUtils: file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.json cannot be efficiently moved to content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings: Source scheme (file) != target parent scheme (content)
09-03 14:47:55.620  3288 17366 D OutputDirUtils: Creating ERROR_20240903_144356.677+0200_in_<phone number(s)> with MIME type application/json in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.621  3288 17366 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 772)
09-03 14:47:55.623  3288 17366 W DocumentsContract: Failed to create document
09-03 14:47:55.623  3288 17366 W DocumentsContract: java.lang.NullPointerException: Attempt to invoke virtual method 'java.lang.Object android.os.Bundle.getParcelable(java.lang.String, java.lang.Class)' on a null object reference
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at android.provider.DocumentsContract.createDocument(DocumentsContract.java:1382)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at androidx.documentfile.provider.TreeDocumentFile.createFile(Unknown Source:9)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:93)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:94)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.writeMetadataFile(Unknown Source:293)
09-03 14:47:55.623  3288 17366 W DocumentsContract:     at com.chiller3.bcr.RecorderThread.run(Unknown Source:223)
09-03 14:47:55.624  3288 17366 E OutputDirUtils: Failed to move file:///storage/emulated/0/Android/data/com.chiller3.bcr/files/20240903_144356.677+0200_in_<phone number(s)>.json to androidx.documentfile.provider.TreeDocumentFile@3f5262f
09-03 14:47:55.624  3288 17366 E OutputDirUtils: java.io.IOException: Failed to create file ERROR_20240903_144356.677+0200_in_<phone number(s)> in content://com.android.externalstorage.documents/tree/primary:Recordings/Call recordings/document/primary:Recordings/Call recordings
09-03 14:47:55.624  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.createFile(Unknown Source:124)
09-03 14:47:55.624  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.move(Unknown Source:285)
09-03 14:47:55.624  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.output.OutputDirUtils.tryMoveToOutputDir(Unknown Source:94)
09-03 14:47:55.624  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.RecorderThread.writeMetadataFile(Unknown Source:293)
09-03 14:47:55.624  3288 17366 E OutputDirUtils:    at com.chiller3.bcr.RecorderThread.run(Unknown Source:223)
09-03 14:47:55.627  3288 17366 I RecorderThread/63: Keeping all existing files
09-03 14:47:55.627  3288 17366 I RecorderThread/63: Recording thread completed
09-03 14:47:55.627  3288 17366 D RecorderThread/63: Stopping log file
09-03 14:47:55.655  3288  3288 I ActivityThread: Removing dead content provider:android.content.ContentProviderProxy@41f05c5
chenxiaolong commented 2 months ago

Thanks for the logs! The issue here is:

09-03 14:47:55.618  3288 17366 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 760)

It looks like there some serious bug in the ROM. The binder transaction failing is usually the result of the system component for handling storage crashing. To troubleshoot that, you'll probably have to grab the logcat for the entire system (BCR only has permissions to see its own logs).

iwconfig commented 2 months ago

Alright, thanks for letting me know. I'll check this out, however, given your answer I tried restarting my device and now it works for some reason! And this time I recorded a really long one. Strange! I'll update this thread if the same issue happens again or if I find anything interesting related to this.

Thanks again!