oriain os4上的奇怪bug

[L-JINBIN]

Please report bugs of Shizuku itself.

Requirements:

• Shizuku version is up-to-date
• Shizuku is downloaded from official channels (GitHub release or Google Play)
• Shizuku is not running in a virtual environment or broken ROM (GrapheneOS)
• (Root users) No Xposed installed / Xposed is not enabled for Shizuku

Change "[ ]" to "[x]" if it meets the requirements.

Reports not meet the requirements will be immediately closed.

Information:

• Shizuku version: 13.5.4.r1049.0e53409
• Mode: [adb, root] adb
• Android version: oriain os4 (Android 14)
• Device: vivo S18e

Logs:

2024-03-18 21:14:56.855 24920-25844 UserServiceManager app_process I New service record bin.mt.plus.canary:bin.mt.shell.ShizukuCommand (5622e135-41bb-4141-9274-3b4885936704-1710767696854): version=24031888, daemon=false, apk=/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.apk
2024-03-18 21:14:56.855 24920-25844 UserServiceRecord app_process V Set starting timeout for service record 5622e135-41bb-4141-9274-3b4885936704-1710767696854: 30000
2024-03-18 21:14:56.856 24920-25063 UserServiceManager app_process V Starting process for service record bin.mt.plus.canary:bin.mt.shell.ShizukuCommand (5622e135-41bb-4141-9274-3b4885936704-1710767696854)...
2024-03-18 21:14:56.932 25561-25561 ImeFocusController bin.mt.plus.canary V onWindowFocus: DecorView@f97d4de[MainLightIcon] softInputMode=STATE_UNSPECIFIED|ADJUST_RESIZE
2024-03-18 21:14:56.978 27578-27578 app_process pid-27578 E finding bbqNumWrapPrototype() failed [undefined symbol: ged_kpi_target_FPS_hint]
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath failed , error(Permission denied)
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath2 failed , error(Permission denied)
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath3 failed , error(Permission denied)
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath4 failed , error(Permission denied)
2024-03-18 21:14:56.986 27578-27578 appproc pid-27578 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-03-18 21:14:56.986 27578-27578 AndroidRuntime pid-27578 D >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
2024-03-18 21:14:56.992 27578-27578 AndroidRuntime pid-27578 I Using default boot image
2024-03-18 21:14:56.992 27578-27578 AndroidRuntime pid-27578 I Leaving lock profiling enabled
2024-03-18 21:14:56.994 27578-27578 app_process pid-27578 I Using CollectorTypeCC GC.
2024-03-18 21:14:57.080 27578-27578 nativeloader pid-27578 D InitDefaultPublicLibraries for_preload=1: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libicu.so:libicui18n.so:libicuuc.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativehelper.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-03-18 21:14:57.090 27578-27578 app_process pid-27578 D Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
2024-03-18 21:14:57.090 27578-27578 app_process pid-27578 D I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt72l.dat
2024-03-18 21:14:57.106 27578-27578 VivoSystem...orkFactory pid-27578 I Instance implClass com.vivo.VivoSystemFrameworkFactoryImpl
2024-03-18 21:14:57.154 27578-27578 libc pid-27578 W Access denied finding property "ro.product.name_for_attestation"
2024-03-18 21:14:57.155 27578-27578 libc pid-27578 W Access denied finding property "ro.product.brand_for_attestation"
2024-03-18 21:14:57.155 27578-27578 libc pid-27578 W Access denied finding property "ro.product.model_for_attestation"
2024-03-18 21:14:57.151 27578-27578 main pid-27578 W type=1400 audit(0.0:14674): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=173 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-03-18 21:14:57.156 27578-27578 libc pid-27578 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-03-18 21:14:57.156 27578-27578 libc pid-27578 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-03-18 21:14:57.151 27578-27578 main pid-27578 W type=1400 audit(0.0:14675): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=173 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-03-18 21:14:57.156 27578-27578 libc pid-27578 W Access denied finding property "ro.vendor.mtk.app32_boost_support"
2024-03-18 21:14:57.156 27578-27578 libc pid-27578 W Access denied finding property "ro.vendor.mtk.hbt_dyn_type"
2024-03-18 21:14:57.156 27578-27578 libc pid-27578 W Access denied finding property "persist.vendor.mtk.hbt_dyn_type"
2024-03-18 21:14:57.151 27578-27578 main pid-27578 W type=1400 audit(0.0:14676): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=173 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-03-18 21:14:57.151 27578-27578 main pid-27578 W type=1400 audit(0.0:14677): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=399 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-03-18 21:14:57.151 27578-27578 main pid-27578 W type=1400 audit(0.0:14678): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=399 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-03-18 21:14:57.166 27578-27578 AndroidRuntime pid-27578 D Calling main entry moe.shizuku.starter.ServiceStarter
2024-03-18 21:14:57.180 25561-25561 WindowOnBackDispatcher bin.mt.plus.canary W sendCancelIfRunning: isInProgress=falsecallback=android.view.ViewRootImpl$$ExternalSyntheticLambda17@59006cd
2024-03-18 21:14:57.188 25561-27310 OpenGLRenderer bin.mt.plus.canary D endAllActiveAnimators on 0xb40000752fc4f2c0 (MenuPopupWindow$MenuDropDownListView) with handle 0xb40000753fb54ca0
2024-03-18 21:14:57.200 25561-25561 InputTransport bin.mt.plus.canary D Destroy ARC handle: 0xb40000753fb4e010
2024-03-18 21:14:57.201 25561-27280 BLASTBufferQueue bin.mt.plus.canary D VRI[PopupWindow:2dd5817]#3 destructor()
2024-03-18 21:14:57.201 25561-27280 BufferQueueConsumer bin.mt.plus.canary D VRI[PopupWindow:2dd5817]#3(BLAST Consumer)3 disconnect
2024-03-18 21:14:57.301 27578-27578 ShizukuServiceStarter pid-27578 I starting service bin.mt.plus.canary/bin.mt.shell.ShizukuCommand...
2024-03-18 21:14:57.302 27578-27578 ziparchive pid-27578 W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-03-18 21:14:57.302 27578-27578 ziparchive pid-27578 W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-03-18 21:14:57.307 27578-27578 VivoConfigStore pid-27578 D init begin
2024-03-18 21:14:57.319 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.319 27578-27578 libc pid-27578 W Access denied finding property "qemu.sf.lcd_density"
2024-03-18 21:14:57.319 27578-27578 libc pid-27578 W Access denied finding property "qemu.sf.lcd_density"
2024-03-18 21:14:57.413 27578-27578 app_process pid-27578 W unable to execute idmap2: Permission denied
2024-03-18 21:14:57.413 27578-27578 OverlayConfig pid-27578 W 'idmap2 create-multiple' failed: no mutable="false" overlays targeting "android" will be loaded
2024-03-18 21:14:57.415 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.416 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.pem.ddc isCached is true and value is true
2024-03-18 21:14:57.419 27578-27578 VgcUtil pid-27578 D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-03-18 21:14:57.419 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.420 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.420 27578-27578 VgcUtil pid-27578 D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-03-18 21:14:57.422 27578-27578 VivoConfigStore pid-27578 D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.422 27578-27578 VivoConfigStore system_process D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.422 27578-27578 VivoConfigStore system_process D key:vivo.software.globaltheme isCached is true and value is true
2024-03-18 21:14:57.433 27578-27578 app_process system_process W ClassLoaderContext classpath size mismatch. expected=1, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536] | PCL[])
2024-03-18 21:14:57.435 27578-27578 app_process system_process W ClassLoaderContext classpath size mismatch. expected=8, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536:/system/framework/services.jar2465108973:/system/framework/services.jar!classes2.dex1101484240:/system/framework/services.jar!classes3.dex379007127:/system/framework/vivo-services.jar200236959:/system/framework/vivo-services.jar!classes2.dex1583215136:/system/framework/soc-services.jar2396945984:/system/framework/services-adapter.jar*3705662762] | PCL[])
2024-03-18 21:14:57.447 27578-27578 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-03-18 21:14:57.447 27578-27578 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-03-18 21:14:57.447 27578-27578 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-03-18 21:14:57.457 27578-27578 nativeloader system_process D InitApexLibraries:
com_android_art: libnativehelper.so
com_android_i18n: libicui18n.so:libicuuc.so:libicu.so
com_android_neuralnetworks: libneuralnetworks.so
2024-03-18 21:14:57.457 27578-27578 nativeloader system_process D InitDefaultPublicLibraries for_preload=0: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libclang_rt.hwasan-aarch64-android.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-03-18 21:14:57.457 27578-27578 nativeloader system_process D Configuring clns-1 for other apk /system/framework/org.apache.http.legacy.jar. target_sdk_version=30, uses_libraries=ALL, library_path=/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/lib/arm64:/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand
2024-03-18 21:14:57.457 27578-27578 nativeloader system_process D InitExtendedPublicLibraries: libapuwareapusys.mtk.so:libapuwareapusys_v2.mtk.so:libapuwarexrp.mtk.so:libapuwarexrp_v2.mtk.so:libapuwareutils.mtk.so:libapuwareutils_v2.mtk.so:libapuwarehmp.mtk.so:libmcv_runtime_usdk.mtk.so:libneuron_graph_delegate.mtk.so:libneuronusdk_adapter.mtk.so:libtflite_mtk.mtk.so:libarmnn_ndk.mtk.so:libcmdl_ndk.mtk.so:libnir_neon_driver_ndk.mtk.so:libmvpu_runtime.mtk.so:libmvpu_runtime_pub.mtk.so:libmvpu_engine_pub.mtk.so:libmvpu_pattern_pub.mtk.so:libmvpuop_mtk_cv.mtk.so:libmvpuop_mtk_nn.mtk.so:libmvpu_runtime_25.mtk.so:libmvpu_runtime_25_pub.mtk.so:libmvpu_engine_25_pub.mtk.so:libmvpu_pattern_25_pub.mtk.so:libmvpuop25_mtk_cv.mtk.so:libmvpuop25_mtk_nn.mtk.so:libmvpu_config.mtk.so:libteeservice_client.trustonic.so
2024-03-18 21:14:57.457 27578-27578 nativeloader system_process D Extending system_exposed_libraries: libapuwareapusys.mtk.so:libapuwareapusys_v2.mtk.so:libapuwarexrp.mtk.so:libapuwarexrp_v2.mtk.so:libapuwareutils.mtk.so:libapuwareutils_v2.mtk.so:libapuwarehmp.mtk.so:libmcv_runtime_usdk.mtk.so:libneuron_graph_delegate.mtk.so:libneuronusdk_adapter.mtk.so:libtflite_mtk.mtk.so:libarmnn_ndk.mtk.so:libcmdl_ndk.mtk.so:libnir_neon_driver_ndk.mtk.so:libmvpu_runtime.mtk.so:libmvpu_runtime_pub.mtk.so:libmvpu_engine_pub.mtk.so:libmvpu_pattern_pub.mtk.so:libmvpuop_mtk_cv.mtk.so:libmvpuop_mtk_nn.mtk.so:libmvpu_runtime_25.mtk.so:libmvpu_runtime_25_pub.mtk.so:libmvpu_engine_25_pub.mtk.so:libmvpu_pattern_25_pub.mtk.so:libmvpuop25_mtk_cv.mtk.so:libmvpuop25_mtk_nn.mtk.so:libmvpu_config.mtk.so:libteeservice_client.trustonic.so
2024-03-18 21:14:57.458 27578-27578 nativeloader system_process D InitVendorPublicLibraries: libOpenCL.so:libnir_neon_driver_ndk.mtk.vndk.so:libcmdl_ndk.mtk.vndk.so:libarmnn_ndk.mtk.vndk.so
2024-03-18 21:14:57.458 27578-27578 nativeloader system_process D InitProductPublicLibraries:
2024-03-18 21:14:57.458 27578-27578 ziparchive system_process W Unable to open '/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.dm': No such file or directory
2024-03-18 21:14:57.458 27578-27578 ziparchive system_process W Unable to open '/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.dm': No such file or directory
---------------------------- PROCESS STARTED (27578) for package bin.mt.plus.canary ----------------------------
2024-03-18 21:14:57.958 27578-27578 nativeloader bin.mt.plus.canary D Configuring clns-2 for other apk /data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.apk. target_sdk_version=30, uses_libraries=, library_path=/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/lib/arm64:/data/app/~~2PtiKLLFHpA44MoyTj1ipg==/bin.mt.plus.canary-ZiBV-tbRIhYb6BbzhvoiWQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand
2024-03-18 21:14:57.958 27578-27578 nativeloader bin.mt.plus.canary D Extending system_exposed_libraries: libapuwareapusys.mtk.so:libapuwareapusys_v2.mtk.so:libapuwarexrp.mtk.so:libapuwarexrp_v2.mtk.so:libapuwareutils.mtk.so:libapuwareutils_v2.mtk.so:libapuwarehmp.mtk.so:libmcv_runtime_usdk.mtk.so:libneuron_graph_delegate.mtk.so:libneuronusdk_adapter.mtk.so:libtflite_mtk.mtk.so:libarmnn_ndk.mtk.so:libcmdl_ndk.mtk.so:libnir_neon_driver_ndk.mtk.so:libmvpu_runtime.mtk.so:libmvpu_runtime_pub.mtk.so:libmvpu_engine_pub.mtk.so:libmvpu_pattern_pub.mtk.so:libmvpuop_mtk_cv.mtk.so:libmvpuop_mtk_nn.mtk.so:libmvpu_runtime_25.mtk.so:libmvpu_runtime_25_pub.mtk.so:libmvpu_engine_25_pub.mtk.so:libmvpu_pattern_25_pub.mtk.so:libmvpuop25_mtk_cv.mtk.so:libmvpuop25_mtk_nn.mtk.so:libmvpu_config.mtk.so:libteeservice_client.trustonic.so
2024-03-18 21:14:57.963 27578-27578 ShizukuServiceStarter bin.mt.plus.canary E provider is null moe.shizuku.privileged.api.shizuku 0
2024-03-18 21:14:57.966 27578-27578 app_process bin.mt.plus.canary I System.exit called, status: 1
---------------------------- PROCESS ENDED (27578) for package bin.mt.plus.canary ----------------------------
2024-03-18 21:14:57.966 27578-27578 AndroidRuntime bin.mt.plus.canary I VM exiting with result code 1.

Describe the bug:

调用 Shizuku.bindUserService 后可能接收不到 onServiceConnected 回调，logcat 中出现 provider is null moe.shizuku.privileged.api.shizuku 0 错误。

而且这个问题是概率性的，每当重新启动 Shizuku 服务后，要么一直成功，要么一直错误。

目前发现出现这个错误后，去 Shizuku 中关闭 APP 的授权再重新打开，就正常了。但如果重启 Shizuku 服务，就可能再次出现该问题。

Additional context:

Add any other context about the problem here.

[qianjujun]

这个问题你解决了吗

[L-JINBIN]

这个问题你解决了吗

没有，目前只能提示用户去 Shizuku 中关闭授权

[RikkaW]

provider is null 应该出自这里：

Shizuku/server/src/main/java/rikka/shizuku/server/ShizukuService.java

Lines 527 to 532 in 9792354

	try {
	provider = ActivityManagerApis.getContentProviderExternal(name, userId, token, name);
	if (provider == null) {
	LOGGER.e("provider is null %s %d", name, userId);
	return;
	}

正常情况下，它应该永远不是 null。

听说一些厂商在限制后台运行的时候的行为也包括，使应用的 provider 不能被获取。可以看看是不是这个原因（

[laiqun]

这个问题和我的一样，我发现的规律是，我自己的应用打开后，需要稍微等待一会儿，大概1.8s左右，就有大概率是PASS的。如果一开始失败了，后续继续尝试，大概过10s以后，也会成功。

查了一下，可能是provider的 onCreate有耗时操作,disableAutomatic一SuiInitialization不清楚这个API是否能改善这个情况。

参考仓库中的代码实现，可以继承一下ShizukuProvider，重写他的OnCreate方法。
尝试了一下，重写Provider的OnCreate问题还在，provider ready还是会出问题，暂时的work around是加点延迟

[RikkaW]

Sui.isSui() 里面就很简单的 binder 通信，它不可能说需要秒级别的时间的

[laiqun]

[laiqun]

添加了PASS和FAIL时的logcat日志对比图

[laiqun]

2024-05-01 00:59:16.455 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10087, cached=false
2024-05-01 00:59:16.456 10738-10767 BinderSender shizuku_server V Uid 10087 already starts
2024-05-01 00:59:16.457 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10261, cached=false
2024-05-01 00:59:16.457 10738-10767 BinderSender shizuku_server V Uid 10261 already starts
2024-05-01 00:59:16.458 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=false
2024-05-01 00:59:16.458 10738-10767 BinderSender shizuku_server V Uid 10038 already starts
2024-05-01 00:59:16.463 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10087, cached=true
2024-05-01 00:59:16.465 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10261, cached=true
2024-05-01 00:59:16.465 10738-10767 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=true
2024-05-01 00:59:17.452 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10461
2024-05-01 00:59:17.452 10738-10766 BinderSender shizuku_server V Uid 10461 starts
2024-05-01 00:59:17.452 10738-10766 BinderSender shizuku_server D sendBinder to uid 10461: packages=com.example.xyclicker
2024-05-01 00:59:17.459 10738-10766 Service shizuku_server V Add 0:com.example.xyclicker to power save temp whitelist for 30s
2024-05-01 00:59:17.476 10738-10767 BinderSender shizuku_server D onForegroundActivitiesChanged: pid=29042, uid=10461, foregroundActivities=true
2024-05-01 00:59:17.476 10738-10767 BinderSender shizuku_server D sendBinder to uid 10461: packages=com.example.xyclicker
2024-05-01 00:59:17.477 10738-10767 Service shizuku_server V Add 0:com.example.xyclicker to power save temp whitelist for 30s
2024-05-01 00:59:17.491 10738-10767 Service shizuku_server D attachApplication: com.example.xyclicker 10461 29042
2024-05-01 00:59:17.491 10738-10767 Service shizuku_server I send binder to user app com.example.xyclicker in user 0
2024-05-01 00:59:17.492 10738-10767 BinderSender shizuku_server D onForegroundActivitiesChanged: pid=2598, uid=10097, foregroundActivities=false
2024-05-01 00:59:17.492 10738-10766 Service shizuku_server I send binder to user app com.example.xyclicker in user 0
2024-05-01 00:59:17.494 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10461, cached=false
2024-05-01 00:59:17.495 10738-10766 BinderSender shizuku_server V Uid 10461 already starts
2024-05-01 00:59:17.879 1735-1938 ActivityTaskManager system_server I Displayed com.example.xyclicker/.MainActivity for user 0: +488ms
2024-05-01 00:59:17.964 3646-3646 InputTransport com.iflytek.inputmethod D Create ARC handle: 0xb400007be5e0de00
2024-05-01 00:59:18.059 5936-5964 FontLog com.google.android.gms.persistent I (REDACTED) Received query %s, URI %s
2024-05-01 00:59:18.059 5936-5964 FontLog com.google.android.gms.persistent I (REDACTED) Query [%s] resolved to %s
2024-05-01 00:59:18.063 5936-5964 FontLog com.google.android.gms.persistent I (REDACTED) Fetch %s end status %s
2024-05-01 00:59:18.069 5936-5964 FontLog com.google.android.gms.persistent I (REDACTED) Pulling font file for id = %d, cache size = %d
2024-05-01 00:59:18.074 5936-5964 FontLog com.google.android.gms.persistent I (REDACTED) Pulling font file for id = %d, cache size = %d
2024-05-01 00:59:18.587 10738-10738 binder:10738_4 shizuku_server W type=1400 audit(0.0:2830010): avc: denied { watch } for path="/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==" dev="dm-59" ino=613674 scontext=u:r:shell:s0 tcontext=u:object_r:apk_data_file:s0 tclass=dir permissive=0
2024-05-01 00:59:18.595 10738-10767 UserServiceManager shizuku_server I New service record com.example.xyclicker:com.example.xyclicker.service.ClickerService (093f8aca-a793-4b71-97e0-900a59f4cf9f-1714496358593): version=1, daemon=false, apk=/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk
2024-05-01 00:59:18.596 10738-10767 UserServiceRecord shizuku_server V Set starting timeout for service record 093f8aca-a793-4b71-97e0-900a59f4cf9f-1714496358593: 30000
2024-05-01 00:59:18.599 10738-10892 UserServiceManager shizuku_server V Starting process for service record com.example.xyclicker:com.example.xyclicker.service.ClickerService (093f8aca-a793-4b71-97e0-900a59f4cf9f-1714496358593)...
2024-05-01 00:59:18.718 19003-19003 appproc pid-19003 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath failed , error(Permission denied)
2024-05-01 00:59:18.718 19003-19003 appproc pid-19003 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 00:59:18.719 19003-19003 appproc pid-19003 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath2 failed , error(Permission denied)
2024-05-01 00:59:18.719 19003-19003 appproc pid-19003 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 00:59:18.719 19003-19003 appproc pid-19003 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath3 failed , error(Permission denied)
2024-05-01 00:59:18.719 19003-19003 appproc pid-19003 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 00:59:18.720 19003-19003 appproc pid-19003 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath4 failed , error(Permission denied)
2024-05-01 00:59:18.720 19003-19003 appproc pid-19003 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 00:59:18.720 19003-19003 AndroidRuntime pid-19003 D >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
2024-05-01 00:59:18.737 19003-19003 AndroidRuntime pid-19003 I Using default boot image
2024-05-01 00:59:18.737 19003-19003 AndroidRuntime pid-19003 I Leaving lock profiling enabled
2024-05-01 00:59:18.741 19003-19003 com.exampl...er:service pid-19003 I Using CollectorTypeCC GC.
2024-05-01 00:59:18.947 19003-19003 nativeloader pid-19003 D InitDefaultPublicLibraries for_preload=1: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libicu.so:libicui18n.so:libicuuc.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativehelper.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-05-01 00:59:18.957 19003-19003 com.exampl...er:service pid-19003 D Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
2024-05-01 00:59:18.957 19003-19003 com.exampl...er:service pid-19003 D I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt72l.dat
2024-05-01 00:59:18.966 19003-19003 ziparchive pid-19003 W Unable to open '/data/app/~~J4Xm-q3GYoB_hQmN0vwHFA==/moe.shizuku.privileged.api-C8L6VqzQFdsbEUw-XFzDIQ==/base.dm': No such file or directory
2024-05-01 00:59:18.966 19003-19003 ziparchive pid-19003 W Unable to open '/data/app/~~J4Xm-q3GYoB_hQmN0vwHFA==/moe.shizuku.privileged.api-C8L6VqzQFdsbEUw-XFzDIQ==/base.dm': No such file or directory
2024-05-01 00:59:18.994 19003-19003 VivoSystem...orkFactory pid-19003 I Instance implClass com.vivo.VivoSystemFrameworkFactoryImpl
2024-05-01 00:59:19.027 19003-19003 libc pid-19003 W Access denied finding property "ro.product.name_for_attestation"
2024-05-01 00:59:19.028 19003-19003 libc pid-19003 W Access denied finding property "ro.product.brand_for_attestation"
2024-05-01 00:59:19.028 19003-19003 libc pid-19003 W Access denied finding property "ro.product.model_for_attestation"
2024-05-01 00:59:19.028 19003-19003 libc pid-19003 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-05-01 00:59:19.029 19003-19003 libc pid-19003 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-05-01 00:59:19.029 19003-19003 libc pid-19003 W Access denied finding property "ro.vendor.mtk.app32_boost_support"
2024-05-01 00:59:19.029 19003-19003 libc pid-19003 W Access denied finding property "ro.vendor.mtk.hbt_dyn_type"
2024-05-01 00:59:19.029 19003-19003 libc pid-19003 W Access denied finding property "persist.vendor.mtk.hbt_dyn_type"
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830012): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=150 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830013): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=150 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830014): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830015): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830016): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830017): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.023 19003-19003 main pid-19003 W type=1400 audit(0.0:2830018): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 00:59:19.038 19003-19003 AndroidRuntime pid-19003 D Calling main entry moe.shizuku.starter.ServiceStarter
2024-05-01 00:59:19.167 19003-19003 ShizukuServiceStarter com.example.xyclicker I starting service com.example.xyclicker/com.example.xyclicker.service.ClickerService...
2024-05-01 00:59:19.171 19003-19003 ziparchive com.example.xyclicker W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-05-01 00:59:19.171 19003-19003 ziparchive com.example.xyclicker W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-05-01 00:59:19.175 19003-19003 VivoConfigStore com.example.xyclicker D init begin
2024-05-01 00:59:19.188 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.189 19003-19003 libc com.example.xyclicker W Access denied finding property "qemu.sf.lcd_density"
2024-05-01 00:59:19.189 19003-19003 libc com.example.xyclicker W Access denied finding property "qemu.sf.lcd_density"
2024-05-01 00:59:19.326 19003-19003 com.exampl...er:service com.example.xyclicker W unable to execute idmap2: Permission denied
2024-05-01 00:59:19.327 19003-19003 OverlayConfig com.example.xyclicker W 'idmap2 create-multiple' failed: no mutable="false" overlays targeting "android" will be loaded
2024-05-01 00:59:19.334 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.336 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.pem.ddc isCached is true and value is true
2024-05-01 00:59:19.345 19003-19003 VgcUtil com.example.xyclicker D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-05-01 00:59:19.345 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.346 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.347 19003-19003 VgcUtil com.example.xyclicker D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-05-01 00:59:19.348 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.349 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.349 19003-19003 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 00:59:19.360 19003-19003 com.exampl...er:service system_process W ClassLoaderContext classpath size mismatch. expected=1, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536] | PCL[])
2024-05-01 00:59:19.370 19003-19003 com.exampl...er:service system_process W ClassLoaderContext classpath size mismatch. expected=8, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536:/system/framework/services.jar3972749036:/system/framework/services.jar!classes2.dex3311746127:/system/framework/services.jar!classes3.dex1279713898:/system/framework/vivo-services.jar532792025:/system/framework/vivo-services.jar!classes2.dex3438074220:/system/framework/soc-services.jar2396945984:/system/framework/services-adapter.jar56863701] | PCL[])
2024-05-01 00:59:19.384 19003-19003 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-05-01 00:59:19.384 19003-19003 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-05-01 00:59:19.384 19003-19003 System system_process W No Compatibility callbacks set! Querying change 263076149
2024-05-01 00:59:19.391 19003-19003 nativeloader system_process D InitApexLibraries:
com_android_art: libnativehelper.so
com_android_i18n: libicui18n.so:libicuuc.so:libicu.so
com_android_neuralnetworks: libneuralnetworks.so
2024-05-01 00:59:19.391 19003-19003 nativeloader system_process D InitDefaultPublicLibraries for_preload=0: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libclang_rt.hwasan-aarch64-android.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-05-01 00:59:19.391 19003-19003 nativeloader system_process D Configuring clns-1 for other apk /data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk. target_sdk_version=34, uses_libraries=, library_path=/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/lib/arm64:/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand
2024-05-01 00:59:19.394 19003-19003 nativeloader system_process D InitExtendedPublicLibraries: libapuwareapusys.mtk.so:libapuwareapusys_v2.mtk.so:libapuwarexrp.mtk.so:libapuwarexrp_v2.mtk.so:libapuwareutils.mtk.so:libapuwareutils_v2.mtk.so:libapuwarehmp.mtk.so:libmcv_runtime_usdk.mtk.so:libneuron_graph_delegate.mtk.so:libneuronusdk_adapter.mtk.so:libtflite_mtk.mtk.so:libarmnn_ndk.mtk.so:libcmdl_ndk.mtk.so:libnir_neon_driver_ndk.mtk.so:libmvpu_runtime.mtk.so:libmvpu_runtime_pub.mtk.so:libmvpu_engine_pub.mtk.so:libmvpu_pattern_pub.mtk.so:libmvpuop_mtk_cv.mtk.so:libmvpuop_mtk_nn.mtk.so:libmvpu_runtime_25.mtk.so:libmvpu_runtime_25_pub.mtk.so:libmvpu_engine_25_pub.mtk.so:libmvpu_pattern_25_pub.mtk.so:libmvpuop25_mtk_cv.mtk.so:libmvpuop25_mtk_nn.mtk.so:libmvpu_config.mtk.so:libteeservice_client.trustonic.so
2024-05-01 00:59:19.395 19003-19003 nativeloader system_process D InitVendorPublicLibraries: libOpenCL.so:libnir_neon_driver_ndk.mtk.vndk.so:libcmdl_ndk.mtk.vndk.so:libarmnn_ndk.mtk.vndk.so:libvcap_npu_network.so:libvcap_npu_network_v1.so:libvdnn.so:libc++_shared.so
2024-05-01 00:59:19.396 19003-19003 nativeloader system_process D InitProductPublicLibraries:
2024-05-01 00:59:19.400 19003-19003 UserService system_process I constructor
2024-05-01 00:59:19.400 19003-19003 UserService system_process I constructor with Context: context=android.app.ContextImpl@9ac1741
2024-05-01 00:59:19.403 19003-19003 ShizukuServiceStarter system_process E provider is null moe.shizuku.privileged.api.shizuku 0
2024-05-01 00:59:19.404 19003-19003 com.exampl...er:service system_process I System.exit called, status: 1
2024-05-01 00:59:19.404 19003-19003 AndroidRuntime system_process I VM exiting with result code 1.
2024-05-01 00:59:21.800 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10252, cached=false
2024-05-01 00:59:21.802 10738-1859 BinderSender shizuku_server V Uid 10252 already starts
2024-05-01 00:59:21.807 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10252, cached=true
2024-05-01 00:59:22.713 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10073, cached=false
2024-05-01 00:59:22.713 10738-1859 BinderSender shizuku_server V Uid 10073 already starts
2024-05-01 00:59:22.719 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10073, cached=true
2024-05-01 00:59:24.661 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10148, cached=false
2024-05-01 00:59:24.661 10738-1859 BinderSender shizuku_server V Uid 10148 already starts
2024-05-01 00:59:24.667 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10148, cached=true
2024-05-01 00:59:26.345 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=false
2024-05-01 00:59:26.345 10738-1859 BinderSender shizuku_server V Uid 10038 already starts
2024-05-01 00:59:26.783 5936-5936 BoundBrokerSvc com.google.android.gms.persistent D onUnbind: Intent { act=com.google.android.gms.phenotype.service.START pkg=com.google.android.gms }
2024-05-01 00:59:32.391 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=true
2024-05-01 00:59:33.467 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10122, cached=false
2024-05-01 00:59:33.467 10738-1859 BinderSender shizuku_server V Uid 10122 already starts
2024-05-01 00:59:33.476 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10122, cached=true
2024-05-01 00:59:34.000 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@42cc2d9 ipAddress = /224.0.0.251
2024-05-01 00:59:34.963 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10230, cached=false
2024-05-01 00:59:34.964 10738-1859 BinderSender shizuku_server V Uid 10230 already starts
2024-05-01 00:59:34.968 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10230, cached=true
2024-05-01 00:59:36.146 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10071, cached=false
2024-05-01 00:59:36.147 10738-1859 BinderSender shizuku_server V Uid 10071 already starts
2024-05-01 00:59:36.157 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10071, cached=true
2024-05-01 00:59:38.564 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10063
2024-05-01 00:59:38.564 10738-1859 BinderSender shizuku_server V Uid 10063 starts
2024-05-01 00:59:38.565 10738-1859 BinderSender shizuku_server D sendBinder to uid 10063: packages=com.vivo.numbermark
2024-05-01 00:59:38.566 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10063, cached=false
2024-05-01 00:59:38.566 10738-1859 BinderSender shizuku_server V Uid 10063 already starts
2024-05-01 00:59:38.608 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10089
2024-05-01 00:59:38.608 10738-14783 BinderSender shizuku_server V Uid 10089 already starts
2024-05-01 00:59:38.710 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=false
2024-05-01 00:59:38.710 10738-14783 BinderSender shizuku_server V Uid 10038 already starts
2024-05-01 00:59:41.645 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(297) with statsTag=0x30000401, statsUid=10176
2024-05-01 00:59:41.648 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 00:59:42.008 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 00:59:42.011 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 00:59:44.626 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10063, cached=true
2024-05-01 00:59:44.718 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=true
2024-05-01 00:59:45.756 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10232, cached=false
2024-05-01 00:59:45.756 10738-14783 BinderSender shizuku_server V Uid 10232 already starts
2024-05-01 00:59:45.761 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10232, cached=true
2024-05-01 00:59:46.939 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10061, cached=false
2024-05-01 00:59:46.939 10738-14783 BinderSender shizuku_server V Uid 10061 already starts
2024-05-01 00:59:46.948 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10061, cached=true
2024-05-01 00:59:48.612 10738-10738 UserServiceRecord shizuku_server W Service record 093f8aca-a793-4b71-97e0-900a59f4cf9f-1714496358593 is not started in 30000 ms
2024-05-01 00:59:49.119 4871-4917 android.vending com.android.vending I dgc_arg: 2048KB,8192KB
2024-05-01 00:59:54.001 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@3166b77 ipAddress = /224.0.0.251
2024-05-01 00:59:54.279 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10380, cached=false
2024-05-01 00:59:54.279 10738-14783 BinderSender shizuku_server V Uid 10380 already starts
2024-05-01 00:59:54.280 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10380, cached=true
2024-05-01 00:59:54.285 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10380, cached=false
2024-05-01 00:59:54.286 10738-14783 BinderSender shizuku_server V Uid 10380 already starts
2024-05-01 00:59:55.309 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10380, cached=true
2024-05-01 00:59:57.429 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10225, cached=false
2024-05-01 00:59:57.429 10738-14783 BinderSender shizuku_server V Uid 10225 already starts
2024-05-01 00:59:57.440 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10225, cached=true
2024-05-01 00:59:58.965 10738-14783 BinderSender shizuku_server D onProcessDied: pid=13650, uid=1000
2024-05-01 01:00:00.310 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10224, cached=false
2024-05-01 01:00:00.311 10738-14783 BinderSender shizuku_server V Uid 10224 already starts
2024-05-01 01:00:00.318 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10224, cached=true
2024-05-01 01:00:03.669 10738-14783 BinderSender shizuku_server D onUidIdle: uid=10199, disabled=false
2024-05-01 01:00:03.669 10738-14783 BinderSender shizuku_server V Uid 10199 already starts
2024-05-01 01:00:04.334 10738-14783 BinderSender shizuku_server D onUidIdle: uid=10380, disabled=false
2024-05-01 01:00:04.334 10738-14783 BinderSender shizuku_server V Uid 10380 already starts
2024-05-01 01:00:05.576 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10135, cached=false
2024-05-01 01:00:05.576 10738-14783 BinderSender shizuku_server V Uid 10135 already starts
2024-05-01 01:00:05.587 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10135, cached=true
2024-05-01 01:00:05.872 10738-14783 BinderSender shizuku_server D onUidIdle: uid=10105, disabled=false
2024-05-01 01:00:05.873 10738-14783 BinderSender shizuku_server V Uid 10105 already starts
2024-05-01 01:00:10.637 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10155, cached=false
2024-05-01 01:00:10.637 10738-14783 BinderSender shizuku_server V Uid 10155 already starts
2024-05-01 01:00:10.639 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10155, cached=true
2024-05-01 01:00:11.751 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(226) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:00:11.754 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:00:12.061 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:00:12.063 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:00:14.002 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@f98114e ipAddress = /224.0.0.251
2024-05-01 01:00:16.880 13224-13314 mars::stn com.tencent.mm I [longlink_connect_monitor.cc:518, OnHeartbeatAlarmReceived]:longlink_progress on_heartbeat_alarm, time: 78407747, is_noop_timeout:false
2024-05-01 01:00:16.905 13224-23996 mars::stn com.tencent.mm I [longlink_connect_monitor.cc:512, OnHeartbeatAlarmSet]:longlink_progress on_heartbeat_set, time: 78407773, time interval:210000
2024-05-01 01:00:16.909 13224-23996 mars::stn com.tencent.mm I [longlink_connect_monitor.cc:512, OnHeartbeatAlarmSet]:longlink_progress on_heartbeat_set, time: 78407777, time interval:210000
2024-05-01 01:00:16.990 10738-14783 BinderSender shizuku_server D onUidIdle: uid=10041, disabled=false
2024-05-01 01:00:16.990 10738-14783 BinderSender shizuku_server V Uid 10041 already starts
2024-05-01 01:00:17.128 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10153, cached=false
2024-05-01 01:00:17.128 10738-14783 BinderSender shizuku_server V Uid 10153 already starts
2024-05-01 01:00:17.134 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10153, cached=true
2024-05-01 01:00:20.482 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10220, cached=false
2024-05-01 01:00:20.482 10738-14783 BinderSender shizuku_server V Uid 10220 already starts
2024-05-01 01:00:20.494 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10220, cached=true
2024-05-01 01:00:22.809 13971-19449 GMS_MM_Logger com.google.android.gms.unstable I Memory Metric Logging not allowed. Stopping.
2024-05-01 01:00:23.298 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10079, cached=false
2024-05-01 01:00:23.298 10738-14783 BinderSender shizuku_server V Uid 10079 already starts
2024-05-01 01:00:23.304 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10079, cached=true
2024-05-01 01:00:29.067 10738-14783 BinderSender shizuku_server D onProcessDied: pid=15468, uid=1000
2024-05-01 01:00:29.068 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10038, cached=false
2024-05-01 01:00:29.068 10738-14783 BinderSender shizuku_server V Uid 10038 already starts
2024-05-01 01:00:31.462 15140-15170 System com.android.mms W A resource failed to call close.
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10261, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10261 already starts
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10252, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10252 already starts
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10232, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10232 already starts
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10230, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10230 already starts
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10225, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10225 already starts
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server D onUidCachedChanged: uid=10224, cached=false
2024-05-01 01:00:33.688 10738-14783 BinderSender shizuku_server V Uid 10224 already starts
2024-05-01 01:00:33.688 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10220, cached=false
2024-05-01 01:00:33.688 10738-12617 BinderSender shizuku_server V Uid 10220 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10155, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10155 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10153, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10153 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10148, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10148 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10135, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10135 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10122, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10122 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10087, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10087 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10079, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10079 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10073, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10073 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10071, cached=false
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server V Uid 10071 already starts
2024-05-01 01:00:33.689 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10061, cached=false
2024-05-01 01:00:33.690 10738-12617 BinderSender shizuku_server V Uid 10061 already starts
2024-05-01 01:00:33.690 10738-12617 BinderSender shizuku_server D onUidCachedChanged: uid=10087, cached=true
2024-05-01 01:00:33.695 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10261, cached=true
2024-05-01 01:00:33.696 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10252, cached=true
2024-05-01 01:00:33.696 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10232, cached=true
2024-05-01 01:00:33.696 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10230, cached=true
2024-05-01 01:00:33.697 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10225, cached=true
2024-05-01 01:00:33.697 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10224, cached=true
2024-05-01 01:00:33.697 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10220, cached=true
2024-05-01 01:00:33.697 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10155, cached=true
2024-05-01 01:00:33.697 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10153, cached=true
2024-05-01 01:00:33.697 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10148, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10135, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10122, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10079, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10073, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10071, cached=true
2024-05-01 01:00:33.698 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10061, cached=true
2024-05-01 01:00:34.004 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@d53ac05 ipAddress = /224.0.0.251
2024-05-01 01:00:41.852 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(226) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:00:41.853 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:00:42.148 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:00:42.151 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:00:43.879 10738-10756 BinderSender shizuku_server D onUidIdle: uid=10044, disabled=false
2024-05-01 01:00:43.879 10738-10756 BinderSender shizuku_server V Uid 10044 starts
2024-05-01 01:00:43.879 10738-10756 BinderSender shizuku_server D sendBinder to uid 10044: packages=com.android.cellbroadcastreceiver
2024-05-01 01:00:43.880 10738-10756 BinderSender shizuku_server D onUidCachedChanged: uid=10044, cached=false
2024-05-01 01:00:43.880 10738-10756 BinderSender shizuku_server V Uid 10044 already starts
2024-05-01 01:00:43.959 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10044, cached=true
2024-05-01 01:00:44.622 10738-10766 BinderSender shizuku_server D onUidIdle: uid=10089, disabled=false
2024-05-01 01:00:44.622 10738-10766 BinderSender shizuku_server V Uid 10089 already starts
2024-05-01 01:00:44.625 10738-10766 BinderSender shizuku_server D onUidIdle: uid=10063, disabled=false
2024-05-01 01:00:44.626 10738-10766 BinderSender shizuku_server V Uid 10063 already starts
2024-05-01 01:00:44.720 10738-10766 BinderSender shizuku_server D onUidIdle: uid=10038, disabled=false
2024-05-01 01:00:44.721 10738-10766 BinderSender shizuku_server V Uid 10038 already starts
2024-05-01 01:00:49.615 20611-20611 sh pid-20611 W type=1400 audit(0.0:2830202): avc: denied { dac_override } for capability=1 scontext=u:r:vivo_daemon:s0 tcontext=u:r:vivo_daemon:s0 tclass=capability permissive=0
2024-05-01 01:00:52.372 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(107) with statsTag=0x1803, statsUid=10176
2024-05-01 01:00:52.377 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(108) with statsTag=0x1803, statsUid=10176
2024-05-01 01:00:52.381 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(109) with statsTag=0x1803, statsUid=10176
2024-05-01 01:00:52.385 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(110) with statsTag=0x1803, statsUid=10176
2024-05-01 01:00:54.006 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@b432067 ipAddress = /224.0.0.251
2024-05-01 01:00:58.210 10738-10766 BinderSender shizuku_server D onUidCachedChanged: uid=10105
2024-05-01 01:00:58.210 10738-10766 BinderSender shizuku_server V Uid 10105 already starts
2024-05-01 01:00:59.898 10738-10766 BinderSender shizuku_server D onProcessDied: pid=20686, uid=1000
2024-05-01 01:01:11.937 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(226) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:01:11.942 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:01:12.222 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:01:12.226 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:01:14.007 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@b90b4b2 ipAddress = /224.0.0.251
2024-05-01 01:01:18.750 5936-27767 NativeCrypto com.google.android.gms.persistent V Read error: ssl=0xb400007a25c086d8: I/O error during system call, Software caused connection abort
2024-05-01 01:01:18.752 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(107) with statsTag=0x1803, statsUid=10176
2024-05-01 01:01:18.754 13971-14169 TrafficStats com.google.android.gms.unstable D tagSocket(107) with statsTag=0x1803, statsUid=10176
2024-05-01 01:01:18.762 5936-8273 NativeCrypto com.google.android.gms.persistent V SSL shutdown failed: ssl=0xb400007a25c086d8: I/O error during system call, Broken pipe
2024-05-01 01:01:18.762 5936-8273 WakeLock com.google.android.gms.persistent E GCM_HB_ALARM release without a matched acquire!
2024-05-01 01:01:18.762 5936-8273 WakeLock com.google.android.gms.persistent W GCM_HB_ALARM counter does not exist
2024-05-01 01:01:18.791 5936-8273 AlarmManager com.google.android.gms.persistent I setExactAndAllowWhileIdle [name: GCM_CONN_ALARM type: 2 triggerAtMillis: 78473693]
2024-05-01 01:01:18.792 5936-8273 WakeLock com.google.android.gms.persistent E GCM_CONN_ALARM release without a matched acquire!
2024-05-01 01:01:18.792 5936-8273 WakeLock com.google.android.gms.persistent W GCM_CONN_ALARM counter does not exist
2024-05-01 01:01:23.856 5936-27767 TrafficStats com.google.android.gms.persistent D tagSocket(-1) with statsTag=0x30000407, statsUid=-1
2024-05-01 01:01:23.857 5936-27767 TrafficStats com.google.android.gms.persistent I tagSocketFd(-1, 805307399, -1) failed with errno-9
2024-05-01 01:01:23.857 5936-27767 TrafficStats com.google.android.gms.persistent D tagSocket(286) with statsTag=0x30000407, statsUid=-1
2024-05-01 01:01:23.864 5936-27767 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :5228
2024-05-01 01:01:24.347 5936-8273 AlarmManager com.google.android.gms.persistent I setExactAndAllowWhileIdle [name: GCM_HB_ALARM type: 2 triggerAtMillis: 78705214]
2024-05-01 01:01:24.709 5936-13585 AlarmManager com.google.android.gms.persistent I setExactAndAllowWhileIdle [name: GCM_HB_ALARM type: 2 triggerAtMillis: 78705576]
2024-05-01 01:01:24.714 5936-13585 WakeLock com.google.android.gms.persistent E GCM_CONN_ALARM release without a matched acquire!
2024-05-01 01:01:24.726 5936-13585 AlarmManager com.google.android.gms.persistent I setExactAndAllowWhileIdle [name: GCM_HB_ALARM type: 2 triggerAtMillis: 78705593]
2024-05-01 01:01:34.010 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@32d4f3f ipAddress = /224.0.0.251
2024-05-01 01:01:42.044 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(226) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:01:42.047 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:01:42.248 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:01:42.251 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:01:50.278 5936-5990 WakeLock com.google.android.gms.persistent E gms_scheduler/com.google.android.gms/.gass.chimera.SchedulePeriodicTasksService ** IS FORCE-RELEASED ON TIMEOUT **
2024-05-01 01:01:51.366 5936-5990 WakeLock com.google.android.gms.persistent E gms_scheduler/com.google.android.gms/.semanticlocationhistory.db.OdlhUserEditSyncJob ** IS FORCE-RELEASED ON TIMEOUT **
2024-05-01 01:01:54.011 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@95f30f8 ipAddress = /224.0.0.251
2024-05-01 01:01:59.510 10738-10766 BinderSender shizuku_server D onUidIdle: uid=10105, disabled=false
2024-05-01 01:01:59.510 10738-10766 BinderSender shizuku_server V Uid 10105 already starts
2024-05-01 01:02:05.417 5936-27767 CastDatabase com.google.android.gms.persistent I Saving the database
2024-05-01 01:02:05.433 5936-27767 SQLiteCastStore com.google.android.gms.persistent E Skip saving CastDeviceInfo: "附3备" (__cast_nearby___V_57255d96-c3e1-4b66-bb5d-f66a2e16330c)
2024-05-01 01:02:05.434 5936-27767 SQLiteCastStore com.google.android.gms.persistent I [CastNetworkInfo] saved: 0, skipped: 0, [CastDeviceInfo]: saved 0, skipped 1, [Paired Guest Mode DeviceInfo] saved: 0, skipped: 0. [ProbedNetworks]: saved 0, skipped: 0, [ProbedSocketAddress]: saved 0, skipped: 0, [Network-Device pairs]: saved: 0
2024-05-01 01:02:12.140 5936-18734 TrafficStats com.google.android.gms.persistent D tagSocket(226) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:02:12.144 5936-18734 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:02:12.349 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:02:12.352 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:02:14.013 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@67a05c2 ipAddress = /224.0.0.251
2024-05-01 01:02:28.918 4871-5057 PlayCommon com.android.vending I [7531] amfi.j(32): Preparing logs for uploading
2024-05-01 01:02:28.936 4871-5057 PlayCommon com.android.vending I [7531] amfi.j(183): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true
2024-05-01 01:02:28.936 4871-5057 System com.android.vending W ClassLoader referenced unknown path: /system/framework/tcmclient.jar
2024-05-01 01:02:28.937 4871-5057 System com.android.vending W ClassLoader referenced unknown path: /system/framework/tcmiface.jar
2024-05-01 01:02:28.954 4871-5057 TrafficStats com.android.vending D tagSocket(146) with statsTag=0x6900, statsUid=-1
2024-05-01 01:02:28.957 4871-5057 Linux com.android.vending D [Posix_connect Debug]Process com.android.vending :443
2024-05-01 01:02:42.229 5936-18734 Auth com.google.android.gms.persistent W [GetToken] GetToken failed with status code: NetworkError
2024-05-01 01:02:42.274 5936-6027 NetworkScheduler.ATC com.google.android.gms.persistent E Trying to release unacquired lock: com.google.android.gms/.semanticlocationhistory.db.OdlhUserEditSyncJob [CONTEXT service_id=218 ]
2024-05-01 01:02:42.279 5936-27767 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.300 5936-6027 NetworkScheduler.ATC com.google.android.gms.persistent E Trying to release unacquired lock: com.google.android.gms/.gass.chimera.SchedulePeriodicTasksService [CONTEXT service_id=218 ]
2024-05-01 01:02:42.309 5936-6027 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.339 5936-20887 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.347 5936-27767 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.364 5936-20887 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.390 5936-27767 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.432 5936-6242 TrafficStats com.google.android.gms.persistent D tagSocket(308) with statsTag=0x407, statsUid=-1
2024-05-01 01:02:42.435 5936-6242 TrafficStats com.google.android.gms.persistent D tagSocket(309) with statsTag=0x407, statsUid=-1
2024-05-01 01:02:42.447 5936-5965 TrafficStats com.google.android.gms.persistent D tagSocket(228) with statsTag=0x30000401, statsUid=10179
2024-05-01 01:02:42.448 5936-20887 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.448 5936-5965 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:02:42.475 5936-21019 NetworkScheduler.Stats com.google.android.gms.persistent I (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s
2024-05-01 01:02:42.608 5936-20886 PhenotypeResourceReader com.google.android.gms.persistent I unable to find any Phenotype resource metadata for com.google.android.onetimeinitializer
2024-05-01 01:02:42.695 5936-6242 TrafficStats com.google.android.gms.persistent D tagSocket(323) with statsTag=0x407, statsUid=-1
2024-05-01 01:02:42.714 5936-20886 PhenotypeResourceReader com.google.android.gms.persistent I unable to find any Phenotype resource metadata for com.google.ar.core
2024-05-01 01:02:42.970 5936-20886 GoogleCertificatesImpl com.google.android.gms.persistent W Source stamp verification failed, package: com.google.android.webview status: CANNOT_VERIFY
2024-05-01 01:02:43.065 5936-20886 PhenotypeResourceReader com.google.android.gms.persistent I unable to find any Phenotype resource metadata for com.google.android.gsf
2024-05-01 01:02:43.089 5936-20886 PhenotypeResourceReader com.google.android.gms.persistent I unable to find any Phenotype resource metadata for com.google.android.calendar
2024-05-01 01:02:43.134 5936-20886 GoogleCertificatesImpl com.google.android.gms.persistent W Source stamp verification failed, package: com.google.android.accessibility.switchaccess status: CANNOT_VERIFY
2024-05-01 01:02:43.387 5936-20886 System com.google.android.gms.persistent W ClassLoader referenced unknown path: /system/framework/tcmclient.jar
2024-05-01 01:02:43.388 5936-20886 System com.google.android.gms.persistent W ClassLoader referenced unknown path: /system/framework/tcmiface.jar
2024-05-01 01:02:43.401 5936-20886 TrafficStats com.google.android.gms.persistent D tagSocket(317) with statsTag=0x30000401, statsUid=10176
2024-05-01 01:02:43.402 5936-20886 Linux com.google.android.gms.persistent D [Posix_connect Debug]Process com.google.android.gms.persistent :443
2024-05-01 01:02:54.016 5936-15697 System.out com.google.android.gms.persistent I DatagramSocket p = java.net.DatagramPacket@616e8f3 ipAddress = /224.0.0.251
2024-05-01 01:02:55.432 3646-21052 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.433 3646-21052 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.434 3646-21052 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.436 3646-21052 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.438 3646-21053 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.439 3646-21053 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.440 3646-21053 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.440 3646-21053 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.454 3646-12242 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.455 3646-12242 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.459 3646-12242 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.460 3646-12242 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.464 3646-12242 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.465 3646-12242 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.467 3646-12242 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.468 3646-12242 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :0
2024-05-01 01:02:55.468 3646-21053 TrafficStats com.iflytek.inputmethod D tagSocket(223) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.469 3646-21052 TrafficStats com.iflytek.inputmethod D tagSocket(176) with statsTag=0xffffffff, statsUid=-1
2024-05-01 01:02:55.469 3646-21053 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :443
2024-05-01 01:02:55.470 3646-21052 Linux com.iflytek.inputmethod D [Posix_connect Debug]Process com.iflytek.inputmethod :443
2024-05-01 01:03:01.835 10738-10738 binder:10738_3 shizuku_server W type=1400 audit(0.0:2830210): avc: denied { watch } for path="/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==" dev="dm-59" ino=613674 scontext=u:r:shell:s0 tcontext=u:object_r:apk_data_file:s0 tclass=dir permissive=0
2024-05-01 01:03:01.842 10738-10766 UserServiceManager shizuku_server I New service record com.example.xyclicker:com.example.xyclicker.service.ClickerService (4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841): version=1, daemon=false, apk=/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk
2024-05-01 01:03:01.843 10738-10766 UserServiceRecord shizuku_server V Set starting timeout for service record 4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841: 30000
2024-05-01 01:03:01.844 10738-10892 UserServiceManager shizuku_server V Starting process for service record com.example.xyclicker:com.example.xyclicker.service.ClickerService (4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841)...
2024-05-01 01:03:01.920 21064-21064 appproc pid-21064 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath failed , error(Permission denied)
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath2 failed , error(Permission denied)
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath3 failed , error(Permission denied)
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv get_baksize open file /data/dalvik-cache/classpath4 failed , error(Permission denied)
2024-05-01 01:03:01.921 21064-21064 appproc pid-21064 E checkAndSetEnv write_classpathbak open file failed:Permission denied
2024-05-01 01:03:01.921 21064-21064 AndroidRuntime pid-21064 D >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
2024-05-01 01:03:01.924 21064-21064 AndroidRuntime pid-21064 I Using default boot image
2024-05-01 01:03:01.924 21064-21064 AndroidRuntime pid-21064 I Leaving lock profiling enabled
2024-05-01 01:03:01.925 21064-21064 com.exampl...er:service pid-21064 I Using CollectorTypeCC GC.
2024-05-01 01:03:01.979 21064-21064 nativeloader pid-21064 D InitDefaultPublicLibraries for_preload=1: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libicu.so:libicui18n.so:libicuuc.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativehelper.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-05-01 01:03:01.986 21064-21064 com.exampl...er:service pid-21064 D Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
2024-05-01 01:03:01.986 21064-21064 com.exampl...er:service pid-21064 D I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt72l.dat
2024-05-01 01:03:01.990 21064-21064 ziparchive pid-21064 W Unable to open '/data/app/~~J4Xm-q3GYoB_hQmN0vwHFA==/moe.shizuku.privileged.api-C8L6VqzQFdsbEUw-XFzDIQ==/base.dm': No such file or directory
2024-05-01 01:03:01.991 21064-21064 ziparchive pid-21064 W Unable to open '/data/app/~~J4Xm-q3GYoB_hQmN0vwHFA==/moe.shizuku.privileged.api-C8L6VqzQFdsbEUw-XFzDIQ==/base.dm': No such file or directory
2024-05-01 01:03:02.011 21064-21064 VivoSystem...orkFactory pid-21064 I Instance implClass com.vivo.VivoSystemFrameworkFactoryImpl
2024-05-01 01:03:02.037 21064-21064 libc pid-21064 W Access denied finding property "ro.product.name_for_attestation"
2024-05-01 01:03:02.037 21064-21064 libc pid-21064 W Access denied finding property "ro.product.brand_for_attestation"
2024-05-01 01:03:02.038 21064-21064 libc pid-21064 W Access denied finding property "ro.product.model_for_attestation"
2024-05-01 01:03:02.038 21064-21064 libc pid-21064 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-05-01 01:03:02.038 21064-21064 libc pid-21064 W Access denied finding property "ro.vendor.mtk.hbt_binfmt_misc_support"
2024-05-01 01:03:02.038 21064-21064 libc pid-21064 W Access denied finding property "ro.vendor.mtk.app32_boost_support"
2024-05-01 01:03:02.039 21064-21064 libc pid-21064 W Access denied finding property "ro.vendor.mtk.hbt_dyn_type"
2024-05-01 01:03:02.039 21064-21064 libc pid-21064 W Access denied finding property "persist.vendor.mtk.hbt_dyn_type"
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830211): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=150 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830212): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=150 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830213): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=150 scontext=u:r:shell:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830214): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830215): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830216): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830217): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.031 21064-21064 main pid-21064 W type=1400 audit(0.0:2830218): avc: denied { read } for name="u:object_r:system_mtk_app32_boost_prop:s0" dev="tmpfs" ino=376 scontext=u:r:shell:s0 tcontext=u:object_r:system_mtk_app32_boost_prop:s0 tclass=file permissive=0
2024-05-01 01:03:02.044 21064-21064 AndroidRuntime pid-21064 D Calling main entry moe.shizuku.starter.ServiceStarter
2024-05-01 01:03:02.143 21064-21064 ShizukuServiceStarter pid-21064 I starting service com.example.xyclicker/com.example.xyclicker.service.ClickerService...
2024-05-01 01:03:02.143 21064-21064 ziparchive pid-21064 W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-05-01 01:03:02.144 21064-21064 ziparchive pid-21064 W Unable to open '/system_ext/framework/mediatek-framework.dm': No such file or directory
2024-05-01 01:03:02.147 21064-21064 VivoConfigStore pid-21064 D init begin
2024-05-01 01:03:02.159 21064-21064 VivoConfigStore pid-21064 D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.159 21064-21064 libc pid-21064 W Access denied finding property "qemu.sf.lcd_density"
2024-05-01 01:03:02.160 21064-21064 libc pid-21064 W Access denied finding property "qemu.sf.lcd_density"
2024-05-01 01:03:02.230 21064-21064 com.exampl...er:service com.example.xyclicker W unable to execute idmap2: Permission denied
2024-05-01 01:03:02.230 21064-21064 OverlayConfig com.example.xyclicker W 'idmap2 create-multiple' failed: no mutable="false" overlays targeting "android" will be loaded
2024-05-01 01:03:02.234 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.235 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.pem.ddc isCached is true and value is true
2024-05-01 01:03:02.237 21064-21064 VgcUtil com.example.xyclicker D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-05-01 01:03:02.237 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.237 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.238 21064-21064 VgcUtil com.example.xyclicker D getFile name :theme_dir_path not exit in vgc_path_config.xml
2024-05-01 01:03:02.239 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.239 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.239 21064-21064 VivoConfigStore com.example.xyclicker D key:vivo.software.globaltheme isCached is true and value is true
2024-05-01 01:03:02.247 21064-21064 com.exampl...er:service com.example.xyclicker W ClassLoaderContext classpath size mismatch. expected=1, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536] | PCL[])
2024-05-01 01:03:02.248 21064-21064 com.exampl...er:service com.example.xyclicker W ClassLoaderContext classpath size mismatch. expected=8, found=0 (PCL[/system/framework/com.android.location.provider.jar3994156536:/system/framework/services.jar3972749036:/system/framework/services.jar!classes2.dex3311746127:/system/framework/services.jar!classes3.dex1279713898:/system/framework/vivo-services.jar532792025:/system/framework/vivo-services.jar!classes2.dex3438074220:/system/framework/soc-services.jar2396945984:/system/framework/services-adapter.jar56863701] | PCL[])
2024-05-01 01:03:02.256 21064-21064 System com.example.xyclicker W No Compatibility callbacks set! Querying change 263076149
2024-05-01 01:03:02.256 21064-21064 System com.example.xyclicker W No Compatibility callbacks set! Querying change 263076149
2024-05-01 01:03:02.256 21064-21064 System com.example.xyclicker W No Compatibility callbacks set! Querying change 263076149
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D InitApexLibraries:
com_android_art: libnativehelper.so
com_android_i18n: libicui18n.so:libicuuc.so:libicu.so
com_android_neuralnetworks: libneuralnetworks.so
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D InitDefaultPublicLibraries for_preload=0: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libclang_rt.hwasan-aarch64-android.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D Configuring clns-1 for other apk /data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk. target_sdk_version=34, uses_libraries=, library_path=/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/lib/arm64:/data/app/~~4QsskY0b7k5_ouv2fsQunA==/com.example.xyclicker-CP5051_ol5pXgn1k25QHHQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D InitExtendedPublicLibraries: libapuwareapusys.mtk.so:libapuwareapusys_v2.mtk.so:libapuwarexrp.mtk.so:libapuwarexrp_v2.mtk.so:libapuwareutils.mtk.so:libapuwareutils_v2.mtk.so:libapuwarehmp.mtk.so:libmcv_runtime_usdk.mtk.so:libneuron_graph_delegate.mtk.so:libneuronusdk_adapter.mtk.so:libtflite_mtk.mtk.so:libarmnn_ndk.mtk.so:libcmdl_ndk.mtk.so:libnir_neon_driver_ndk.mtk.so:libmvpu_runtime.mtk.so:libmvpu_runtime_pub.mtk.so:libmvpu_engine_pub.mtk.so:libmvpu_pattern_pub.mtk.so:libmvpuop_mtk_cv.mtk.so:libmvpuop_mtk_nn.mtk.so:libmvpu_runtime_25.mtk.so:libmvpu_runtime_25_pub.mtk.so:libmvpu_engine_25_pub.mtk.so:libmvpu_pattern_25_pub.mtk.so:libmvpuop25_mtk_cv.mtk.so:libmvpuop25_mtk_nn.mtk.so:libmvpu_config.mtk.so:libteeservice_client.trustonic.so
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D InitVendorPublicLibraries: libOpenCL.so:libnir_neon_driver_ndk.mtk.vndk.so:libcmdl_ndk.mtk.vndk.so:libarmnn_ndk.mtk.vndk.so:libvcap_npu_network.so:libvcap_npu_network_v1.so:libvdnn.so:libc++_shared.so
2024-05-01 01:03:02.261 21064-21064 nativeloader com.example.xyclicker D InitProductPublicLibraries:
2024-05-01 01:03:02.266 21064-21064 UserService com.example.xyclicker I constructor
2024-05-01 01:03:02.266 21064-21064 UserService com.example.xyclicker I constructor with Context: context=android.app.ContextImpl@866fb88
2024-05-01 01:03:02.272 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10394
2024-05-01 01:03:02.273 10738-1859 BinderSender shizuku_server V Uid 10394 already starts
2024-05-01 01:03:02.273 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10394, cached=false
2024-05-01 01:03:02.273 10738-1859 BinderSender shizuku_server V Uid 10394 already starts
2024-05-01 01:03:02.300 10738-1859 UserServiceManager shizuku_server V Received binder for service record 4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841
2024-05-01 01:03:02.300 10738-1859 UserServiceRecord shizuku_server V Binder received for service record 4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841
2024-05-01 01:03:02.301 10738-1859 UserServiceRecord shizuku_server V Broadcast binder received for service record 4f92420b-1e1d-48c0-86e7-48d61b0e53d8-1714496581841
2024-05-01 01:03:02.304 21064-21064 ShizukuServiceStarter com.example.xyclicker I send binder to moe.shizuku.privileged.api in user 0
2024-05-01 01:03:02.304 21064-21078 xxxx com.example.xyclicker I find abs device fd:67 devName:/dev/input/event6
2024-05-01 01:03:02.307 10738-1859 BinderSender shizuku_server D onUidCachedChanged: uid=10394, cached=true

[laiqun]

[laiqun]

实验了很多次，翻了好多次log，fail和pass的流程十分相似，仅仅是sh稍有不同。不知道是不是应用启动流程的影响。
shizuku api demo也能复现问题，做法是应用一启动，立马点击 binduserservice，点的不够快就复现不了问题。

[zhangruiyu]

确实很奇怪 目前oriain os上很多用户反馈失败,然后给shizuku和app都关了再打开才能用

[laiqun]

经过测试，多获取几次provider，就会成功，但是如果失败，进程退出，就需要等10s以上，才能成功。
最多等待50次，每次200ms，最多等待10s，我个人觉得这个时间还可以接受（相对于等待10s来说）。
出问题的地方在这里：
File： "\Shizuku\starter\src\main\java\moe\shizuku\starter\ServiceStarter.java"
private static boolean sendBinder(IBinder binder, String token, boolean retry) {

            for(int i=0;i<50;i++)
            {
                provider = ActivityManagerApis.getContentProviderExternal(name, userId, null, name);
                if (provider == null)
                {
                    Thread.sleep(200);
                    Log.w(TAG, String.format("provider is null %s %d,try times %d", name, userId,i+1));
                }
                else
                    break;
            }

2024-06-22 10:29:30.725 32298-32298 ShizukuServiceStarter rikka.shizuku.demo W provider is null moe.shizuku.privileged.api.shizuku 0,try times 1
2024-06-22 10:29:30.927 32298-32298 ShizukuServiceStarter rikka.shizuku.demo W provider is null moe.shizuku.privileged.api.shizuku 0,try times 2
2024-06-22 10:29:31.128 32298-32298 ShizukuServiceStarter rikka.shizuku.demo W provider is null moe.shizuku.privileged.api.shizuku 0,try times 3
2024-06-22 10:29:31.329 32298-32298 ShizukuServiceStarter rikka.shizuku.demo W provider is null moe.shizuku.privileged.api.shizuku 0,try times 4

提交了一个request, #533
希望Owner考虑一下~ 感谢感谢~

[RikkaW]

@laiqun 直接 Thread.sleep 不是特别的好，个人觉得好一点的做法是用 handler.postDelayed，并且还要加入如果目标进程已经没了就移除掉（removeCallbacks）。