v2.5 Schedule Export Error #280
Description
First, I wanted to thank everyone that makes this project possible, you guys rock! This is the missing piece in my backup architecture and I'm excited to have background exports happening.
SMS ie: v2.5
Phone: Pixel 8
OS: Android 16 (GrapheneOS)
When running a schedule export in the background I get the following error. It looks like the export happens in full although I haven't tested the export. I'm happy to do any additional testing I can.
type: logcat
osVersion: google/shiba/shiba:16/BP2A.250705.008/2025071900:user/release-keys
package: com.github.tmo1.sms_ie:10110, targetSdk 36
buffers: main,system,crash,events,kernel
level: verbose
--------- beginning of events
07-24 18:54:03.700 13165 13165 I auditd : avc=type=1400 audit(0.0:16859): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.920 13165 13165 I auditd : avc=type=1400 audit(0.0:16860): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.944 13165 13165 I auditd : avc=type=1400 audit(0.0:16861): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.944 13165 13165 I auditd : avc=type=1400 audit(0.0:16862): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.944 13165 13165 I auditd : avc=type=1400 audit(0.0:16863): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.948 13165 13165 I auditd : avc=type=1400 audit(0.0:16864): avc: denied { read } for comm="hub.tmo1.sms_ie" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.980 13165 13165 I auditd : avc=type=1400 audit(0.0:16865): avc: denied { read } for comm="hub.tmo1.sms_ie" name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=796 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.984 13165 13165 I auditd : avc=type=1400 audit(0.0:16866): avc: denied { read } for comm="hub.tmo1.sms_ie" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:03.988 13165 13165 I auditd : avc=type=1400 audit(0.0:16867): avc: denied { getattr } for comm="hub.tmo1.sms_ie" path="/apex/apex-info-list.xml" dev="tmpfs" ino=93 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16868): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=608 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16869): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=608 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16870): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=608 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16871): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=608 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16872): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=608 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.156 13165 13165 I auditd : avc=type=1400 audit(0.0:16873): avc: denied { read } for comm="main" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.292 13165 13165 I auditd : avc=type=1400 audit(0.0:16874): avc: denied { read } for comm="binder:13165_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=824 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
07-24 18:54:04.292 13165 13165 I auditd : avc=type=1400 audit(0.0:16875): avc: denied { read } for comm="binder:13165_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=824 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
--------- beginning of system
07-24 18:54:04.351 13165 13165 V Configuration: Updating configuration, locales updated from [] to [en_US]
--------- switch to events
07-24 18:54:04.408 13165 13165 I auditd : avc=type=1400 audit(0.0:16876): avc: denied { read } for comm="hub.tmo1.sms_ie" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=899 scontext=u:r:untrusted_app:s0:c212,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=com.github.tmo1.sms_ie
--------- switch to system
07-24 18:54:04.420 13165 13189 I DisplayManager: Choreographer implicitly registered for the refresh rate.
--------- switch to events
07-24 18:54:04.478 13165 13165 I wm_on_create_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performCreate,time=45ms]
07-24 18:54:04.479 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=0ms]
07-24 18:54:04.484 13165 13165 I wm_on_resume_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=RESUME_ACTIVITY,time=4ms]
07-24 18:54:04.567 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 18:54:04.628 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 18:54:04.962 13165 13165 I wm_on_paused_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performPause,time=0ms]
07-24 18:54:04.993 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 18:54:05.011 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]
07-24 18:54:05.035 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:02:31.671 13165 13165 I wm_on_restart_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performRestart,time=0ms]
07-24 19:02:31.674 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=3ms]
07-24 19:02:31.675 13165 13165 I wm_on_resume_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 19:02:31.675 13165 13165 I wm_on_top_resumed_gained_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topWhenResuming]
07-24 19:02:31.680 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 19:02:31.732 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 19:02:31.735 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 19:02:31.741 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 19:02:34.897 13165 13165 I viewroot_draw_event: [window=VRI[PopupWindow:733932f],event=reportDrawFinished seqId=0]
07-24 19:02:34.907 13165 13165 I viewroot_draw_event: [window=VRI[PopupWindow:733932f],event=reportDrawFinished seqId=0]
07-24 19:02:45.983 13165 13165 I menu_item_selected: [Menu type where 0 is options and 1 is context=0,Menu item title=Settings]
07-24 19:02:46.013 13165 13165 I wm_on_top_resumed_lost_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topStateChangedWhenResumed]
07-24 19:02:46.014 13165 13165 I wm_on_paused_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performPause,time=0ms]
07-24 19:02:46.022 13165 13165 I wm_on_create_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performCreate,time=3ms]
07-24 19:02:46.028 13165 13165 I wm_on_start_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=handleStartActivity,time=6ms]
07-24 19:02:46.029 13165 13165 I wm_on_resume_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=RESUME_ACTIVITY,time=1ms]
07-24 19:02:46.033 13165 13165 I wm_on_top_resumed_gained_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topStateChangedWhenResumed]
07-24 19:02:46.064 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=reportDrawFinished seqId=0]
07-24 19:02:46.552 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:02:46.555 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=0ms]
07-24 19:02:48.164 13165 13165 I wm_on_restart_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performRestart,time=0ms]
07-24 19:02:48.164 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=0ms]
07-24 19:02:48.187 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 19:02:48.196 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 19:02:48.250 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=0ms]
07-24 19:02:48.266 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:03:20.605 13165 13165 I wm_on_top_resumed_lost_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topStateChangedWhenResumed]
07-24 19:03:20.611 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.SettingsActivity]
07-24 19:03:21.700 13165 13165 I wm_on_paused_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performPause,time=0ms]
07-24 19:03:21.732 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:03:21.742 13165 13165 I wm_on_stop_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]
07-24 19:03:24.369 13165 13165 I wm_on_restart_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performRestart,time=0ms]
07-24 19:03:24.369 13165 13165 I wm_on_start_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=handleStartActivity,time=0ms]
07-24 19:03:24.369 13165 13165 I wm_on_resume_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 19:03:24.369 13165 13165 I wm_on_top_resumed_gained_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topWhenResuming]
07-24 19:03:24.382 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Start draw after previous draw not visible]
07-24 19:03:24.385 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=reportDrawFinished seqId=0]
07-24 19:03:26.431 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=reportDrawFinished seqId=0]
07-24 19:03:46.963 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.SettingsActivity]
07-24 19:03:46.976 13165 13165 I wm_on_top_resumed_lost_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topStateChangedWhenResumed]
07-24 19:03:48.083 13165 13165 I wm_on_paused_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performPause,time=0ms]
07-24 19:03:48.130 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:03:48.135 13165 13165 I wm_on_stop_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]
07-24 19:05:09.745 13165 13165 I wm_on_restart_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performRestart,time=0ms]
07-24 19:05:09.746 13165 13165 I wm_on_start_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=handleStartActivity,time=1ms]
07-24 19:05:09.747 13165 13165 I wm_on_resume_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 19:05:09.747 13165 13165 I wm_on_top_resumed_gained_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topWhenResuming]
07-24 19:05:09.754 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Start draw after previous draw not visible]
07-24 19:05:09.771 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=reportDrawFinished seqId=0]
07-24 19:05:12.520 13165 13165 I wm_on_top_resumed_lost_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topStateChangedWhenResumed]
07-24 19:05:12.528 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.SettingsActivity]
07-24 19:05:13.632 13165 13165 I wm_on_paused_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performPause,time=0ms]
07-24 19:05:13.650 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:05:13.652 13165 13165 I wm_on_stop_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=STOP_ACTIVITY_ITEM,time=0ms]
07-24 19:07:43.104 13165 13557 I force_gc: reason=Binder
07-24 19:07:56.106 13165 13557 I force_gc: reason=Binder
07-24 19:19:35.058 13165 13165 I wm_on_restart_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performRestart,time=0ms]
07-24 19:19:35.063 13165 13165 I wm_on_start_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=handleStartActivity,time=5ms]
07-24 19:19:35.063 13165 13165 I wm_on_resume_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 19:19:35.063 13165 13165 I wm_on_top_resumed_gained_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topWhenResuming]
07-24 19:19:35.069 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Start draw after previous draw not visible]
07-24 19:19:35.132 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=reportDrawFinished seqId=0]
07-24 19:19:36.533 13165 13165 I wm_on_restart_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performRestart,time=0ms]
07-24 19:19:36.533 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=0ms]
07-24 19:19:36.552 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 19:19:36.562 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 19:19:36.563 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.SettingsActivity]
07-24 19:19:36.610 13165 13165 I wm_on_top_resumed_lost_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=topStateChangedWhenResumed]
07-24 19:19:36.611 13165 13165 I wm_on_paused_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performPause,time=1ms]
07-24 19:19:36.619 13165 13165 I wm_on_resume_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=RESUME_ACTIVITY,time=1ms]
07-24 19:19:36.619 13165 13165 I wm_on_top_resumed_gained_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topWhenResuming]
07-24 19:19:37.083 13165 13165 I viewroot_draw_event: [window=VRI[SettingsActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:19:37.084 13165 13165 I wm_on_stop_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=LIFECYCLER_STOP_ACTIVITY,time=0ms]
07-24 19:19:37.087 13165 13165 I wm_on_destroy_called: [Token=243030607,Component Name=com.github.tmo1.sms_ie.SettingsActivity,Reason=performDestroy,time=3ms]
07-24 19:19:45.063 13165 13557 I force_gc: reason=Binder
07-24 19:20:03.464 13165 13557 I force_gc: reason=Binder
07-24 19:20:20.532 13165 13557 I force_gc: reason=Binder
07-24 19:20:37.879 13165 13557 I force_gc: reason=Binder
07-24 19:20:55.093 13165 13557 I force_gc: reason=Binder
07-24 19:21:11.860 13165 13557 I force_gc: reason=Binder
07-24 19:21:26.974 13165 13557 I force_gc: reason=Binder
07-24 19:21:43.707 13165 13557 I force_gc: reason=Binder
07-24 19:21:58.048 13165 13557 I force_gc: reason=Binder
07-24 19:22:10.612 13165 13557 I force_gc: reason=Binder
07-24 19:22:28.448 13165 13557 I force_gc: reason=Binder
07-24 19:22:42.697 13165 13557 I force_gc: reason=Binder
07-24 19:22:59.421 13165 13413 I force_gc: reason=Binder
07-24 19:23:14.810 13165 13413 I force_gc: reason=Binder
07-24 19:23:33.083 13165 13413 I force_gc: reason=Binder
07-24 19:23:50.843 13165 13413 I force_gc: reason=Binder
07-24 19:24:08.892 13165 13413 I force_gc: reason=Binder
07-24 19:24:26.912 13165 13413 I force_gc: reason=Binder
07-24 19:24:42.402 13165 13413 I force_gc: reason=Binder
07-24 19:24:54.598 13165 13557 I force_gc: reason=Binder
07-24 19:25:11.341 13165 13557 I force_gc: reason=Binder
07-24 19:25:29.212 13165 13557 I force_gc: reason=Binder
07-24 19:25:46.438 13165 13557 I force_gc: reason=Binder
07-24 19:26:05.347 13165 13557 I force_gc: reason=Binder
07-24 19:26:23.361 13165 13557 I force_gc: reason=Binder
07-24 19:26:41.453 13165 13557 I force_gc: reason=Binder
07-24 19:26:53.913 13165 13557 I force_gc: reason=Binder
07-24 19:27:02.212 13165 13557 I force_gc: reason=Binder
07-24 19:27:17.496 13165 13557 I force_gc: reason=Binder
07-24 19:27:35.421 13165 13557 I force_gc: reason=Binder
07-24 19:27:53.260 13165 13557 I force_gc: reason=Binder
07-24 19:28:10.842 13165 13557 I force_gc: reason=Binder
07-24 19:28:29.240 13165 13557 I force_gc: reason=Binder
07-24 19:28:46.083 13165 13557 I force_gc: reason=Binder
07-24 19:29:03.933 13165 13557 I force_gc: reason=Binder
07-24 19:29:21.301 13165 13557 I force_gc: reason=Binder
07-24 19:29:39.203 13165 13557 I force_gc: reason=Binder
07-24 19:29:57.856 13165 13557 I force_gc: reason=Binder
07-24 19:30:15.832 13165 13557 I force_gc: reason=Binder
07-24 19:30:33.910 13165 13557 I force_gc: reason=Binder
07-24 19:30:49.472 13165 13557 I force_gc: reason=Binder
07-24 19:30:56.440 13165 13557 I force_gc: reason=Binder
07-24 19:31:10.840 13165 13413 I force_gc: reason=Binder
07-24 19:31:28.559 13165 13414 I force_gc: reason=Binder
07-24 19:31:46.602 13165 13414 I force_gc: reason=Binder
07-24 19:32:04.657 13165 13414 I force_gc: reason=Binder
07-24 19:32:23.321 13165 13414 I force_gc: reason=Binder
07-24 19:32:41.674 13165 13414 I force_gc: reason=Binder
07-24 19:32:59.986 13165 13414 I force_gc: reason=Binder
07-24 19:33:18.505 13165 13414 I force_gc: reason=Binder
07-24 19:33:37.049 13165 13414 I force_gc: reason=Binder
07-24 19:33:55.181 13165 13414 I force_gc: reason=Binder
07-24 19:34:08.418 13165 13414 I force_gc: reason=Binder
07-24 19:34:23.916 13165 13557 I force_gc: reason=Binder
07-24 19:34:39.214 13165 13557 I force_gc: reason=Binder
07-24 19:34:57.693 13165 13557 I force_gc: reason=Binder
07-24 19:35:15.999 13165 13557 I force_gc: reason=Binder
07-24 19:35:34.113 13165 13557 I force_gc: reason=Binder
07-24 19:35:52.539 13165 13557 I force_gc: reason=Binder
07-24 19:36:10.511 13165 13557 I force_gc: reason=Binder
07-24 19:36:29.132 13165 13557 I force_gc: reason=Binder
07-24 19:36:47.879 13165 13557 I force_gc: reason=Binder
07-24 19:37:06.125 13165 13557 I force_gc: reason=Binder
07-24 19:37:24.094 13165 13557 I force_gc: reason=Binder
07-24 19:37:41.630 13165 13557 I force_gc: reason=Binder
07-24 19:37:59.335 13165 13557 I force_gc: reason=Binder
07-24 19:38:17.637 13165 13557 I force_gc: reason=Binder
07-24 19:38:31.555 13165 13557 I force_gc: reason=Binder
07-24 19:38:47.936 13165 13557 I force_gc: reason=Binder
07-24 19:39:05.614 13165 13557 I force_gc: reason=Binder
07-24 19:39:23.800 13165 13557 I force_gc: reason=Binder
07-24 19:39:40.769 13165 13557 I force_gc: reason=Binder
07-24 19:39:58.616 13165 13557 I force_gc: reason=Binder
07-24 19:40:15.624 13165 13557 I force_gc: reason=Binder
07-24 19:40:31.187 13165 13557 I force_gc: reason=Binder
07-24 19:40:45.646 13165 13557 I force_gc: reason=Binder
07-24 19:41:02.838 13165 13557 I force_gc: reason=Binder
07-24 19:41:19.979 13165 13557 I force_gc: reason=Binder
07-24 19:41:38.279 13165 13557 I force_gc: reason=Binder
07-24 19:41:56.625 13165 13557 I force_gc: reason=Binder
07-24 19:42:15.314 13165 13557 I force_gc: reason=Binder
07-24 19:42:33.630 13165 13557 I force_gc: reason=Binder
07-24 19:42:51.859 13165 13557 I force_gc: reason=Binder
07-24 19:43:10.164 13165 13557 I force_gc: reason=Binder
07-24 19:43:28.851 13165 13557 I force_gc: reason=Binder
07-24 19:43:47.527 13165 13557 I force_gc: reason=Binder
07-24 19:44:05.160 13165 13557 I force_gc: reason=Binder
07-24 19:44:17.986 13165 13165 I wm_on_top_resumed_lost_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topStateChangedWhenResumed]
07-24 19:44:17.999 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.MainActivity]
07-24 19:44:19.120 13165 13165 I wm_on_paused_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performPause,time=1ms]
07-24 19:44:19.128 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]
07-24 19:44:19.154 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 19:44:20.422 13165 13557 I force_gc: reason=Binder
07-24 19:59:01.397 13165 13165 I wm_on_restart_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performRestart,time=0ms]
07-24 19:59:01.400 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=2ms]
07-24 19:59:01.403 13165 13165 I wm_on_resume_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=RESUME_ACTIVITY,time=3ms]
07-24 19:59:01.403 13165 13165 I wm_on_top_resumed_gained_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topWhenResuming]
07-24 19:59:01.415 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 19:59:01.451 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 19:59:13.473 13165 13414 I force_gc: reason=Binder
07-24 19:59:30.663 13165 13557 I force_gc: reason=Binder
07-24 19:59:47.675 13165 13557 I force_gc: reason=Binder
07-24 20:00:04.740 13165 13557 I force_gc: reason=Binder
07-24 20:00:21.765 13165 13557 I force_gc: reason=Binder
07-24 20:00:39.216 13165 13557 I force_gc: reason=Binder
07-24 20:00:55.934 13165 13557 I force_gc: reason=Binder
07-24 20:01:12.706 13165 13557 I force_gc: reason=Binder
07-24 20:01:29.624 13165 13557 I force_gc: reason=Binder
07-24 20:01:47.242 13165 13557 I force_gc: reason=Binder
07-24 20:02:04.149 13165 13557 I force_gc: reason=Binder
07-24 20:02:21.530 13165 13557 I force_gc: reason=Binder
07-24 20:02:39.260 13165 13557 I force_gc: reason=Binder
07-24 20:02:56.649 13165 13557 I force_gc: reason=Binder
07-24 20:03:13.361 13165 13557 I force_gc: reason=Binder
07-24 20:03:30.549 13165 13557 I force_gc: reason=Binder
07-24 20:03:47.942 13165 13557 I force_gc: reason=Binder
07-24 20:04:04.859 13165 13557 I force_gc: reason=Binder
07-24 20:04:22.246 13165 13557 I force_gc: reason=Binder
07-24 20:04:39.985 13165 13414 I force_gc: reason=Binder
07-24 20:04:57.048 13165 13414 I force_gc: reason=Binder
07-24 20:05:14.196 13165 13414 I force_gc: reason=Binder
07-24 20:05:31.749 13165 13414 I force_gc: reason=Binder
07-24 20:05:47.563 13165 13414 I force_gc: reason=Binder
07-24 20:06:01.898 13165 13414 I force_gc: reason=Binder
07-24 20:06:18.642 13165 13414 I force_gc: reason=Binder
07-24 20:06:34.864 13165 13414 I force_gc: reason=Binder
07-24 20:06:52.069 13165 13414 I force_gc: reason=Binder
07-24 20:07:08.911 13165 13414 I force_gc: reason=Binder
07-24 20:07:25.978 13165 13414 I force_gc: reason=Binder
07-24 20:07:38.722 13165 13414 I force_gc: reason=Binder
07-24 20:07:56.079 13165 13414 I force_gc: reason=Binder
07-24 20:08:13.470 13165 13414 I force_gc: reason=Binder
07-24 20:08:31.121 13165 13414 I force_gc: reason=Binder
07-24 20:08:48.570 13165 13414 I force_gc: reason=Binder
07-24 20:09:05.799 13165 13414 I force_gc: reason=Binder
07-24 20:09:23.001 13165 13557 I force_gc: reason=Binder
07-24 20:09:40.113 13165 13557 I force_gc: reason=Binder
07-24 20:09:57.536 13165 13557 I force_gc: reason=Binder
07-24 20:10:14.660 13165 13557 I force_gc: reason=Binder
07-24 20:10:31.937 13165 13557 I force_gc: reason=Binder
07-24 20:10:49.099 13165 13557 I force_gc: reason=Binder
07-24 20:11:06.307 13165 13557 I force_gc: reason=Binder
07-24 20:11:23.418 13165 13557 I force_gc: reason=Binder
07-24 20:11:40.599 13165 13557 I force_gc: reason=Binder
07-24 20:11:58.154 13165 13557 I force_gc: reason=Binder
07-24 20:12:15.759 13165 13557 I force_gc: reason=Binder
07-24 20:12:31.511 13165 13414 I force_gc: reason=Binder
07-24 20:12:48.805 13165 13414 I force_gc: reason=Binder
07-24 20:13:05.214 13165 13414 I force_gc: reason=Binder
07-24 20:13:22.423 13165 13414 I force_gc: reason=Binder
07-24 20:13:40.201 13165 13414 I force_gc: reason=Binder
07-24 20:13:57.156 13165 13414 I force_gc: reason=Binder
07-24 20:14:14.144 13165 13414 I force_gc: reason=Binder
07-24 20:14:31.441 13165 13414 I force_gc: reason=Binder
07-24 20:14:44.443 13165 13414 I force_gc: reason=Binder
07-24 20:15:01.172 13165 13414 I force_gc: reason=Binder
07-24 20:15:19.206 13165 13414 I force_gc: reason=Binder
07-24 20:15:36.466 13165 13414 I force_gc: reason=Binder
07-24 20:15:53.873 13165 13414 I force_gc: reason=Binder
07-24 20:16:11.331 13165 13414 I force_gc: reason=Binder
07-24 20:16:28.340 13165 13414 I force_gc: reason=Binder
07-24 20:16:45.267 13165 13414 I force_gc: reason=Binder
07-24 20:17:02.541 13165 13414 I force_gc: reason=Binder
07-24 20:17:16.787 13165 13414 I force_gc: reason=Binder
--------- beginning of main
07-24 20:19:32.618 13165 13414 I SMSIE : Deleting old exports ...
07-24 20:19:33.580 13165 13414 I SMSIE : 2 exports deleted
07-24 20:19:33.763 13165 13196 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:34.771 13165 13196 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:35.778 13165 13197 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:36.781 13165 13188 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:37.783 13165 13188 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:38.782 13165 13195 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:39.783 13165 13188 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:40.786 13165 13195 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:41.799 13165 13196 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:42.616 13165 13413 I SMSIE : Deleting old exports ...
07-24 20:19:42.876 13165 13413 I SMSIE : 2 exports deleted
07-24 20:19:43.053 13165 13195 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:44.057 13165 13197 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:45.054 13165 13188 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:46.058 13165 13195 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:47.060 13165 13196 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:48.071 13165 13188 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:49.077 13165 13196 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:50.078 13165 13195 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
--------- switch to events
07-24 20:19:50.986 13165 13413 I force_gc: reason=Binder
--------- switch to main
07-24 20:19:51.078 13165 13197 I WM-Processor: Moving WorkSpec (df914a6e-5703-4d55-b11b-6061cd336cb6) to the foreground
07-24 20:19:51.473 13165 13413 E SMSIE : EXPORT_AUTOMATIC failed
07-24 20:19:51.473 13165 13413 E SMSIE : java.lang.IllegalStateException: Nesting problem: [NONEMPTY_DOCUMENT, NONEMPTY_ARRAY, NONEMPTY_OBJECT, NONEMPTY_ARRAY, NONEMPTY_OBJECT]
07-24 20:19:51.473 13165 13413 E SMSIE : at android.util.JsonWriter.close(JsonWriter.java:254)
07-24 20:19:51.473 13165 13413 E SMSIE : at android.util.JsonWriter.endArray(JsonWriter.java:213)
07-24 20:19:51.473 13165 13413 E SMSIE : at s0.G.e(SourceFile:558)
07-24 20:19:51.473 13165 13413 E SMSIE : at F0.l.m(SourceFile:11)
07-24 20:19:51.473 13165 13413 E SMSIE : at m1.a.g(SourceFile:9)
07-24 20:19:51.473 13165 13413 E SMSIE : at A1.C.run(SourceFile:115)
07-24 20:19:51.473 13165 13413 E SMSIE : at A0.a.c(SourceFile:6)
07-24 20:19:51.473 13165 13413 E SMSIE : at A0.a.run(SourceFile:114)
07-24 20:19:51.473 13165 13413 E SMSIE : at H1.j.run(SourceFile:3)
07-24 20:19:51.473 13165 13413 E SMSIE : at H1.a.run(SourceFile:88)
07-24 20:19:51.490 13165 13413 I SMSIE : EXPORT_AUTOMATIC result: Failure {mOutputData=Data {title : Scheduled export error, message : Nesting problem: [NONEMPTY_DOCUMENT, NONEMPTY_ARRAY, NONEMPTY_OBJECT, NONEMPTY_ARRAY, NONEMPTY_OBJECT]
07-24 20:19:51.490 13165 13413 I SMSIE :
07-24 20:19:51.490 13165 13413 I SMSIE : See logcat for more information., success : false}}
07-24 20:19:51.499 13165 13196 I WM-WorkerWrapper: Worker result FAILURE for Work [ id=df914a6e-5703-4d55-b11b-6061cd336cb6, tags={ com.github.tmo1.sms_ie.ImportExportWorker,export } ]
07-24 20:19:51.509 13165 13165 I WM-SystemFgDispatcher: Stopping foreground service
07-24 20:19:51.592 13165 13165 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=androidx.activity.r@ff9cfe1
--------- switch to events
07-24 20:19:51.639 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 20:26:39.205 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.MainActivity]
07-24 20:26:39.227 13165 13165 I wm_on_top_resumed_lost_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topStateChangedWhenResumed]
07-24 20:26:40.754 13165 13165 I wm_on_paused_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performPause,time=5ms]
07-24 20:26:40.797 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]
07-24 20:26:40.849 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=View.GONE]
07-24 20:26:40.865 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=View.INVISIBLE]
07-24 20:27:01.553 13165 13165 I wm_on_restart_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performRestart,time=0ms]
07-24 20:27:01.557 13165 13165 I wm_on_start_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=handleStartActivity,time=4ms]
07-24 20:27:01.560 13165 13165 I wm_on_resume_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 20:27:01.561 13165 13165 I wm_on_top_resumed_gained_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topWhenResuming]
07-24 20:27:01.570 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 20:27:01.593 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 20:27:01.596 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Start draw after previous draw not visible]
07-24 20:27:01.602 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 20:27:01.605 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
07-24 20:27:01.617 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=reportDrawFinished seqId=0]
--------- switch to main
07-24 20:27:04.468 13165 13165 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=android.app.Dialog$$ExternalSyntheticLambda2@77df4d8
07-24 20:27:04.474 13165 13189 D HWUI : endAllActiveAnimators on 0x700d7298cc1ac00 (RippleDrawable) with handle 0x700d5f67c339d50
07-24 20:27:04.484 13165 13165 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=androidx.activity.r@ff9cfe1
07-24 20:27:04.523 13165 13165 D InsetsController: hide(ime(), fromIme=false)
07-24 20:27:04.523 13165 13165 I ImeTracker: com.github.tmo1.sms_ie:c68abfad: onCancelled at PHASE_CLIENT_ALREADY_HIDDEN
07-24 20:27:15.727 13165 13165 D InsetsController: hide(ime(), fromIme=false)
07-24 20:27:15.727 13165 13165 I ImeTracker: com.github.tmo1.sms_ie:9a723954: onCancelled at PHASE_CLIENT_ALREADY_HIDDEN
--------- switch to events
07-24 20:27:20.269 13165 13165 I wm_on_top_resumed_lost_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=topStateChangedWhenResumed]
07-24 20:27:20.270 13165 13165 I view_enqueue_input_event: [eventType=Motion - Cancel,action=com.github.tmo1.sms_ie/com.github.tmo1.sms_ie.MainActivity]
07-24 20:27:20.770 13165 13165 I wm_on_paused_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=performPause,time=1ms]
--------- switch to main
07-24 20:27:20.771 13165 13165 D VRI[MainActivity]: visibilityChanged oldVisibility=true newVisibility=false
--------- switch to events
07-24 20:27:20.801 13165 13165 I viewroot_draw_event: [window=VRI[MainActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
07-24 20:27:20.809 13165 13165 I wm_on_stop_called: [Token=115838607,Component Name=com.github.tmo1.sms_ie.MainActivity,Reason=STOP_ACTIVITY_ITEM,time=1ms]