add debug logging to track hook initialization timing

Author: Hepolise

app/src/main/java/ru/hepolise/volumekeytrackcontrol/module/VolumeControlModule.kt

@@ -9,6 +9,7 @@ import de.robv.android.xposed.callbacks.XC_LoadPackage.LoadPackageParam
 import ru.hepolise.volumekeytrackcontrol.module.VolumeKeyControlModuleHandlers.handleConstructPhoneWindowManager
 import ru.hepolise.volumekeytrackcontrol.module.VolumeKeyControlModuleHandlers.handleInterceptKeyBeforeQueueing
 import ru.hepolise.volumekeytrackcontrol.module.util.LogHelper
+import ru.hepolise.volumekeytrackcontrolmodule.BuildConfig
 import java.io.Serializable
 
 @Keep
@@ -57,6 +58,7 @@ class VolumeControlModule : IXposedHookLoadPackage {
         if (lpparam.packageName != "android") {
             return
         }
+        log("ApplicationID: ${BuildConfig.APPLICATION_ID}")
         init(lpparam.classLoader)
     }
 

app/src/main/java/ru/hepolise/volumekeytrackcontrol/module/VolumeKeyControlModuleHandlers.kt

@@ -82,6 +82,7 @@ object VolumeKeyControlModuleHandlers {
             val filter = IntentFilter(Intent.ACTION_USER_UNLOCKED)
             context.registerReceiver(object : BroadcastReceiver() {
                 override fun onReceive(ctx: Context, intent: Intent) {
+                    log("handleConstructPhoneWindowManager: user unlocked")
                     HookNotifier.notifyHooked(context)
                     ctx.unregisterReceiver(this)
                 }

app/src/main/java/ru/hepolise/volumekeytrackcontrol/module/util/HookNotifier.kt

@@ -7,16 +7,20 @@ import ru.hepolise.volumekeytrackcontrolmodule.BuildConfig
 
 object HookNotifier {
 
+    private fun log(text: String) = LogHelper.log(HookNotifier::class.java.simpleName, text)
+
     private val authorityUri = "content://${BuildConfig.APPLICATION_ID}.hookstatusprovider".toUri()
 
     fun notifyHooked(context: Context) {
         runCatching {
+            log("notifyHooked")
             context.contentResolver.call(authorityUri, Constants.SET_HOOKED, null, null)
         }
     }
 
     fun incrementLaunchCount(context: Context) {
         runCatching {
+            log("incrementLaunchCount")
             context.contentResolver.call(authorityUri, Constants.INCREMENT_LAUNCH_COUNT, null, null)
         }
     }

app/src/main/java/ru/hepolise/volumekeytrackcontrol/provider/HookStatusProvider.kt

@@ -6,6 +6,7 @@ import android.content.Context
 import android.database.Cursor
 import android.net.Uri
 import android.os.Bundle
+import android.util.Log
 import androidx.core.content.edit
 import ru.hepolise.volumekeytrackcontrol.util.Constants
 import ru.hepolise.volumekeytrackcontrol.util.SharedPreferencesUtil.HOOK_PREFS_NAME
@@ -21,6 +22,8 @@ class HookStatusProvider : ContentProvider() {
         val context = context ?: return null
         val prefs = context.getSharedPreferences(HOOK_PREFS_NAME, Context.MODE_PRIVATE)
 
+        Log.d(Constants.LOG_TAG, "call: $method")
+
         prefs.edit {
             when (method) {
                 Constants.SET_HOOKED -> {

app/src/main/java/ru/hepolise/volumekeytrackcontrol/util/Constants.kt

@@ -9,4 +9,6 @@ object Constants {
 
     const val SET_HOOKED = "set_hooked"
     const val INCREMENT_LAUNCH_COUNT = "increment_launch_count"
+
+    const val LOG_TAG = "LSPosed-Bridge"
 }

app/src/main/java/ru/hepolise/volumekeytrackcontrol/util/SharedPreferencesUtil.kt

@@ -3,6 +3,7 @@ package ru.hepolise.volumekeytrackcontrol.util
 import android.content.SharedPreferences
 import android.os.Build
 import android.os.SystemClock
+import android.util.Log
 import android.view.ViewConfiguration
 import de.robv.android.xposed.XSharedPreferences
 import ru.hepolise.volumekeytrackcontrolmodule.BuildConfig
@@ -79,7 +80,12 @@ object SharedPreferencesUtil {
         getLong(
             LAST_INIT_HOOK_TIME,
             0L
-        ) >= (System.currentTimeMillis() - SystemClock.elapsedRealtime())
+        ).also {
+            Log.d(
+                Constants.LOG_TAG,
+                "Last init hook time: $it, comparing to ${System.currentTimeMillis() - SystemClock.elapsedRealtime()}"
+            )
+        } >= (System.currentTimeMillis() - SystemClock.elapsedRealtime())
 
     fun SharedPreferences.getLaunchedCount(): Int =
         this.getInt(LAUNCHED_COUNT, LAUNCHED_COUNT_DEFAULT_VALUE)