diff --git a/lib/src/binder_ext_call.c b/lib/src/binder_ext_call.c
index 32d8937..afdaf4b 100644
--- a/lib/src/binder_ext_call.c
+++ b/lib/src/binder_ext_call.c
@@ -14,6 +14,9 @@
  */
 
 #include "binder_ext_call_impl.h"
+#include <gutil_log.h>
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "ext:"fmt, ##__VA_ARGS__)
 
 G_DEFINE_INTERFACE(BinderExtCall, binder_ext_call, G_TYPE_OBJECT)
 #define GET_IFACE(obj) BINDER_EXT_CALL_GET_IFACE(obj)
@@ -26,6 +29,7 @@ BinderExtCall*
 binder_ext_call_ref(
     BinderExtCall* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_ref(self);
     }
@@ -36,6 +40,7 @@ void
 binder_ext_call_unref(
     BinderExtCall* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_unref(self);
     }
@@ -45,6 +50,7 @@ BINDER_EXT_CALL_INTERFACE_FLAGS
 binder_ext_call_get_interface_flags(
     BinderExtCall* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(self) ? GET_IFACE(self)->flags :
         BINDER_EXT_CALL_INTERFACE_NO_FLAGS;
 }
@@ -53,6 +59,7 @@ const BinderExtCallInfo* const*
 binder_ext_call_get_calls(
     BinderExtCall* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     static const BinderExtCallInfo* none = NULL;
 
     if (G_LIKELY(self)) {
@@ -82,6 +89,7 @@ binder_ext_call_dial(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(number)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -103,6 +111,7 @@ binder_ext_call_deflect(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(number)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -122,6 +131,7 @@ binder_ext_call_answer(
     GDestroyNotify destroy,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -141,6 +151,7 @@ binder_ext_call_swap(
     GDestroyNotify destroy,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -160,6 +171,7 @@ binder_ext_call_transfer(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -178,6 +190,7 @@ binder_ext_call_conference(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -196,6 +209,7 @@ binder_ext_call_send_dtmf(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(tones)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -216,6 +230,7 @@ binder_ext_call_hangup(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -232,6 +247,7 @@ binder_ext_call_cancel(
     BinderExtCall* self,
     guint id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -247,6 +263,7 @@ binder_ext_call_add_calls_changed_handler(
     BinderExtCallFunc handler,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -263,6 +280,7 @@ binder_ext_call_add_disconnect_handler(
     BinderExtCallDisconnectFunc handler,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -279,6 +297,7 @@ binder_ext_call_add_ring_handler(
     BinderExtCallFunc handler,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -295,6 +314,7 @@ binder_ext_call_add_ssn_handler(
     BinderExtCallSuppSvcNotifyFunc handler,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
 
@@ -310,6 +330,7 @@ binder_ext_call_remove_handler(
     BinderExtCall* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         /*
          * Since we provide the default callback, we can safely assume
@@ -325,6 +346,7 @@ binder_ext_call_remove_handlers(
     gulong* ids,
     guint count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(ids) && G_LIKELY(count)) {
         BinderExtCallInterface* iface = GET_IFACE(self);
         int i;
@@ -352,6 +374,7 @@ binder_ext_call_default_remove_handler(
     BinderExtCall* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (id) {
         g_signal_handler_disconnect(self, id);
     }
@@ -362,6 +385,7 @@ void
 binder_ext_call_default_init(
     BinderExtCallInterface* iface)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /*
      * Assume the smallest interface version. Implementation must overwrite
      * iface->version with BINDER_EXT_CALL_INTERFACE_VERSION known to it at
diff --git a/lib/src/binder_ext_ims.c b/lib/src/binder_ext_ims.c
index 7b6014f..c84bea2 100644
--- a/lib/src/binder_ext_ims.c
+++ b/lib/src/binder_ext_ims.c
@@ -14,6 +14,9 @@
  */
 
 #include "binder_ext_ims_impl.h"
+#include <gutil_log.h>
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "ext:"fmt, ##__VA_ARGS__)
 
 G_DEFINE_INTERFACE(BinderExtIms, binder_ext_ims, G_TYPE_OBJECT)
 #define GET_IFACE(obj) BINDER_EXT_IMS_GET_IFACE(obj)
@@ -26,6 +29,7 @@ BinderExtIms*
 binder_ext_ims_ref(
     BinderExtIms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_ref(self);
     }
@@ -36,6 +40,7 @@ void
 binder_ext_ims_unref(
     BinderExtIms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_unref(self);
     }
@@ -45,6 +50,7 @@ BINDER_EXT_IMS_INTERFACE_FLAGS
 binder_ext_ims_get_interface_flags(
     BinderExtIms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(self) ? GET_IFACE(self)->flags :
         BINDER_EXT_IMS_INTERFACE_NO_FLAGS;
 }
@@ -53,6 +59,7 @@ BINDER_EXT_IMS_STATE
 binder_ext_ims_get_state(
     BinderExtIms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtImsInterface* iface = GET_IFACE(self);
 
@@ -71,6 +78,7 @@ binder_ext_ims_set_registration(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtImsInterface* iface = GET_IFACE(self);
 
@@ -87,6 +95,7 @@ binder_ext_ims_cancel(
     BinderExtIms* self,
     guint id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         BinderExtImsInterface* iface = GET_IFACE(self);
 
@@ -102,6 +111,7 @@ binder_ext_ims_add_state_handler(
     BinderExtImsFunc handler,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(handler)) {
         BinderExtImsInterface* iface = GET_IFACE(self);
 
@@ -117,6 +127,7 @@ binder_ext_ims_remove_handler(
     BinderExtIms* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         /*
          * Since we provide the default callback, we can safely assume
@@ -132,6 +143,7 @@ binder_ext_ims_remove_handlers(
     gulong* ids,
     guint count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(ids) && G_LIKELY(count)) {
         BinderExtImsInterface* iface = GET_IFACE(self);
         int i;
@@ -159,6 +171,7 @@ binder_ext_ims_default_remove_handler(
     BinderExtIms* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (id) {
         g_signal_handler_disconnect(self, id);
     }
@@ -169,6 +182,7 @@ void
 binder_ext_ims_default_init(
     BinderExtImsInterface* iface)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /*
      * Assume the smallest interface version. Implementation must overwrite
      * iface->version with BINDER_EXT_IMS_INTERFACE_VERSION known to it at
diff --git a/lib/src/binder_ext_plugin.c b/lib/src/binder_ext_plugin.c
index 0aaeebe..a176ba1 100644
--- a/lib/src/binder_ext_plugin.c
+++ b/lib/src/binder_ext_plugin.c
@@ -14,6 +14,9 @@
  */
 
 #include "binder_ext_plugin_impl.h"
+#include <gutil_log.h>
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "ext:"fmt, ##__VA_ARGS__)
 
 #define THIS(obj) BINDER_EXT_PLUGIN(obj)
 #define THIS_TYPE BINDER_EXT_TYPE_PLUGIN
@@ -36,6 +39,7 @@ binder_ext_slot_new(
     RadioInstance* radio,
     GHashTable* params)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /* Well, this is kind of BinderExtSlot API but let it be here. */
     return plugin ? GET_CLASS(plugin)->new_slot(plugin, radio, params) : NULL;
 }
@@ -44,6 +48,7 @@ BinderExtPlugin*
 binder_ext_plugin_ref(
     BinderExtPlugin* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_ref(THIS(self));
     }
@@ -54,6 +59,7 @@ void
 binder_ext_plugin_unref(
     BinderExtPlugin* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_unref(THIS(self));
     }
@@ -63,6 +69,7 @@ const char*
 binder_ext_plugin_name(
     BinderExtPlugin* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(self) ? GET_CLASS(self)->plugin_name : NULL;
 }
 
@@ -70,6 +77,7 @@ BinderExtPlugin*
 binder_ext_plugin_get(
     const char* name)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return (binder_ext_plugin_table && G_LIKELY(name)) ?
         g_hash_table_lookup(binder_ext_plugin_table, name) :
         NULL;
@@ -79,6 +87,7 @@ void
 binder_ext_plugin_register(
     BinderExtPlugin* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const char* name = binder_ext_plugin_name(self);
 
     if (name) {
@@ -95,6 +104,7 @@ void
 binder_ext_plugin_unregister(
     const char* name)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (binder_ext_plugin_table) {
         g_hash_table_remove(binder_ext_plugin_table, name);
         if (!g_hash_table_size(binder_ext_plugin_table)) {
@@ -113,6 +123,7 @@ void
 binder_ext_plugin_init(
     BinderExtPlugin* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
 }
 
 static
@@ -120,6 +131,7 @@ void
 binder_ext_plugin_class_init(
     BinderExtPluginClass* klass)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
 }
 
 /*
diff --git a/lib/src/binder_ext_slot.c b/lib/src/binder_ext_slot.c
index 649dfbf..5957c4b 100644
--- a/lib/src/binder_ext_slot.c
+++ b/lib/src/binder_ext_slot.c
@@ -16,6 +16,9 @@
 #define GLIB_DISABLE_DEPRECATION_WARNINGS
 
 #include "binder_ext_slot_impl.h"
+#include <gutil_log.h>
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "ext:"fmt, ##__VA_ARGS__)
 
 struct binder_ext_slot_priv {
     gboolean dropped;
@@ -37,6 +40,7 @@ BinderExtSlot*
 binder_ext_slot_ref(
     BinderExtSlot* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_ref(self);
     }
@@ -47,6 +51,7 @@ void
 binder_ext_slot_unref(
     BinderExtSlot* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_unref(self);
     }
@@ -56,6 +61,7 @@ void
 binder_ext_slot_drop(
     BinderExtSlot* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtSlotPriv* priv = self->priv;
 
@@ -72,6 +78,7 @@ binder_ext_slot_get_interface(
     BinderExtSlot* self,
     GType type)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(self) ? GET_CLASS(self)->get_interface(self, type) : NULL;
 }
 
@@ -85,6 +92,7 @@ binder_ext_slot_default_get_interface(
     BinderExtSlot* self,
     GType iface)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return NULL;
 }
 
@@ -93,6 +101,7 @@ void
 binder_ext_slot_nop(
     BinderExtSlot* slot)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
 }
 
 static
@@ -100,6 +109,7 @@ void
 binder_ext_slot_init(
     BinderExtSlot* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     self->priv = G_TYPE_INSTANCE_GET_PRIVATE(self, THIS_TYPE,
         BinderExtSlotPriv);
 }
@@ -109,6 +119,7 @@ void
 binder_ext_slot_class_init(
     BinderExtSlotClass* klass)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_type_class_add_private(klass, sizeof(BinderExtSlotPriv));
     klass->get_interface = binder_ext_slot_default_get_interface;
     klass->shutdown = binder_ext_slot_nop;
diff --git a/lib/src/binder_ext_sms.c b/lib/src/binder_ext_sms.c
index 0dd97db..2d16c0c 100644
--- a/lib/src/binder_ext_sms.c
+++ b/lib/src/binder_ext_sms.c
@@ -14,6 +14,9 @@
  */
 
 #include "binder_ext_sms_impl.h"
+#include <gutil_log.h>
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "ext:"fmt, ##__VA_ARGS__)
 
 G_DEFINE_INTERFACE(BinderExtSms, binder_ext_sms, G_TYPE_OBJECT)
 #define GET_IFACE(obj) BINDER_EXT_SMS_GET_IFACE(obj)
@@ -26,6 +29,7 @@ BinderExtSms*
 binder_ext_sms_ref(
     BinderExtSms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_ref(self);
     }
@@ -36,6 +40,7 @@ void
 binder_ext_sms_unref(
     BinderExtSms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         g_object_unref(self);
     }
@@ -45,6 +50,7 @@ BINDER_EXT_SMS_INTERFACE_FLAGS
 binder_ext_sms_get_interface_flags(
     BinderExtSms* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(self) ? GET_IFACE(self)->flags :
         BINDER_EXT_SMS_INTERFACE_NO_FLAGS;
 }
@@ -61,6 +67,7 @@ binder_ext_sms_send(
     GDestroyNotify destroy,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -77,6 +84,7 @@ binder_ext_sms_cancel(
     BinderExtSms* self,
     guint id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -92,6 +100,7 @@ binder_ext_sms_add_report_handler(
     BinderExtSmsReportFunc cb,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(cb)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -108,6 +117,7 @@ binder_ext_sms_add_incoming_handler(
     BinderExtSmsIncomingFunc cb,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(cb)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -124,6 +134,7 @@ binder_ext_sms_ack_report(
     guint msg_ref,
     gboolean ok)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -138,6 +149,7 @@ binder_ext_sms_ack_incoming(
     BinderExtSms* self,
     gboolean ok)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
 
@@ -152,6 +164,7 @@ binder_ext_sms_remove_handler(
     BinderExtSms* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(id)) {
         /*
          * Since we provide the default callback, we can safely assume
@@ -167,6 +180,7 @@ binder_ext_sms_remove_handlers(
     gulong* ids,
     guint count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(self) && G_LIKELY(ids) && G_LIKELY(count)) {
         BinderExtSmsInterface* iface = GET_IFACE(self);
         int i;
@@ -194,6 +208,7 @@ binder_ext_sms_default_remove_handler(
     BinderExtSms* self,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (id) {
         g_signal_handler_disconnect(self, id);
     }
@@ -204,6 +219,7 @@ void
 binder_ext_sms_default_init(
     BinderExtSmsInterface* iface)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /*
      * Assume the smallest interface version. Implementation must overwrite
      * iface->version with BINDER_EXT_SMS_INTERFACE_VERSION known to it at
diff --git a/src/binder_base.c b/src/binder_base.c
index 48f6336..b721139 100644
--- a/src/binder_base.c
+++ b/src/binder_base.c
@@ -53,6 +53,7 @@ GQuark
 binder_base_property_quark(
     guint property)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     GASSERT(property < BINDER_BASE_MAX_PROPERTIES);
     /* For ANY property this function is expected to return zero */
     if (property > 0 && G_LIKELY(property < BINDER_BASE_MAX_PROPERTIES)) {
@@ -77,6 +78,7 @@ binder_base_property_changed(
     guint property,
     BinderBaseClosure* closure)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const BinderBaseClass* klass = GET_CLASS(self);
 
     closure->callback(((guint8*)self) + klass->public_offset, property,
@@ -94,6 +96,7 @@ binder_base_add_property_handler(
     GCallback callback,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(callback)) {
         /*
          * We can't directly connect the provided callback because
@@ -122,6 +125,7 @@ binder_base_queue_property_change(
     BinderBase* self,
     guint property)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     self->queued_signals |= BINDER_BASE_PROPERTY_BIT(property);
 }
 
@@ -130,6 +134,7 @@ binder_base_emit_property_change(
     BinderBase* self,
     guint property)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     binder_base_queue_property_change(self, property);
     binder_base_emit_queued_signals(self);
 }
@@ -138,6 +143,7 @@ void
 binder_base_emit_queued_signals(
     BinderBase* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     guint p;
 
     /* Signal handlers may release references to this object */
@@ -165,6 +171,7 @@ void
 binder_base_init(
     BinderBase* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
 }
 
 static
@@ -172,6 +179,7 @@ void
 binder_base_class_init(
     BinderBaseClass* klass)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /* By default assume that public part immediately follows BinderBase */
     klass->public_offset = sizeof(BinderBase);
     binder_base_signals[SIGNAL_PROPERTY_CHANGED] =
diff --git a/src/binder_call_barring.c b/src/binder_call_barring.c
index d6a15cc..0678479 100644
--- a/src/binder_call_barring.c
+++ b/src/binder_call_barring.c
@@ -64,6 +64,7 @@ binder_call_barring_callback_data_new(
     BinderCallback cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarringCbData* cbd = g_slice_new0(BinderCallBarringCbData);
 
     cbd->self = self;
@@ -77,6 +78,7 @@ void
 binder_call_barring_callback_data_free(
     gpointer cbd)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_slice_free(BinderCallBarringCbData, cbd);
 }
 
@@ -86,6 +88,7 @@ binder_call_barring_query_ok(
     const BinderCallBarringCbData* cbd,
     const GBinderReader* args)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     GBinderReader reader;
     gint32 response;
 
@@ -117,6 +120,7 @@ binder_call_barring_query_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallBarringCbData* cbd = user_data;
 
@@ -148,6 +152,7 @@ binder_call_barring_query(
     ofono_call_barring_query_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarring* self = ofono_call_barring_get_data(b);
 
     /*
@@ -192,6 +197,7 @@ binder_call_barring_set_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallBarringCbData* cbd = user_data;
     ofono_call_barring_set_cb_t cb = cbd->cb.set;
@@ -230,6 +236,7 @@ binder_call_barring_set(
     ofono_call_barring_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarring* self = ofono_call_barring_get_data(b);
 
     /*
@@ -277,6 +284,7 @@ binder_call_barring_set_passwd_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallBarringCbData* cbd = user_data;
     ofono_call_barring_set_cb_t cb = cbd->cb.set;
@@ -311,6 +319,7 @@ binder_call_barring_set_passwd(
     ofono_call_barring_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarring* self = ofono_call_barring_get_data(b);
     const RADIO_AIDL_INTERFACE iface_aidl =
         radio_client_aidl_interface(self->network_client);
@@ -350,6 +359,7 @@ gboolean
 binder_call_barring_register(
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarring* self = user_data;
 
     GASSERT(self->register_id);
@@ -365,6 +375,7 @@ binder_call_barring_probe(
     unsigned int vendor,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderModem* modem = binder_modem_get_data(data);
     BinderCallBarring* self = g_new0(struct binder_call_barring, 1);
 
@@ -386,6 +397,7 @@ void
 binder_call_barring_remove(
     struct ofono_call_barring* b)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallBarring* self = binder_call_barring_get_data(b);
 
     DBG_(self, "");
@@ -418,12 +430,14 @@ static const struct ofono_call_barring_driver binder_call_barring_driver = {
 void
 binder_call_barring_init()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_barring_driver_register(&binder_call_barring_driver);
 }
 
 void
 binder_call_barring_cleanup()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_barring_driver_unregister(&binder_call_barring_driver);
 }
 
diff --git a/src/binder_call_forwarding.c b/src/binder_call_forwarding.c
index 855e3f7..1f33643 100644
--- a/src/binder_call_forwarding.c
+++ b/src/binder_call_forwarding.c
@@ -62,6 +62,7 @@ binder_call_forwarding_callback_data_new(
     BinderCallback cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwardingCbData* cbd = g_slice_new0(BinderCallForwardingCbData);
 
     cbd->self = self;
@@ -75,6 +76,7 @@ void
 binder_call_forwarding_callback_data_free(
     gpointer cbd)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_slice_free(BinderCallForwardingCbData, cbd);
 }
 
@@ -92,6 +94,7 @@ binder_call_forwarding_call(
     BinderCallback cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /*
      * getCallForwardStatus(int32_t serial, CallForwardInfo callInfo);
      * setCallForward(int32_t serial, CallForwardInfo callInfo);
@@ -158,6 +161,7 @@ binder_call_forwarding_set_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallForwardingCbData* cbd = user_data;
     ofono_call_forwarding_set_cb_t cb = cbd->cb.set;
@@ -192,6 +196,7 @@ binder_call_forwarding_set(
     ofono_call_forwarding_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_SET_CALL_FORWARD :
         RADIO_REQ_SET_CALL_FORWARD;
@@ -211,6 +216,7 @@ binder_call_forwarding_registration(
     ofono_call_forwarding_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
 
     DBG_(self, "%d", type);
@@ -227,6 +233,7 @@ binder_call_forwarding_erasure(
     ofono_call_forwarding_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
 
     DBG_(self, "%d", type);
@@ -243,6 +250,7 @@ binder_call_forwarding_deactivate(
     ofono_call_forwarding_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
 
     DBG_(self, "%d", type);
@@ -259,6 +267,7 @@ binder_call_forwarding_activate(
     ofono_call_forwarding_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
 
     DBG_(self, "%d", type);
@@ -272,6 +281,7 @@ binder_call_forwarding_query_ok(
     const BinderCallForwardingCbData* cbd,
     const GBinderReader* args)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const RadioCallForwardInfo* infos;
     struct ofono_call_forwarding_condition* list = NULL;
@@ -312,6 +322,7 @@ binder_call_forwarding_query_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallForwardingCbData* cbd = user_data;
 
@@ -342,6 +353,7 @@ binder_call_forwarding_query(
     ofono_call_forwarding_query_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_GET_CALL_FORWARD_STATUS :
@@ -365,6 +377,7 @@ gboolean
 binder_call_forwarding_register(
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = user_data;
 
     GASSERT(self->register_id);
@@ -380,6 +393,7 @@ binder_call_forwarding_probe(
     unsigned int vendor,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderModem* modem = binder_modem_get_data(data);
     BinderCallForwarding* self = g_new0(BinderCallForwarding, 1);
 
@@ -399,6 +413,7 @@ void
 binder_call_forwarding_remove(
     struct ofono_call_forwarding* f)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallForwarding* self = binder_call_forwarding_get_data(f);
 
     DBG_(self, "");
@@ -432,12 +447,14 @@ binder_call_forwarding_driver = {
 void
 binder_call_forwarding_init()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_forwarding_driver_register(&binder_call_forwarding_driver);
 }
 
 void
 binder_call_forwarding_cleanup()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_forwarding_driver_unregister(&binder_call_forwarding_driver);
 }
 
diff --git a/src/binder_call_settings.c b/src/binder_call_settings.c
index 4011ba0..8462d2d 100644
--- a/src/binder_call_settings.c
+++ b/src/binder_call_settings.c
@@ -61,6 +61,7 @@ binder_call_settings_callback_data_new(
     BinderCallback cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettingsCbData* cbd = g_slice_new0(BinderCallSettingsCbData);
 
     cbd->self = self;
@@ -74,6 +75,7 @@ void
 binder_call_settings_callback_data_free(
     gpointer cbd)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_slice_free(BinderCallSettingsCbData, cbd);
 }
 
@@ -86,6 +88,7 @@ binder_call_settings_call(
     BinderCallback cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     RadioRequest* req = radio_request_new2(self->g, code, NULL, complete,
         binder_call_settings_callback_data_free,
         binder_call_settings_callback_data_new(self, cb, data));
@@ -104,6 +107,7 @@ binder_call_settings_set_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallSettingsCbData* cbd = user_data;
     ofono_call_settings_set_cb_t cb = cbd->cb.set;
@@ -124,6 +128,7 @@ binder_call_settings_cw_set(
     ofono_call_settings_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
 
     /*
@@ -160,6 +165,7 @@ binder_call_settings_cw_query_ok(
     const BinderCallSettingsCbData* cbd,
     const GBinderReader* args)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     ofono_call_settings_status_cb_t cb = cbd->cb.status;
     GBinderReader reader;
@@ -196,6 +202,7 @@ binder_call_settings_cw_query_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallSettingsCbData* cbd = user_data;
 
@@ -224,6 +231,7 @@ void binder_call_settings_cw_query(
     ofono_call_settings_status_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
 
     /*
@@ -259,6 +267,7 @@ binder_call_settings_clip_query_ok(
     const BinderCallSettingsCbData* cbd,
     const GBinderReader* args)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     GBinderReader reader;
     gint32 status;
@@ -282,6 +291,7 @@ binder_call_settings_clip_query_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallSettingsCbData* cbd = user_data;
 
@@ -310,6 +320,7 @@ binder_call_settings_clip_query(
     ofono_call_settings_status_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_GET_CLIP : RADIO_REQ_GET_CLIP;
@@ -326,6 +337,7 @@ binder_call_settings_clir_ok(
     const BinderCallSettingsCbData* cbd,
     const GBinderReader* args)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     GBinderReader reader;
     gint32 n, m;
@@ -351,6 +363,7 @@ binder_call_settings_clir_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallSettingsCbData* cbd = user_data;
 
@@ -379,6 +392,7 @@ binder_call_settings_clir_query(
     ofono_call_settings_clir_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_GET_CLIR : RADIO_REQ_GET_CLIR;
@@ -397,6 +411,7 @@ binder_call_settings_clir_set(
     ofono_call_settings_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_SET_CLIR : RADIO_REQ_SET_CLIR;
@@ -421,6 +436,7 @@ gboolean
 binder_call_settings_register(
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = user_data;
 
     DBG_(self, "");
@@ -437,6 +453,7 @@ binder_call_settings_probe(
     unsigned int vendor,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderModem* modem = binder_modem_get_data(data);
     BinderCallSettings* self = g_new0(BinderCallSettings, 1);
 
@@ -456,6 +473,7 @@ void
 binder_call_settings_remove(
     struct ofono_call_settings* s)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallSettings* self = binder_call_settings_get_data(s);
 
     DBG_(self, "");
@@ -488,12 +506,14 @@ static const struct ofono_call_settings_driver binder_call_settings_driver = {
 void
 binder_call_settings_init()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_settings_driver_register(&binder_call_settings_driver);
 }
 
 void
 binder_call_settings_cleanup()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_settings_driver_unregister(&binder_call_settings_driver);
 }
 
diff --git a/src/binder_call_volume.c b/src/binder_call_volume.c
index 0f54637..0cb6144 100644
--- a/src/binder_call_volume.c
+++ b/src/binder_call_volume.c
@@ -56,6 +56,7 @@ binder_call_volume_callback_data_new(
     ofono_call_volume_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallVolumeCbData* cbd = g_slice_new0(BinderCallVolumeCbData);
 
     cbd->self = self;
@@ -69,6 +70,7 @@ void
 binder_call_volume_callback_data_free(
     gpointer cbd)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_slice_free(BinderCallVolumeCbData, cbd);
 }
 
@@ -82,6 +84,7 @@ binder_call_volume_mute_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     const BinderCallVolumeCbData* cbd = user_data;
     ofono_call_volume_cb_t cb = cbd->cb;
@@ -111,6 +114,7 @@ binder_call_volume_mute(
     ofono_call_volume_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallVolume* self = binder_call_volume_get_data(v);
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_SET_MUTE : RADIO_REQ_SET_MUTE;
@@ -140,6 +144,7 @@ binder_call_volume_query_mute_cb(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const BinderCallVolume* self = user_data;
     if (status == RADIO_TX_STATUS_OK) {
         guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
@@ -171,6 +176,7 @@ gboolean
 binder_call_volume_register(
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallVolume* self = user_data;
     guint32 code = self->interface_aidl == RADIO_VOICE_INTERFACE ?
         RADIO_VOICE_REQ_GET_MUTE : RADIO_REQ_GET_MUTE;
@@ -194,6 +200,7 @@ binder_call_volume_probe(
     unsigned int vendor,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderModem* modem = binder_modem_get_data(data);
     BinderCallVolume* self = g_new0(BinderCallVolume, 1);
 
@@ -213,6 +220,7 @@ void
 binder_call_volume_remove(
     struct ofono_call_volume* v)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCallVolume* self = binder_call_volume_get_data(v);
 
     DBG_(self, "");
@@ -241,12 +249,14 @@ static const struct ofono_call_volume_driver binder_call_volume_driver = {
 void
 binder_call_volume_init()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_volume_driver_register(&binder_call_volume_driver);
 }
 
 void
 binder_call_volume_cleanup()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_call_volume_driver_unregister(&binder_call_volume_driver);
 }
 
diff --git a/src/binder_cbs.c b/src/binder_cbs.c
index 3957a63..a007332 100644
--- a/src/binder_cbs.c
+++ b/src/binder_cbs.c
@@ -62,6 +62,7 @@ binder_cbs_callback_data_new(
     ofono_cbs_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbsCbData* cbd = g_slice_new0(BinderCbsCbData);
 
     cbd->self = self;
@@ -75,6 +76,7 @@ void
 binder_cbs_callback_data_free(
     gpointer cbd)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_slice_free(BinderCbsCbData, cbd);
 }
 
@@ -88,6 +90,7 @@ binder_cbs_retry(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return error == RADIO_ERROR_INVALID_STATE;
 }
 
@@ -101,6 +104,7 @@ binder_cbs_activate_cb(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_error err;
     BinderCbsCbData* cbd = user_data;
     guint32 code = cbd->self->interface_aidl == RADIO_MESSAGING_INTERFACE ?
@@ -132,6 +136,7 @@ binder_cbs_activate(
     ofono_cbs_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /* setGsmBroadcastActivation(int32_t serial, bool activate); */
     GBinderWriter writer;
     guint32 code = self->interface_aidl == RADIO_MESSAGING_INTERFACE ?
@@ -161,6 +166,7 @@ binder_cbs_set_config_cb(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbsCbData* cbd = user_data;
     struct ofono_error err;
 
@@ -191,6 +197,7 @@ binder_cbs_set_config(
     ofono_cbs_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     GBinderWriter writer;
     guint32 code = self->interface_aidl == RADIO_MESSAGING_INTERFACE ?
         RADIO_MESSAGING_REQ_SET_GSM_BROADCAST_CONFIG :
@@ -287,6 +294,7 @@ binder_cbs_set_topics(
     ofono_cbs_set_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbs* self = binder_cbs_get_data(cbs);
 
     DBG_(self, "%s", topics);
@@ -300,6 +308,7 @@ binder_cbs_clear_topics(
     ofono_cbs_set_cb_t cb,
     void *data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbs* self = binder_cbs_get_data(cbs);
 
     DBG_(self, "");
@@ -314,6 +323,7 @@ binder_cbs_notify(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbs* self = user_data;
     GBinderReader reader;
     const guchar* ptr;
@@ -356,6 +366,7 @@ gboolean
 binder_cbs_register(
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbs* self = user_data;
     RadioClient* client = self->g->client;
     guint32 code = self->interface_aidl == RADIO_MESSAGING_INTERFACE ?
@@ -378,6 +389,7 @@ binder_cbs_probe(
     unsigned int vendor,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderModem* modem = binder_modem_get_data(data);
     BinderCbs* self = g_new0(BinderCbs, 1);
 
@@ -397,6 +409,7 @@ void
 binder_cbs_remove(
     struct ofono_cbs* cbs)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCbs* self = binder_cbs_get_data(cbs);
 
     DBG_(self, "");
@@ -427,12 +440,14 @@ static const struct ofono_cbs_driver binder_cbs_driver = {
 void
 binder_cbs_init()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_cbs_driver_register(&binder_cbs_driver);
 }
 
 void
 binder_cbs_cleanup()
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ofono_cbs_driver_unregister(&binder_cbs_driver);
 }
 
diff --git a/src/binder_cell_info.c b/src/binder_cell_info.c
index d7fe724..83d9a10 100644
--- a/src/binder_cell_info.c
+++ b/src/binder_cell_info.c
@@ -95,6 +95,7 @@ binder_cell_info_int_format(
     int value,
     const char* format)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (value == OFONO_CELL_INVALID_VALUE) {
         return "";
     } else {
@@ -112,6 +113,7 @@ binder_cell_info_int64_format(
     guint64 value,
     const char* format)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (value == OFONO_CELL_INVALID_VALUE_INT64) {
         return "";
     } else {
@@ -129,6 +131,7 @@ binder_cell_info_list_compare(
     gconstpointer a,
     gconstpointer b)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return ofono_cell_compare_location(*(struct ofono_cell**)a,
         *(struct ofono_cell**)b);
 }
@@ -139,6 +142,7 @@ binder_cell_info_list_equal(
     const ofono_cell_ptr* l1,
     const ofono_cell_ptr* l2)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (l1 && l2) {
         while (*l1 && *l2) {
             if (memcmp(*l1, *l2, sizeof(struct ofono_cell))) {
@@ -158,6 +162,7 @@ void
 binder_cell_info_clear(
     BinderCellInfo* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (self->cells && self->cells[0]) {
         gutil_ptrv_free((void**)self->cells);
         self->info.cells = self->cells = g_new0(struct ofono_cell*, 1);
@@ -172,6 +177,7 @@ binder_cell_info_update_cells(
     BinderCellInfo* self,
     GPtrArray* l)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (l) {
         g_ptr_array_sort(l, binder_cell_info_list_compare);
         g_ptr_array_add(l, NULL);
@@ -197,6 +203,7 @@ binder_cell_info_invalidate(
     void* info,
     gsize size)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const int n = size/sizeof(int);
     int* value = info;
     int i;
@@ -211,6 +218,7 @@ void
 binder_cell_info_invalidate_nr(
     struct ofono_cell_info_nr* nr)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     nr->mcc = OFONO_CELL_INVALID_VALUE;
     nr->mnc = OFONO_CELL_INVALID_VALUE;
     nr->nci = OFONO_CELL_INVALID_VALUE_INT64;
@@ -232,6 +240,7 @@ binder_cell_info_new_cell_gsm(
     const RadioCellIdentityGsm* id,
     const RadioSignalStrengthGsm* ss)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_gsm* gsm = &cell->info.gsm;
 
@@ -269,6 +278,7 @@ binder_cell_info_new_cell_wcdma(
     const RadioCellIdentityWcdma* id,
     const RadioSignalStrengthWcdma* ss)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_wcdma* wcdma = &cell->info.wcdma;
 
@@ -302,6 +312,7 @@ binder_cell_info_new_cell_lte(
     const RadioCellIdentityLte* id,
     const RadioSignalStrengthLte* ss)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_lte* lte = &cell->info.lte;
 
@@ -342,6 +353,7 @@ binder_cell_info_new_cell_nr(
     const RadioCellIdentityNr* id,
     const RadioSignalStrengthNr* ss)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_nr* nr = &cell->info.nr;
 
@@ -382,6 +394,7 @@ binder_cell_info_new_cell_gsm_aidl(
     gboolean registered,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_gsm* gsm = &cell->info.gsm;
     gsize data_read;
@@ -436,6 +449,7 @@ binder_cell_info_new_cell_wcdma_aidl(
     gboolean registered,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_wcdma* wcdma = &cell->info.wcdma;
     gsize data_read;
@@ -489,6 +503,7 @@ binder_cell_info_new_cell_lte_aidl(
     gboolean registered,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_lte* lte = &cell->info.lte;
     gsize data_read;
@@ -548,6 +563,7 @@ binder_cell_info_new_cell_nr_aidl(
     gboolean registered,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     struct ofono_cell* cell = binder_cell_new();
     struct ofono_cell_info_nr* nr = &cell->info.nr;
     gsize data_read;
@@ -613,6 +629,7 @@ binder_cell_info_array_new_1_0(
     const RadioCellInfo* cells,
     gsize count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize i;
     GPtrArray* l = g_ptr_array_sized_new(count + 1);
 
@@ -664,6 +681,7 @@ binder_cell_info_array_new_1_2(
     const RadioCellInfo_1_2* cells,
     gsize count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize i;
     GPtrArray* l = g_ptr_array_sized_new(count + 1);
 
@@ -715,6 +733,7 @@ binder_cell_info_array_new_1_4(
     const RadioCellInfo_1_4* cells,
     gsize count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize i;
     GPtrArray* l = g_ptr_array_sized_new(count + 1);
 
@@ -758,6 +777,7 @@ binder_cell_info_array_new_1_5(
     const RadioCellInfo_1_5* cells,
     gsize count)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize i;
     GPtrArray* l = g_ptr_array_sized_new(count + 1);
 
@@ -800,6 +820,7 @@ GPtrArray*
 binder_cell_info_array_new_aidl(
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize i;
     gint32 count = 0;
     GPtrArray* l;
@@ -853,6 +874,7 @@ binder_cell_info_list_1_0(
     BinderCellInfo* self,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize count;
     const RadioCellInfo* cells = gbinder_reader_read_hidl_type_vec(reader,
         RadioCellInfo, &count);
@@ -871,6 +893,7 @@ binder_cell_info_list_1_2(
     BinderCellInfo* self,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize count;
     const RadioCellInfo_1_2* cells = gbinder_reader_read_hidl_type_vec(reader,
         RadioCellInfo_1_2, &count);
@@ -889,6 +912,7 @@ binder_cell_info_list_1_4(
     BinderCellInfo* self,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize count;
     const RadioCellInfo_1_4* cells = gbinder_reader_read_hidl_type_vec(reader,
         RadioCellInfo_1_4, &count);
@@ -907,6 +931,7 @@ binder_cell_info_list_1_5(
     BinderCellInfo* self,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     gsize count;
     const RadioCellInfo_1_5* cells = gbinder_reader_read_hidl_type_vec(reader,
         RadioCellInfo_1_5, &count);
@@ -925,6 +950,7 @@ binder_cell_info_list_aidl(
     BinderCellInfo* self,
     GBinderReader* reader)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     binder_cell_info_update_cells(self,
         binder_cell_info_array_new_aidl(reader));
 }
@@ -937,6 +963,7 @@ binder_cell_info_list_changed_1_0(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT(code == RADIO_IND_CELL_INFO_LIST);
@@ -956,6 +983,7 @@ binder_cell_info_list_changed_1_2(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT(code == RADIO_IND_CELL_INFO_LIST_1_2);
@@ -975,6 +1003,7 @@ binder_cell_info_list_changed_1_4(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT(code == RADIO_IND_CELL_INFO_LIST_1_4);
@@ -994,6 +1023,7 @@ binder_cell_info_list_changed_1_5(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT(code == RADIO_IND_CELL_INFO_LIST_1_5);
@@ -1013,6 +1043,7 @@ binder_cell_info_list_changed_aidl(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT((RADIO_NETWORK_IND)code == RADIO_NETWORK_IND_CELL_INFO_LIST);
@@ -1034,6 +1065,7 @@ binder_cell_info_list_cb(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     GASSERT(self->query_req == req);
@@ -1085,6 +1117,7 @@ binder_cell_info_set_rate_cb(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
     const RADIO_AIDL_INTERFACE iface_aidl = radio_client_aidl_interface(self->client);
     guint32 code = iface_aidl == RADIO_NETWORK_INTERFACE ?
@@ -1117,6 +1150,7 @@ binder_cell_info_retry(
     const GBinderReader* args,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     switch (error) {
@@ -1133,6 +1167,7 @@ void
 binder_cell_info_query(
     BinderCellInfo* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const RADIO_AIDL_INTERFACE iface_aidl = radio_client_aidl_interface(self->client);
     guint32 code = iface_aidl == RADIO_NETWORK_INTERFACE ?
         RADIO_NETWORK_REQ_GET_CELL_INFO_LIST :
@@ -1152,6 +1187,7 @@ void
 binder_cell_info_set_rate(
     BinderCellInfo* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     GBinderWriter writer;
     const RADIO_AIDL_INTERFACE iface_aidl = radio_client_aidl_interface(self->client);
     guint32 code = iface_aidl == RADIO_NETWORK_INTERFACE ?
@@ -1177,6 +1213,7 @@ void
 binder_cell_info_refresh(
     BinderCellInfo* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     /* getCellInfoList fails without SIM card */
     if (self->enabled &&
         self->radio->state == RADIO_STATE_ON &&
@@ -1194,6 +1231,7 @@ binder_cell_info_radio_state_cb(
     BINDER_RADIO_PROPERTY property,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     DBG_(self, "%s", binder_radio_state_string(radio->state));
@@ -1206,6 +1244,7 @@ binder_cell_info_sim_status_cb(
     BinderSimCard* sim,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(user_data);
 
     self->sim_card_ready = binder_sim_card_ready(sim);
@@ -1234,6 +1273,7 @@ void
 binder_cell_info_ref_proc(
     struct ofono_cell_info* info)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_object_ref(binder_cell_info_cast(info));
 }
 
@@ -1242,6 +1282,7 @@ void
 binder_cell_info_unref_proc(
     struct ofono_cell_info* info)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     g_object_unref(binder_cell_info_cast(info));
 }
 
@@ -1251,6 +1292,7 @@ binder_cell_info_cells_changed_cb(
     BinderCellInfo* self,
     BinderCellInfoClosure* closure)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     closure->cb(&self->info, closure->user_data);
 }
 
@@ -1261,6 +1303,7 @@ binder_cell_info_add_cells_changed_handler_proc(
     ofono_cell_info_cb_t cb,
     void* user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (cb) {
         BinderCellInfoClosure* closure = (BinderCellInfoClosure *)
             g_closure_new_simple(sizeof(BinderCellInfoClosure), NULL);
@@ -1284,6 +1327,7 @@ binder_cell_info_remove_handler_proc(
     struct ofono_cell_info* info,
     gulong id)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (G_LIKELY(id)) {
         g_signal_handler_disconnect(binder_cell_info_cast(info), id);
     }
@@ -1295,6 +1339,7 @@ binder_cell_info_set_update_interval_proc(
     struct ofono_cell_info* info,
     int ms)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = binder_cell_info_cast(info);
 
     if (self->update_rate_ms != ms) {
@@ -1312,6 +1357,7 @@ binder_cell_info_set_enabled_proc(
     struct ofono_cell_info* info,
     gboolean enabled)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = binder_cell_info_cast(info);
 
     if (self->enabled != enabled) {
@@ -1336,6 +1382,7 @@ binder_cell_info_new(
     BinderRadio* radio,
     BinderSimCard* sim)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = g_object_new(THIS_TYPE, 0);
 
     self->instance = radio_instance_ref(instance);
@@ -1397,6 +1444,7 @@ void
 binder_cell_info_init(
     BinderCellInfo* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     static const struct ofono_cell_info_proc binder_cell_info_proc = {
         binder_cell_info_ref_proc,
         binder_cell_info_unref_proc,
@@ -1416,6 +1464,7 @@ void
 binder_cell_info_finalize(
     GObject* object)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderCellInfo* self = THIS(object);
 
     DBG_(self, "");
@@ -1438,6 +1487,7 @@ void
 binder_cell_info_class_init(
     BinderCellInfoClass* klass)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     G_OBJECT_CLASS(klass)->finalize = binder_cell_info_finalize;
     binder_cell_info_signals[SIGNAL_CELLS_CHANGED] =
         g_signal_new(SIGNAL_CELLS_CHANGED_NAME, G_OBJECT_CLASS_TYPE(klass),
diff --git a/src/binder_connman.c b/src/binder_connman.c
index 44570db..bd66500 100644
--- a/src/binder_connman.c
+++ b/src/binder_connman.c
@@ -85,6 +85,7 @@ ConnManObject*
 connman_object_cast(
     BinderConnman* connman)
 {
+DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     return G_LIKELY(connman) ?
         THIS(G_CAST(connman, ConnManObject, pub)) :
         NULL;
@@ -95,6 +96,7 @@ const char*
 connman_iter_get_string(
     DBusMessageIter* it)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     const char* str = NULL;
 
     dbus_message_iter_get_basic(it, &str);
@@ -106,6 +108,7 @@ void
 connman_object_emit_pending_signals(
     ConnManObject* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderBase* base = &self->base;
     BinderConnman* connman = &self->pub;
     gsize late_signals = 0;
@@ -144,6 +147,7 @@ void
 connman_cancel_call(
     ConnManObject* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (self->call) {
         dbus_pending_call_cancel(self->call);
         dbus_pending_call_unref(self->call);
@@ -157,6 +161,7 @@ connman_tech_new(
     ConnManObject* self,
     const char* path)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     ConnManTech* tech = g_new0(ConnManTech, 1);
     char* key = g_strdup(path);
 
@@ -171,6 +176,7 @@ void
 connman_invalidate(
     ConnManObject* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderConnman* connman = &self->pub;
 
     if (connman->valid) {
@@ -185,6 +191,7 @@ void
 connman_update_valid(
     ConnManObject* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderConnman* connman = &self->pub;
     const gboolean valid = (connman->present && !self->call);
 
@@ -200,6 +207,7 @@ gboolean
 connman_update_tethering(
     ConnManObject* self)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderConnman* connman = &self->pub;
     gboolean tethering = FALSE;
     GHashTableIter it;
@@ -231,6 +239,7 @@ connman_set_tech_tethering(
     ConnManTech* tech,
     gboolean tethering)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (tech->tethering != tethering) {
         ConnManObject* self = tech->obj;
 
@@ -260,6 +269,7 @@ connman_set_tech_connected(
     ConnManTech* tech,
     gboolean connected)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     if (tech->connected != connected) {
         ConnManObject* self = tech->obj;
 
@@ -283,6 +293,7 @@ connman_tech_set_property(
     ConnManTech* tech,
     DBusMessageIter* it)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     DBusMessageIter var;
     DBusBasicValue value;
     const char* key = connman_iter_get_string(it);
diff --git a/src/binder_log.h b/src/binder_log.h
index 5a98386..2312b2e 100644
--- a/src/binder_log.h
+++ b/src/binder_log.h
@@ -20,6 +20,10 @@
 #include <gutil_log.h>
 #include <ofono/log.h>
 
+#undef DBG
+#define DBG(fmt, ...) \
+    gutil_log(GLOG_MODULE_CURRENT, GLOG_LEVEL_ALWAYS, "binder_plugin:"fmt, ##__VA_ARGS__)
+
 #endif /* BINDER_LOG_H */
 
 /*
diff --git a/src/binder_sim.c b/src/binder_sim.c
index 3a2fc15..9041a81 100644
--- a/src/binder_sim.c
+++ b/src/binder_sim.c
@@ -2000,6 +2000,7 @@ binder_sim_query_facility_lock_cb(
     const GBinderReader* args,
     gpointer user_data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderSimCbdIo* cbd = user_data;
     ofono_query_facility_lock_cb_t cb = cbd->cb.query_facility_lock;
     struct ofono_error err;
@@ -2053,6 +2054,7 @@ binder_sim_query_facility_lock(
     ofono_query_facility_lock_cb_t cb,
     void* data)
 {
+    DBG("func:%s,line:%d,file:%s", __func__, __LINE__, __FILE__);
     BinderSim* self = binder_sim_get_data(sim);
     const char* fac = binder_sim_facility_code(type);
     BinderSimCbdIo* cbd = binder_sim_cbd_io_new(self, BINDER_CB(cb), data);