From 1b7b7a9275af7cc9687aaf8b73015fe2d696394b Mon Sep 17 00:00:00 2001 From: Ralph Boehme Date: Fri, 30 Jun 2017 19:37:03 +0200 Subject: examples: add winbindd.stp and a shell script to generate it MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 8bit Usage: Instrument all winbindd processes: # stap winbindd.stp Instrument a specific winbindd process: # stap -x PID winbindd.stp Example output: # stap winbindd.stp Collecting data, press ctrl-C to stop... ^C Winbind request service time ============================ winbindd_getpwnam_send count: 99, sum: 6229 ms (min: 2669 us, avg: 62921 us, max: 157907 us) Winbind request runtime ======================= winbindd_getpwnam_send count: 99, sum: 3 ms (min: 21 us, avg: 36 us, max: 77 us) Winbind domain-child request service time ========================================= _wbint_LookupName count: 99, sum: 1403 ms (min: 619 us, avg: 14181 us, max: 136613 us) _wbint_GetNssInfo count: 99, sum: 0 ms (min: 2 us, avg: 3 us, max: 6 us) _wbint_LookupSid count: 102, sum: 49 ms (min: 13 us, avg: 481 us, max: 6315 us) _wbint_Sids2UnixIDs count: 101, sum: 2 ms (min: 18 us, avg: 29 us, max: 49 us) _wbint_LookupSids count: 101, sum: 84 ms (min: 411 us, avg: 838 us, max: 3524 us) Winbind domain-child AD-backend service time ============================================ sid_to_name count: 56, sum: 45 ms (min: 431 us, avg: 816 us, max: 6275 us) sequence_number count: 12, sum: 1209 ms (min: 46618 us, avg: 100803 us, max: 131439 us) name_to_sid count: 99, sum: 176 ms (min: 547 us, avg: 1781 us, max: 9866 us) ... Regenerate winbindd.stp: $ examples/systemtap/generate-winbindd.stp.sh Signed-off-by: Ralph Boehme Reviewed-by: Ralph Boehme Autobuild-User(master): Ralph Böhme Autobuild-Date(master): Wed Jul 19 16:20:56 CEST 2017 on sn-devel-144 --- examples/systemtap/winbindd.stp | 2859 +++++++++++++++++++++++++++++++++++++++ 1 file changed, 2859 insertions(+) create mode 100644 examples/systemtap/winbindd.stp (limited to 'examples/systemtap/winbindd.stp') diff --git a/examples/systemtap/winbindd.stp b/examples/systemtap/winbindd.stp new file mode 100644 index 00000000000..0769312fd2b --- /dev/null +++ b/examples/systemtap/winbindd.stp @@ -0,0 +1,2859 @@ +#!/usr/bin/stap +# +# Systemtap script to instrument winbindd +# +# Generated by examples/systemtap/generate-winbindd.stp.sh on Sat Jul 15 18:49:52 CEST 2017, do not edit +# +# Usage: +# +# Instrument all winbindd processes: +# # stap winbindd.stp +# +# Instrument a specific winbindd process: +# # stap -x PID winbindd.stp +# + +global dc_running, dc_svctime +global backend_running, backend_svctime +global send_running, recv_running +global start_time, idle_time +global async_svctime, async_runtime + +probe begin { + printf("Collecting data, press ctrl-C to stop... ") +} + +# +# winbind domain child function winbindd_dual_ping +# + +probe process("winbindd").function("winbindd_dual_ping") { + dc_running[tid(), "winbindd_dual_ping"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_ping").return { + if (!([tid(), "winbindd_dual_ping"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_ping"] + delete dc_running[tid(), "winbindd_dual_ping"] + + duration = end - begin + dc_svctime["winbindd_dual_ping"] <<< duration +} + +# +# winbind domain child function winbindd_dual_list_trusted_domains +# + +probe process("winbindd").function("winbindd_dual_list_trusted_domains") { + dc_running[tid(), "winbindd_dual_list_trusted_domains"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_list_trusted_domains").return { + if (!([tid(), "winbindd_dual_list_trusted_domains"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_list_trusted_domains"] + delete dc_running[tid(), "winbindd_dual_list_trusted_domains"] + + duration = end - begin + dc_svctime["winbindd_dual_list_trusted_domains"] <<< duration +} + +# +# winbind domain child function winbindd_dual_init_connection +# + +probe process("winbindd").function("winbindd_dual_init_connection") { + dc_running[tid(), "winbindd_dual_init_connection"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_init_connection").return { + if (!([tid(), "winbindd_dual_init_connection"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_init_connection"] + delete dc_running[tid(), "winbindd_dual_init_connection"] + + duration = end - begin + dc_svctime["winbindd_dual_init_connection"] <<< duration +} + +# +# winbind domain child function winbindd_dual_pam_auth +# + +probe process("winbindd").function("winbindd_dual_pam_auth") { + dc_running[tid(), "winbindd_dual_pam_auth"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_pam_auth").return { + if (!([tid(), "winbindd_dual_pam_auth"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_pam_auth"] + delete dc_running[tid(), "winbindd_dual_pam_auth"] + + duration = end - begin + dc_svctime["winbindd_dual_pam_auth"] <<< duration +} + +# +# winbind domain child function winbindd_dual_pam_auth_crap +# + +probe process("winbindd").function("winbindd_dual_pam_auth_crap") { + dc_running[tid(), "winbindd_dual_pam_auth_crap"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_pam_auth_crap").return { + if (!([tid(), "winbindd_dual_pam_auth_crap"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_pam_auth_crap"] + delete dc_running[tid(), "winbindd_dual_pam_auth_crap"] + + duration = end - begin + dc_svctime["winbindd_dual_pam_auth_crap"] <<< duration +} + +# +# winbind domain child function winbindd_dual_pam_logoff +# + +probe process("winbindd").function("winbindd_dual_pam_logoff") { + dc_running[tid(), "winbindd_dual_pam_logoff"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_pam_logoff").return { + if (!([tid(), "winbindd_dual_pam_logoff"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_pam_logoff"] + delete dc_running[tid(), "winbindd_dual_pam_logoff"] + + duration = end - begin + dc_svctime["winbindd_dual_pam_logoff"] <<< duration +} + +# +# winbind domain child function winbindd_dual_pam_chng_pswd_auth_crap +# + +probe process("winbindd").function("winbindd_dual_pam_chng_pswd_auth_crap") { + dc_running[tid(), "winbindd_dual_pam_chng_pswd_auth_crap"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_pam_chng_pswd_auth_crap").return { + if (!([tid(), "winbindd_dual_pam_chng_pswd_auth_crap"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_pam_chng_pswd_auth_crap"] + delete dc_running[tid(), "winbindd_dual_pam_chng_pswd_auth_crap"] + + duration = end - begin + dc_svctime["winbindd_dual_pam_chng_pswd_auth_crap"] <<< duration +} + +# +# winbind domain child function winbindd_dual_pam_chauthtok +# + +probe process("winbindd").function("winbindd_dual_pam_chauthtok") { + dc_running[tid(), "winbindd_dual_pam_chauthtok"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dual_pam_chauthtok").return { + if (!([tid(), "winbindd_dual_pam_chauthtok"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "winbindd_dual_pam_chauthtok"] + delete dc_running[tid(), "winbindd_dual_pam_chauthtok"] + + duration = end - begin + dc_svctime["winbindd_dual_pam_chauthtok"] <<< duration +} + +# +# winbind domain child function _wbint_LookupSid +# + +probe process("winbindd").function("_wbint_LookupSid") { + dc_running[tid(), "_wbint_LookupSid"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupSid").return { + if (!([tid(), "_wbint_LookupSid"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupSid"] + delete dc_running[tid(), "_wbint_LookupSid"] + + duration = end - begin + dc_svctime["_wbint_LookupSid"] <<< duration +} + +# +# winbind domain child function _wbint_LookupSids +# + +probe process("winbindd").function("_wbint_LookupSids") { + dc_running[tid(), "_wbint_LookupSids"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupSids").return { + if (!([tid(), "_wbint_LookupSids"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupSids"] + delete dc_running[tid(), "_wbint_LookupSids"] + + duration = end - begin + dc_svctime["_wbint_LookupSids"] <<< duration +} + +# +# winbind domain child function _wbint_LookupName +# + +probe process("winbindd").function("_wbint_LookupName") { + dc_running[tid(), "_wbint_LookupName"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupName").return { + if (!([tid(), "_wbint_LookupName"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupName"] + delete dc_running[tid(), "_wbint_LookupName"] + + duration = end - begin + dc_svctime["_wbint_LookupName"] <<< duration +} + +# +# winbind domain child function _wbint_Sids2UnixIDs +# + +probe process("winbindd").function("_wbint_Sids2UnixIDs") { + dc_running[tid(), "_wbint_Sids2UnixIDs"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_Sids2UnixIDs").return { + if (!([tid(), "_wbint_Sids2UnixIDs"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_Sids2UnixIDs"] + delete dc_running[tid(), "_wbint_Sids2UnixIDs"] + + duration = end - begin + dc_svctime["_wbint_Sids2UnixIDs"] <<< duration +} + +# +# winbind domain child function _wbint_UnixIDs2Sids +# + +probe process("winbindd").function("_wbint_UnixIDs2Sids") { + dc_running[tid(), "_wbint_UnixIDs2Sids"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_UnixIDs2Sids").return { + if (!([tid(), "_wbint_UnixIDs2Sids"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_UnixIDs2Sids"] + delete dc_running[tid(), "_wbint_UnixIDs2Sids"] + + duration = end - begin + dc_svctime["_wbint_UnixIDs2Sids"] <<< duration +} + +# +# winbind domain child function _wbint_AllocateUid +# + +probe process("winbindd").function("_wbint_AllocateUid") { + dc_running[tid(), "_wbint_AllocateUid"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_AllocateUid").return { + if (!([tid(), "_wbint_AllocateUid"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_AllocateUid"] + delete dc_running[tid(), "_wbint_AllocateUid"] + + duration = end - begin + dc_svctime["_wbint_AllocateUid"] <<< duration +} + +# +# winbind domain child function _wbint_AllocateGid +# + +probe process("winbindd").function("_wbint_AllocateGid") { + dc_running[tid(), "_wbint_AllocateGid"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_AllocateGid").return { + if (!([tid(), "_wbint_AllocateGid"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_AllocateGid"] + delete dc_running[tid(), "_wbint_AllocateGid"] + + duration = end - begin + dc_svctime["_wbint_AllocateGid"] <<< duration +} + +# +# winbind domain child function _wbint_GetNssInfo +# + +probe process("winbindd").function("_wbint_GetNssInfo") { + dc_running[tid(), "_wbint_GetNssInfo"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_GetNssInfo").return { + if (!([tid(), "_wbint_GetNssInfo"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_GetNssInfo"] + delete dc_running[tid(), "_wbint_GetNssInfo"] + + duration = end - begin + dc_svctime["_wbint_GetNssInfo"] <<< duration +} + +# +# winbind domain child function _wbint_LookupUserAliases +# + +probe process("winbindd").function("_wbint_LookupUserAliases") { + dc_running[tid(), "_wbint_LookupUserAliases"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupUserAliases").return { + if (!([tid(), "_wbint_LookupUserAliases"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupUserAliases"] + delete dc_running[tid(), "_wbint_LookupUserAliases"] + + duration = end - begin + dc_svctime["_wbint_LookupUserAliases"] <<< duration +} + +# +# winbind domain child function _wbint_LookupUserGroups +# + +probe process("winbindd").function("_wbint_LookupUserGroups") { + dc_running[tid(), "_wbint_LookupUserGroups"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupUserGroups").return { + if (!([tid(), "_wbint_LookupUserGroups"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupUserGroups"] + delete dc_running[tid(), "_wbint_LookupUserGroups"] + + duration = end - begin + dc_svctime["_wbint_LookupUserGroups"] <<< duration +} + +# +# winbind domain child function _wbint_QuerySequenceNumber +# + +probe process("winbindd").function("_wbint_QuerySequenceNumber") { + dc_running[tid(), "_wbint_QuerySequenceNumber"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_QuerySequenceNumber").return { + if (!([tid(), "_wbint_QuerySequenceNumber"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_QuerySequenceNumber"] + delete dc_running[tid(), "_wbint_QuerySequenceNumber"] + + duration = end - begin + dc_svctime["_wbint_QuerySequenceNumber"] <<< duration +} + +# +# winbind domain child function _wbint_LookupGroupMembers +# + +probe process("winbindd").function("_wbint_LookupGroupMembers") { + dc_running[tid(), "_wbint_LookupGroupMembers"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupGroupMembers").return { + if (!([tid(), "_wbint_LookupGroupMembers"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupGroupMembers"] + delete dc_running[tid(), "_wbint_LookupGroupMembers"] + + duration = end - begin + dc_svctime["_wbint_LookupGroupMembers"] <<< duration +} + +# +# winbind domain child function _wbint_QueryGroupList +# + +probe process("winbindd").function("_wbint_QueryGroupList") { + dc_running[tid(), "_wbint_QueryGroupList"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_QueryGroupList").return { + if (!([tid(), "_wbint_QueryGroupList"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_QueryGroupList"] + delete dc_running[tid(), "_wbint_QueryGroupList"] + + duration = end - begin + dc_svctime["_wbint_QueryGroupList"] <<< duration +} + +# +# winbind domain child function _wbint_QueryUserRidList +# + +probe process("winbindd").function("_wbint_QueryUserRidList") { + dc_running[tid(), "_wbint_QueryUserRidList"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_QueryUserRidList").return { + if (!([tid(), "_wbint_QueryUserRidList"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_QueryUserRidList"] + delete dc_running[tid(), "_wbint_QueryUserRidList"] + + duration = end - begin + dc_svctime["_wbint_QueryUserRidList"] <<< duration +} + +# +# winbind domain child function _wbint_DsGetDcName +# + +probe process("winbindd").function("_wbint_DsGetDcName") { + dc_running[tid(), "_wbint_DsGetDcName"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_DsGetDcName").return { + if (!([tid(), "_wbint_DsGetDcName"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_DsGetDcName"] + delete dc_running[tid(), "_wbint_DsGetDcName"] + + duration = end - begin + dc_svctime["_wbint_DsGetDcName"] <<< duration +} + +# +# winbind domain child function _wbint_LookupRids +# + +probe process("winbindd").function("_wbint_LookupRids") { + dc_running[tid(), "_wbint_LookupRids"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_LookupRids").return { + if (!([tid(), "_wbint_LookupRids"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_LookupRids"] + delete dc_running[tid(), "_wbint_LookupRids"] + + duration = end - begin + dc_svctime["_wbint_LookupRids"] <<< duration +} + +# +# winbind domain child function _wbint_CheckMachineAccount +# + +probe process("winbindd").function("_wbint_CheckMachineAccount") { + dc_running[tid(), "_wbint_CheckMachineAccount"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_CheckMachineAccount").return { + if (!([tid(), "_wbint_CheckMachineAccount"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_CheckMachineAccount"] + delete dc_running[tid(), "_wbint_CheckMachineAccount"] + + duration = end - begin + dc_svctime["_wbint_CheckMachineAccount"] <<< duration +} + +# +# winbind domain child function _wbint_ChangeMachineAccount +# + +probe process("winbindd").function("_wbint_ChangeMachineAccount") { + dc_running[tid(), "_wbint_ChangeMachineAccount"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_ChangeMachineAccount").return { + if (!([tid(), "_wbint_ChangeMachineAccount"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_ChangeMachineAccount"] + delete dc_running[tid(), "_wbint_ChangeMachineAccount"] + + duration = end - begin + dc_svctime["_wbint_ChangeMachineAccount"] <<< duration +} + +# +# winbind domain child function _wbint_PingDc +# + +probe process("winbindd").function("_wbint_PingDc") { + dc_running[tid(), "_wbint_PingDc"] = gettimeofday_us() +} + +probe process("winbindd").function("_wbint_PingDc").return { + if (!([tid(), "_wbint_PingDc"] in dc_running)) + next + + end = gettimeofday_us() + begin = dc_running[tid(), "_wbint_PingDc"] + delete dc_running[tid(), "_wbint_PingDc"] + + duration = end - begin + dc_svctime["_wbint_PingDc"] <<< duration +} + +# +# winbind domain child backend function query_user_list +# + +probe process("winbindd").function("query_user_list@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "query_user_list"] = gettimeofday_us() +} + +probe process("winbindd").function("query_user_list@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "query_user_list"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "query_user_list"] + delete backend_running[tid(), "query_user_list"] + + duration = end - begin + backend_svctime["query_user_list"] <<< duration +} + +# +# winbind domain child backend function enum_dom_groups +# + +probe process("winbindd").function("enum_dom_groups@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "enum_dom_groups"] = gettimeofday_us() +} + +probe process("winbindd").function("enum_dom_groups@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "enum_dom_groups"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "enum_dom_groups"] + delete backend_running[tid(), "enum_dom_groups"] + + duration = end - begin + backend_svctime["enum_dom_groups"] <<< duration +} + +# +# winbind domain child backend function enum_local_groups +# + +probe process("winbindd").function("enum_local_groups@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "enum_local_groups"] = gettimeofday_us() +} + +probe process("winbindd").function("enum_local_groups@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "enum_local_groups"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "enum_local_groups"] + delete backend_running[tid(), "enum_local_groups"] + + duration = end - begin + backend_svctime["enum_local_groups"] <<< duration +} + +# +# winbind domain child backend function name_to_sid +# + +probe process("winbindd").function("name_to_sid@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "name_to_sid"] = gettimeofday_us() +} + +probe process("winbindd").function("name_to_sid@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "name_to_sid"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "name_to_sid"] + delete backend_running[tid(), "name_to_sid"] + + duration = end - begin + backend_svctime["name_to_sid"] <<< duration +} + +# +# winbind domain child backend function sid_to_name +# + +probe process("winbindd").function("sid_to_name@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "sid_to_name"] = gettimeofday_us() +} + +probe process("winbindd").function("sid_to_name@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "sid_to_name"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "sid_to_name"] + delete backend_running[tid(), "sid_to_name"] + + duration = end - begin + backend_svctime["sid_to_name"] <<< duration +} + +# +# winbind domain child backend function rids_to_names +# + +probe process("winbindd").function("rids_to_names@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "rids_to_names"] = gettimeofday_us() +} + +probe process("winbindd").function("rids_to_names@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "rids_to_names"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "rids_to_names"] + delete backend_running[tid(), "rids_to_names"] + + duration = end - begin + backend_svctime["rids_to_names"] <<< duration +} + +# +# winbind domain child backend function lookup_usergroups +# + +probe process("winbindd").function("lookup_usergroups@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "lookup_usergroups"] = gettimeofday_us() +} + +probe process("winbindd").function("lookup_usergroups@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "lookup_usergroups"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "lookup_usergroups"] + delete backend_running[tid(), "lookup_usergroups"] + + duration = end - begin + backend_svctime["lookup_usergroups"] <<< duration +} + +# +# winbind domain child backend function lookup_useraliases +# + +probe process("winbindd").function("lookup_useraliases@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "lookup_useraliases"] = gettimeofday_us() +} + +probe process("winbindd").function("lookup_useraliases@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "lookup_useraliases"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "lookup_useraliases"] + delete backend_running[tid(), "lookup_useraliases"] + + duration = end - begin + backend_svctime["lookup_useraliases"] <<< duration +} + +# +# winbind domain child backend function lookup_groupmem +# + +probe process("winbindd").function("lookup_groupmem@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "lookup_groupmem"] = gettimeofday_us() +} + +probe process("winbindd").function("lookup_groupmem@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "lookup_groupmem"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "lookup_groupmem"] + delete backend_running[tid(), "lookup_groupmem"] + + duration = end - begin + backend_svctime["lookup_groupmem"] <<< duration +} + +# +# winbind domain child backend function sequence_number +# + +probe process("winbindd").function("sequence_number@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "sequence_number"] = gettimeofday_us() +} + +probe process("winbindd").function("sequence_number@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "sequence_number"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "sequence_number"] + delete backend_running[tid(), "sequence_number"] + + duration = end - begin + backend_svctime["sequence_number"] <<< duration +} + +# +# winbind domain child backend function lockout_policy +# + +probe process("winbindd").function("lockout_policy@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "lockout_policy"] = gettimeofday_us() +} + +probe process("winbindd").function("lockout_policy@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "lockout_policy"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "lockout_policy"] + delete backend_running[tid(), "lockout_policy"] + + duration = end - begin + backend_svctime["lockout_policy"] <<< duration +} + +# +# winbind domain child backend function password_policy +# + +probe process("winbindd").function("password_policy@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "password_policy"] = gettimeofday_us() +} + +probe process("winbindd").function("password_policy@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "password_policy"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "password_policy"] + delete backend_running[tid(), "password_policy"] + + duration = end - begin + backend_svctime["password_policy"] <<< duration +} + +# +# winbind domain child backend function trusted_domains +# + +probe process("winbindd").function("trusted_domains@../source3/winbindd/winbindd_ads.c") { + backend_running[tid(), "trusted_domains"] = gettimeofday_us() +} + +probe process("winbindd").function("trusted_domains@../source3/winbindd/winbindd_ads.c").return { + if (!([tid(), "trusted_domains"] in backend_running)) + next + + end = gettimeofday_us() + begin = backend_running[tid(), "trusted_domains"] + delete backend_running[tid(), "trusted_domains"] + + duration = end - begin + backend_svctime["trusted_domains"] <<< duration +} + +# +# winbind async function wb_ping +# + +probe process("winbindd").function("wb_ping_send") { + send_running["wb_ping_send"] = gettimeofday_us() +} + +probe process("winbindd").function("wb_ping_send").return { + if (!(["wb_ping_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["wb_ping_send"] + delete send_running["wb_ping_send"] + + start_time["wb_ping_send", $return] = start + idle_time["wb_ping_send", $return] = end +} + +probe process("winbindd").function("wb_ping_recv") { + if (!(["wb_ping_send", $req] in start_time)) + next + + recv_running["wb_ping_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("wb_ping_recv").return { + if (!(["wb_ping_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["wb_ping_recv"] + delete recv_running["wb_ping_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["wb_ping_send", req] + delete start_time["wb_ping_send", req] + svctime = recv_end - send_begin + + idle = idle_time["wb_ping_send", req] + delete idle_time["wb_ping_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["wb_ping_send"] <<< svctime + async_runtime["wb_ping_send"] <<< runtime +} + +# +# winbind async function winbindd_lookupsid +# + +probe process("winbindd").function("winbindd_lookupsid_send") { + send_running["winbindd_lookupsid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupsid_send").return { + if (!(["winbindd_lookupsid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_lookupsid_send"] + delete send_running["winbindd_lookupsid_send"] + + start_time["winbindd_lookupsid_send", $return] = start + idle_time["winbindd_lookupsid_send", $return] = end +} + +probe process("winbindd").function("winbindd_lookupsid_recv") { + if (!(["winbindd_lookupsid_send", $req] in start_time)) + next + + recv_running["winbindd_lookupsid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupsid_recv").return { + if (!(["winbindd_lookupsid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_lookupsid_recv"] + delete recv_running["winbindd_lookupsid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_lookupsid_send", req] + delete start_time["winbindd_lookupsid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_lookupsid_send", req] + delete idle_time["winbindd_lookupsid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_lookupsid_send"] <<< svctime + async_runtime["winbindd_lookupsid_send"] <<< runtime +} + +# +# winbind async function winbindd_lookupsids +# + +probe process("winbindd").function("winbindd_lookupsids_send") { + send_running["winbindd_lookupsids_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupsids_send").return { + if (!(["winbindd_lookupsids_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_lookupsids_send"] + delete send_running["winbindd_lookupsids_send"] + + start_time["winbindd_lookupsids_send", $return] = start + idle_time["winbindd_lookupsids_send", $return] = end +} + +probe process("winbindd").function("winbindd_lookupsids_recv") { + if (!(["winbindd_lookupsids_send", $req] in start_time)) + next + + recv_running["winbindd_lookupsids_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupsids_recv").return { + if (!(["winbindd_lookupsids_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_lookupsids_recv"] + delete recv_running["winbindd_lookupsids_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_lookupsids_send", req] + delete start_time["winbindd_lookupsids_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_lookupsids_send", req] + delete idle_time["winbindd_lookupsids_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_lookupsids_send"] <<< svctime + async_runtime["winbindd_lookupsids_send"] <<< runtime +} + +# +# winbind async function winbindd_lookupname +# + +probe process("winbindd").function("winbindd_lookupname_send") { + send_running["winbindd_lookupname_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupname_send").return { + if (!(["winbindd_lookupname_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_lookupname_send"] + delete send_running["winbindd_lookupname_send"] + + start_time["winbindd_lookupname_send", $return] = start + idle_time["winbindd_lookupname_send", $return] = end +} + +probe process("winbindd").function("winbindd_lookupname_recv") { + if (!(["winbindd_lookupname_send", $req] in start_time)) + next + + recv_running["winbindd_lookupname_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookupname_recv").return { + if (!(["winbindd_lookupname_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_lookupname_recv"] + delete recv_running["winbindd_lookupname_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_lookupname_send", req] + delete start_time["winbindd_lookupname_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_lookupname_send", req] + delete idle_time["winbindd_lookupname_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_lookupname_send"] <<< svctime + async_runtime["winbindd_lookupname_send"] <<< runtime +} + +# +# winbind async function winbindd_sids_to_xids +# + +probe process("winbindd").function("winbindd_sids_to_xids_send") { + send_running["winbindd_sids_to_xids_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_sids_to_xids_send").return { + if (!(["winbindd_sids_to_xids_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_sids_to_xids_send"] + delete send_running["winbindd_sids_to_xids_send"] + + start_time["winbindd_sids_to_xids_send", $return] = start + idle_time["winbindd_sids_to_xids_send", $return] = end +} + +probe process("winbindd").function("winbindd_sids_to_xids_recv") { + if (!(["winbindd_sids_to_xids_send", $req] in start_time)) + next + + recv_running["winbindd_sids_to_xids_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_sids_to_xids_recv").return { + if (!(["winbindd_sids_to_xids_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_sids_to_xids_recv"] + delete recv_running["winbindd_sids_to_xids_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_sids_to_xids_send", req] + delete start_time["winbindd_sids_to_xids_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_sids_to_xids_send", req] + delete idle_time["winbindd_sids_to_xids_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_sids_to_xids_send"] <<< svctime + async_runtime["winbindd_sids_to_xids_send"] <<< runtime +} + +# +# winbind async function winbindd_xids_to_sids +# + +probe process("winbindd").function("winbindd_xids_to_sids_send") { + send_running["winbindd_xids_to_sids_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_xids_to_sids_send").return { + if (!(["winbindd_xids_to_sids_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_xids_to_sids_send"] + delete send_running["winbindd_xids_to_sids_send"] + + start_time["winbindd_xids_to_sids_send", $return] = start + idle_time["winbindd_xids_to_sids_send", $return] = end +} + +probe process("winbindd").function("winbindd_xids_to_sids_recv") { + if (!(["winbindd_xids_to_sids_send", $req] in start_time)) + next + + recv_running["winbindd_xids_to_sids_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_xids_to_sids_recv").return { + if (!(["winbindd_xids_to_sids_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_xids_to_sids_recv"] + delete recv_running["winbindd_xids_to_sids_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_xids_to_sids_send", req] + delete start_time["winbindd_xids_to_sids_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_xids_to_sids_send", req] + delete idle_time["winbindd_xids_to_sids_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_xids_to_sids_send"] <<< svctime + async_runtime["winbindd_xids_to_sids_send"] <<< runtime +} + +# +# winbind async function winbindd_getpwsid +# + +probe process("winbindd").function("winbindd_getpwsid_send") { + send_running["winbindd_getpwsid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwsid_send").return { + if (!(["winbindd_getpwsid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getpwsid_send"] + delete send_running["winbindd_getpwsid_send"] + + start_time["winbindd_getpwsid_send", $return] = start + idle_time["winbindd_getpwsid_send", $return] = end +} + +probe process("winbindd").function("winbindd_getpwsid_recv") { + if (!(["winbindd_getpwsid_send", $req] in start_time)) + next + + recv_running["winbindd_getpwsid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwsid_recv").return { + if (!(["winbindd_getpwsid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getpwsid_recv"] + delete recv_running["winbindd_getpwsid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getpwsid_send", req] + delete start_time["winbindd_getpwsid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getpwsid_send", req] + delete idle_time["winbindd_getpwsid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getpwsid_send"] <<< svctime + async_runtime["winbindd_getpwsid_send"] <<< runtime +} + +# +# winbind async function winbindd_getpwnam +# + +probe process("winbindd").function("winbindd_getpwnam_send") { + send_running["winbindd_getpwnam_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwnam_send").return { + if (!(["winbindd_getpwnam_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getpwnam_send"] + delete send_running["winbindd_getpwnam_send"] + + start_time["winbindd_getpwnam_send", $return] = start + idle_time["winbindd_getpwnam_send", $return] = end +} + +probe process("winbindd").function("winbindd_getpwnam_recv") { + if (!(["winbindd_getpwnam_send", $req] in start_time)) + next + + recv_running["winbindd_getpwnam_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwnam_recv").return { + if (!(["winbindd_getpwnam_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getpwnam_recv"] + delete recv_running["winbindd_getpwnam_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getpwnam_send", req] + delete start_time["winbindd_getpwnam_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getpwnam_send", req] + delete idle_time["winbindd_getpwnam_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getpwnam_send"] <<< svctime + async_runtime["winbindd_getpwnam_send"] <<< runtime +} + +# +# winbind async function winbindd_getpwuid +# + +probe process("winbindd").function("winbindd_getpwuid_send") { + send_running["winbindd_getpwuid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwuid_send").return { + if (!(["winbindd_getpwuid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getpwuid_send"] + delete send_running["winbindd_getpwuid_send"] + + start_time["winbindd_getpwuid_send", $return] = start + idle_time["winbindd_getpwuid_send", $return] = end +} + +probe process("winbindd").function("winbindd_getpwuid_recv") { + if (!(["winbindd_getpwuid_send", $req] in start_time)) + next + + recv_running["winbindd_getpwuid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwuid_recv").return { + if (!(["winbindd_getpwuid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getpwuid_recv"] + delete recv_running["winbindd_getpwuid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getpwuid_send", req] + delete start_time["winbindd_getpwuid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getpwuid_send", req] + delete idle_time["winbindd_getpwuid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getpwuid_send"] <<< svctime + async_runtime["winbindd_getpwuid_send"] <<< runtime +} + +# +# winbind async function winbindd_getsidaliases +# + +probe process("winbindd").function("winbindd_getsidaliases_send") { + send_running["winbindd_getsidaliases_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getsidaliases_send").return { + if (!(["winbindd_getsidaliases_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getsidaliases_send"] + delete send_running["winbindd_getsidaliases_send"] + + start_time["winbindd_getsidaliases_send", $return] = start + idle_time["winbindd_getsidaliases_send", $return] = end +} + +probe process("winbindd").function("winbindd_getsidaliases_recv") { + if (!(["winbindd_getsidaliases_send", $req] in start_time)) + next + + recv_running["winbindd_getsidaliases_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getsidaliases_recv").return { + if (!(["winbindd_getsidaliases_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getsidaliases_recv"] + delete recv_running["winbindd_getsidaliases_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getsidaliases_send", req] + delete start_time["winbindd_getsidaliases_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getsidaliases_send", req] + delete idle_time["winbindd_getsidaliases_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getsidaliases_send"] <<< svctime + async_runtime["winbindd_getsidaliases_send"] <<< runtime +} + +# +# winbind async function winbindd_getuserdomgroups +# + +probe process("winbindd").function("winbindd_getuserdomgroups_send") { + send_running["winbindd_getuserdomgroups_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getuserdomgroups_send").return { + if (!(["winbindd_getuserdomgroups_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getuserdomgroups_send"] + delete send_running["winbindd_getuserdomgroups_send"] + + start_time["winbindd_getuserdomgroups_send", $return] = start + idle_time["winbindd_getuserdomgroups_send", $return] = end +} + +probe process("winbindd").function("winbindd_getuserdomgroups_recv") { + if (!(["winbindd_getuserdomgroups_send", $req] in start_time)) + next + + recv_running["winbindd_getuserdomgroups_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getuserdomgroups_recv").return { + if (!(["winbindd_getuserdomgroups_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getuserdomgroups_recv"] + delete recv_running["winbindd_getuserdomgroups_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getuserdomgroups_send", req] + delete start_time["winbindd_getuserdomgroups_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getuserdomgroups_send", req] + delete idle_time["winbindd_getuserdomgroups_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getuserdomgroups_send"] <<< svctime + async_runtime["winbindd_getuserdomgroups_send"] <<< runtime +} + +# +# winbind async function winbindd_getgroups +# + +probe process("winbindd").function("winbindd_getgroups_send") { + send_running["winbindd_getgroups_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgroups_send").return { + if (!(["winbindd_getgroups_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getgroups_send"] + delete send_running["winbindd_getgroups_send"] + + start_time["winbindd_getgroups_send", $return] = start + idle_time["winbindd_getgroups_send", $return] = end +} + +probe process("winbindd").function("winbindd_getgroups_recv") { + if (!(["winbindd_getgroups_send", $req] in start_time)) + next + + recv_running["winbindd_getgroups_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgroups_recv").return { + if (!(["winbindd_getgroups_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getgroups_recv"] + delete recv_running["winbindd_getgroups_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getgroups_send", req] + delete start_time["winbindd_getgroups_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getgroups_send", req] + delete idle_time["winbindd_getgroups_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getgroups_send"] <<< svctime + async_runtime["winbindd_getgroups_send"] <<< runtime +} + +# +# winbind async function winbindd_show_sequence +# + +probe process("winbindd").function("winbindd_show_sequence_send") { + send_running["winbindd_show_sequence_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_show_sequence_send").return { + if (!(["winbindd_show_sequence_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_show_sequence_send"] + delete send_running["winbindd_show_sequence_send"] + + start_time["winbindd_show_sequence_send", $return] = start + idle_time["winbindd_show_sequence_send", $return] = end +} + +probe process("winbindd").function("winbindd_show_sequence_recv") { + if (!(["winbindd_show_sequence_send", $req] in start_time)) + next + + recv_running["winbindd_show_sequence_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_show_sequence_recv").return { + if (!(["winbindd_show_sequence_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_show_sequence_recv"] + delete recv_running["winbindd_show_sequence_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_show_sequence_send", req] + delete start_time["winbindd_show_sequence_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_show_sequence_send", req] + delete idle_time["winbindd_show_sequence_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_show_sequence_send"] <<< svctime + async_runtime["winbindd_show_sequence_send"] <<< runtime +} + +# +# winbind async function winbindd_getgrgid +# + +probe process("winbindd").function("winbindd_getgrgid_send") { + send_running["winbindd_getgrgid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrgid_send").return { + if (!(["winbindd_getgrgid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getgrgid_send"] + delete send_running["winbindd_getgrgid_send"] + + start_time["winbindd_getgrgid_send", $return] = start + idle_time["winbindd_getgrgid_send", $return] = end +} + +probe process("winbindd").function("winbindd_getgrgid_recv") { + if (!(["winbindd_getgrgid_send", $req] in start_time)) + next + + recv_running["winbindd_getgrgid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrgid_recv").return { + if (!(["winbindd_getgrgid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getgrgid_recv"] + delete recv_running["winbindd_getgrgid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getgrgid_send", req] + delete start_time["winbindd_getgrgid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getgrgid_send", req] + delete idle_time["winbindd_getgrgid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getgrgid_send"] <<< svctime + async_runtime["winbindd_getgrgid_send"] <<< runtime +} + +# +# winbind async function winbindd_getgrnam +# + +probe process("winbindd").function("winbindd_getgrnam_send") { + send_running["winbindd_getgrnam_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrnam_send").return { + if (!(["winbindd_getgrnam_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getgrnam_send"] + delete send_running["winbindd_getgrnam_send"] + + start_time["winbindd_getgrnam_send", $return] = start + idle_time["winbindd_getgrnam_send", $return] = end +} + +probe process("winbindd").function("winbindd_getgrnam_recv") { + if (!(["winbindd_getgrnam_send", $req] in start_time)) + next + + recv_running["winbindd_getgrnam_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrnam_recv").return { + if (!(["winbindd_getgrnam_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getgrnam_recv"] + delete recv_running["winbindd_getgrnam_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getgrnam_send", req] + delete start_time["winbindd_getgrnam_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getgrnam_send", req] + delete idle_time["winbindd_getgrnam_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getgrnam_send"] <<< svctime + async_runtime["winbindd_getgrnam_send"] <<< runtime +} + +# +# winbind async function winbindd_getusersids +# + +probe process("winbindd").function("winbindd_getusersids_send") { + send_running["winbindd_getusersids_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getusersids_send").return { + if (!(["winbindd_getusersids_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getusersids_send"] + delete send_running["winbindd_getusersids_send"] + + start_time["winbindd_getusersids_send", $return] = start + idle_time["winbindd_getusersids_send", $return] = end +} + +probe process("winbindd").function("winbindd_getusersids_recv") { + if (!(["winbindd_getusersids_send", $req] in start_time)) + next + + recv_running["winbindd_getusersids_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getusersids_recv").return { + if (!(["winbindd_getusersids_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getusersids_recv"] + delete recv_running["winbindd_getusersids_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getusersids_send", req] + delete start_time["winbindd_getusersids_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getusersids_send", req] + delete idle_time["winbindd_getusersids_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getusersids_send"] <<< svctime + async_runtime["winbindd_getusersids_send"] <<< runtime +} + +# +# winbind async function winbindd_lookuprids +# + +probe process("winbindd").function("winbindd_lookuprids_send") { + send_running["winbindd_lookuprids_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookuprids_send").return { + if (!(["winbindd_lookuprids_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_lookuprids_send"] + delete send_running["winbindd_lookuprids_send"] + + start_time["winbindd_lookuprids_send", $return] = start + idle_time["winbindd_lookuprids_send", $return] = end +} + +probe process("winbindd").function("winbindd_lookuprids_recv") { + if (!(["winbindd_lookuprids_send", $req] in start_time)) + next + + recv_running["winbindd_lookuprids_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_lookuprids_recv").return { + if (!(["winbindd_lookuprids_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_lookuprids_recv"] + delete recv_running["winbindd_lookuprids_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_lookuprids_send", req] + delete start_time["winbindd_lookuprids_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_lookuprids_send", req] + delete idle_time["winbindd_lookuprids_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_lookuprids_send"] <<< svctime + async_runtime["winbindd_lookuprids_send"] <<< runtime +} + +# +# winbind async function winbindd_setpwent +# + +probe process("winbindd").function("winbindd_setpwent_send") { + send_running["winbindd_setpwent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_setpwent_send").return { + if (!(["winbindd_setpwent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_setpwent_send"] + delete send_running["winbindd_setpwent_send"] + + start_time["winbindd_setpwent_send", $return] = start + idle_time["winbindd_setpwent_send", $return] = end +} + +probe process("winbindd").function("winbindd_setpwent_recv") { + if (!(["winbindd_setpwent_send", $req] in start_time)) + next + + recv_running["winbindd_setpwent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_setpwent_recv").return { + if (!(["winbindd_setpwent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_setpwent_recv"] + delete recv_running["winbindd_setpwent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_setpwent_send", req] + delete start_time["winbindd_setpwent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_setpwent_send", req] + delete idle_time["winbindd_setpwent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_setpwent_send"] <<< svctime + async_runtime["winbindd_setpwent_send"] <<< runtime +} + +# +# winbind async function winbindd_getpwent +# + +probe process("winbindd").function("winbindd_getpwent_send") { + send_running["winbindd_getpwent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwent_send").return { + if (!(["winbindd_getpwent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getpwent_send"] + delete send_running["winbindd_getpwent_send"] + + start_time["winbindd_getpwent_send", $return] = start + idle_time["winbindd_getpwent_send", $return] = end +} + +probe process("winbindd").function("winbindd_getpwent_recv") { + if (!(["winbindd_getpwent_send", $req] in start_time)) + next + + recv_running["winbindd_getpwent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getpwent_recv").return { + if (!(["winbindd_getpwent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getpwent_recv"] + delete recv_running["winbindd_getpwent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getpwent_send", req] + delete start_time["winbindd_getpwent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getpwent_send", req] + delete idle_time["winbindd_getpwent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getpwent_send"] <<< svctime + async_runtime["winbindd_getpwent_send"] <<< runtime +} + +# +# winbind async function winbindd_endpwent +# + +probe process("winbindd").function("winbindd_endpwent_send") { + send_running["winbindd_endpwent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_endpwent_send").return { + if (!(["winbindd_endpwent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_endpwent_send"] + delete send_running["winbindd_endpwent_send"] + + start_time["winbindd_endpwent_send", $return] = start + idle_time["winbindd_endpwent_send", $return] = end +} + +probe process("winbindd").function("winbindd_endpwent_recv") { + if (!(["winbindd_endpwent_send", $req] in start_time)) + next + + recv_running["winbindd_endpwent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_endpwent_recv").return { + if (!(["winbindd_endpwent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_endpwent_recv"] + delete recv_running["winbindd_endpwent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_endpwent_send", req] + delete start_time["winbindd_endpwent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_endpwent_send", req] + delete idle_time["winbindd_endpwent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_endpwent_send"] <<< svctime + async_runtime["winbindd_endpwent_send"] <<< runtime +} + +# +# winbind async function winbindd_dsgetdcname +# + +probe process("winbindd").function("winbindd_dsgetdcname_send") { + send_running["winbindd_dsgetdcname_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dsgetdcname_send").return { + if (!(["winbindd_dsgetdcname_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_dsgetdcname_send"] + delete send_running["winbindd_dsgetdcname_send"] + + start_time["winbindd_dsgetdcname_send", $return] = start + idle_time["winbindd_dsgetdcname_send", $return] = end +} + +probe process("winbindd").function("winbindd_dsgetdcname_recv") { + if (!(["winbindd_dsgetdcname_send", $req] in start_time)) + next + + recv_running["winbindd_dsgetdcname_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_dsgetdcname_recv").return { + if (!(["winbindd_dsgetdcname_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_dsgetdcname_recv"] + delete recv_running["winbindd_dsgetdcname_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_dsgetdcname_send", req] + delete start_time["winbindd_dsgetdcname_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_dsgetdcname_send", req] + delete idle_time["winbindd_dsgetdcname_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_dsgetdcname_send"] <<< svctime + async_runtime["winbindd_dsgetdcname_send"] <<< runtime +} + +# +# winbind async function winbindd_getdcname +# + +probe process("winbindd").function("winbindd_getdcname_send") { + send_running["winbindd_getdcname_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getdcname_send").return { + if (!(["winbindd_getdcname_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getdcname_send"] + delete send_running["winbindd_getdcname_send"] + + start_time["winbindd_getdcname_send", $return] = start + idle_time["winbindd_getdcname_send", $return] = end +} + +probe process("winbindd").function("winbindd_getdcname_recv") { + if (!(["winbindd_getdcname_send", $req] in start_time)) + next + + recv_running["winbindd_getdcname_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getdcname_recv").return { + if (!(["winbindd_getdcname_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getdcname_recv"] + delete recv_running["winbindd_getdcname_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getdcname_send", req] + delete start_time["winbindd_getdcname_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getdcname_send", req] + delete idle_time["winbindd_getdcname_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getdcname_send"] <<< svctime + async_runtime["winbindd_getdcname_send"] <<< runtime +} + +# +# winbind async function winbindd_setgrent +# + +probe process("winbindd").function("winbindd_setgrent_send") { + send_running["winbindd_setgrent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_setgrent_send").return { + if (!(["winbindd_setgrent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_setgrent_send"] + delete send_running["winbindd_setgrent_send"] + + start_time["winbindd_setgrent_send", $return] = start + idle_time["winbindd_setgrent_send", $return] = end +} + +probe process("winbindd").function("winbindd_setgrent_recv") { + if (!(["winbindd_setgrent_send", $req] in start_time)) + next + + recv_running["winbindd_setgrent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_setgrent_recv").return { + if (!(["winbindd_setgrent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_setgrent_recv"] + delete recv_running["winbindd_setgrent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_setgrent_send", req] + delete start_time["winbindd_setgrent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_setgrent_send", req] + delete idle_time["winbindd_setgrent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_setgrent_send"] <<< svctime + async_runtime["winbindd_setgrent_send"] <<< runtime +} + +# +# winbind async function winbindd_getgrent +# + +probe process("winbindd").function("winbindd_getgrent_send") { + send_running["winbindd_getgrent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrent_send").return { + if (!(["winbindd_getgrent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_getgrent_send"] + delete send_running["winbindd_getgrent_send"] + + start_time["winbindd_getgrent_send", $return] = start + idle_time["winbindd_getgrent_send", $return] = end +} + +probe process("winbindd").function("winbindd_getgrent_recv") { + if (!(["winbindd_getgrent_send", $req] in start_time)) + next + + recv_running["winbindd_getgrent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_getgrent_recv").return { + if (!(["winbindd_getgrent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_getgrent_recv"] + delete recv_running["winbindd_getgrent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_getgrent_send", req] + delete start_time["winbindd_getgrent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_getgrent_send", req] + delete idle_time["winbindd_getgrent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_getgrent_send"] <<< svctime + async_runtime["winbindd_getgrent_send"] <<< runtime +} + +# +# winbind async function winbindd_endgrent +# + +probe process("winbindd").function("winbindd_endgrent_send") { + send_running["winbindd_endgrent_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_endgrent_send").return { + if (!(["winbindd_endgrent_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_endgrent_send"] + delete send_running["winbindd_endgrent_send"] + + start_time["winbindd_endgrent_send", $return] = start + idle_time["winbindd_endgrent_send", $return] = end +} + +probe process("winbindd").function("winbindd_endgrent_recv") { + if (!(["winbindd_endgrent_send", $req] in start_time)) + next + + recv_running["winbindd_endgrent_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_endgrent_recv").return { + if (!(["winbindd_endgrent_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_endgrent_recv"] + delete recv_running["winbindd_endgrent_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_endgrent_send", req] + delete start_time["winbindd_endgrent_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_endgrent_send", req] + delete idle_time["winbindd_endgrent_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_endgrent_send"] <<< svctime + async_runtime["winbindd_endgrent_send"] <<< runtime +} + +# +# winbind async function winbindd_list_users +# + +probe process("winbindd").function("winbindd_list_users_send") { + send_running["winbindd_list_users_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_list_users_send").return { + if (!(["winbindd_list_users_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_list_users_send"] + delete send_running["winbindd_list_users_send"] + + start_time["winbindd_list_users_send", $return] = start + idle_time["winbindd_list_users_send", $return] = end +} + +probe process("winbindd").function("winbindd_list_users_recv") { + if (!(["winbindd_list_users_send", $req] in start_time)) + next + + recv_running["winbindd_list_users_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_list_users_recv").return { + if (!(["winbindd_list_users_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_list_users_recv"] + delete recv_running["winbindd_list_users_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_list_users_send", req] + delete start_time["winbindd_list_users_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_list_users_send", req] + delete idle_time["winbindd_list_users_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_list_users_send"] <<< svctime + async_runtime["winbindd_list_users_send"] <<< runtime +} + +# +# winbind async function winbindd_list_groups +# + +probe process("winbindd").function("winbindd_list_groups_send") { + send_running["winbindd_list_groups_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_list_groups_send").return { + if (!(["winbindd_list_groups_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_list_groups_send"] + delete send_running["winbindd_list_groups_send"] + + start_time["winbindd_list_groups_send", $return] = start + idle_time["winbindd_list_groups_send", $return] = end +} + +probe process("winbindd").function("winbindd_list_groups_recv") { + if (!(["winbindd_list_groups_send", $req] in start_time)) + next + + recv_running["winbindd_list_groups_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_list_groups_recv").return { + if (!(["winbindd_list_groups_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_list_groups_recv"] + delete recv_running["winbindd_list_groups_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_list_groups_send", req] + delete start_time["winbindd_list_groups_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_list_groups_send", req] + delete idle_time["winbindd_list_groups_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_list_groups_send"] <<< svctime + async_runtime["winbindd_list_groups_send"] <<< runtime +} + +# +# winbind async function winbindd_check_machine_acct +# + +probe process("winbindd").function("winbindd_check_machine_acct_send") { + send_running["winbindd_check_machine_acct_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_check_machine_acct_send").return { + if (!(["winbindd_check_machine_acct_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_check_machine_acct_send"] + delete send_running["winbindd_check_machine_acct_send"] + + start_time["winbindd_check_machine_acct_send", $return] = start + idle_time["winbindd_check_machine_acct_send", $return] = end +} + +probe process("winbindd").function("winbindd_check_machine_acct_recv") { + if (!(["winbindd_check_machine_acct_send", $req] in start_time)) + next + + recv_running["winbindd_check_machine_acct_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_check_machine_acct_recv").return { + if (!(["winbindd_check_machine_acct_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_check_machine_acct_recv"] + delete recv_running["winbindd_check_machine_acct_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_check_machine_acct_send", req] + delete start_time["winbindd_check_machine_acct_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_check_machine_acct_send", req] + delete idle_time["winbindd_check_machine_acct_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_check_machine_acct_send"] <<< svctime + async_runtime["winbindd_check_machine_acct_send"] <<< runtime +} + +# +# winbind async function winbindd_ping_dc +# + +probe process("winbindd").function("winbindd_ping_dc_send") { + send_running["winbindd_ping_dc_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_ping_dc_send").return { + if (!(["winbindd_ping_dc_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_ping_dc_send"] + delete send_running["winbindd_ping_dc_send"] + + start_time["winbindd_ping_dc_send", $return] = start + idle_time["winbindd_ping_dc_send", $return] = end +} + +probe process("winbindd").function("winbindd_ping_dc_recv") { + if (!(["winbindd_ping_dc_send", $req] in start_time)) + next + + recv_running["winbindd_ping_dc_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_ping_dc_recv").return { + if (!(["winbindd_ping_dc_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_ping_dc_recv"] + delete recv_running["winbindd_ping_dc_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_ping_dc_send", req] + delete start_time["winbindd_ping_dc_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_ping_dc_send", req] + delete idle_time["winbindd_ping_dc_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_ping_dc_send"] <<< svctime + async_runtime["winbindd_ping_dc_send"] <<< runtime +} + +# +# winbind async function winbindd_pam_auth +# + +probe process("winbindd").function("winbindd_pam_auth_send") { + send_running["winbindd_pam_auth_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_auth_send").return { + if (!(["winbindd_pam_auth_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_pam_auth_send"] + delete send_running["winbindd_pam_auth_send"] + + start_time["winbindd_pam_auth_send", $return] = start + idle_time["winbindd_pam_auth_send", $return] = end +} + +probe process("winbindd").function("winbindd_pam_auth_recv") { + if (!(["winbindd_pam_auth_send", $req] in start_time)) + next + + recv_running["winbindd_pam_auth_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_auth_recv").return { + if (!(["winbindd_pam_auth_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_pam_auth_recv"] + delete recv_running["winbindd_pam_auth_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_pam_auth_send", req] + delete start_time["winbindd_pam_auth_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_pam_auth_send", req] + delete idle_time["winbindd_pam_auth_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_pam_auth_send"] <<< svctime + async_runtime["winbindd_pam_auth_send"] <<< runtime +} + +# +# winbind async function winbindd_pam_logoff +# + +probe process("winbindd").function("winbindd_pam_logoff_send") { + send_running["winbindd_pam_logoff_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_logoff_send").return { + if (!(["winbindd_pam_logoff_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_pam_logoff_send"] + delete send_running["winbindd_pam_logoff_send"] + + start_time["winbindd_pam_logoff_send", $return] = start + idle_time["winbindd_pam_logoff_send", $return] = end +} + +probe process("winbindd").function("winbindd_pam_logoff_recv") { + if (!(["winbindd_pam_logoff_send", $req] in start_time)) + next + + recv_running["winbindd_pam_logoff_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_logoff_recv").return { + if (!(["winbindd_pam_logoff_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_pam_logoff_recv"] + delete recv_running["winbindd_pam_logoff_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_pam_logoff_send", req] + delete start_time["winbindd_pam_logoff_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_pam_logoff_send", req] + delete idle_time["winbindd_pam_logoff_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_pam_logoff_send"] <<< svctime + async_runtime["winbindd_pam_logoff_send"] <<< runtime +} + +# +# winbind async function winbindd_pam_chauthtok +# + +probe process("winbindd").function("winbindd_pam_chauthtok_send") { + send_running["winbindd_pam_chauthtok_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_chauthtok_send").return { + if (!(["winbindd_pam_chauthtok_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_pam_chauthtok_send"] + delete send_running["winbindd_pam_chauthtok_send"] + + start_time["winbindd_pam_chauthtok_send", $return] = start + idle_time["winbindd_pam_chauthtok_send", $return] = end +} + +probe process("winbindd").function("winbindd_pam_chauthtok_recv") { + if (!(["winbindd_pam_chauthtok_send", $req] in start_time)) + next + + recv_running["winbindd_pam_chauthtok_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_chauthtok_recv").return { + if (!(["winbindd_pam_chauthtok_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_pam_chauthtok_recv"] + delete recv_running["winbindd_pam_chauthtok_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_pam_chauthtok_send", req] + delete start_time["winbindd_pam_chauthtok_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_pam_chauthtok_send", req] + delete idle_time["winbindd_pam_chauthtok_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_pam_chauthtok_send"] <<< svctime + async_runtime["winbindd_pam_chauthtok_send"] <<< runtime +} + +# +# winbind async function winbindd_pam_chng_pswd_auth_crap +# + +probe process("winbindd").function("winbindd_pam_chng_pswd_auth_crap_send") { + send_running["winbindd_pam_chng_pswd_auth_crap_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_chng_pswd_auth_crap_send").return { + if (!(["winbindd_pam_chng_pswd_auth_crap_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_pam_chng_pswd_auth_crap_send"] + delete send_running["winbindd_pam_chng_pswd_auth_crap_send"] + + start_time["winbindd_pam_chng_pswd_auth_crap_send", $return] = start + idle_time["winbindd_pam_chng_pswd_auth_crap_send", $return] = end +} + +probe process("winbindd").function("winbindd_pam_chng_pswd_auth_crap_recv") { + if (!(["winbindd_pam_chng_pswd_auth_crap_send", $req] in start_time)) + next + + recv_running["winbindd_pam_chng_pswd_auth_crap_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_chng_pswd_auth_crap_recv").return { + if (!(["winbindd_pam_chng_pswd_auth_crap_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_pam_chng_pswd_auth_crap_recv"] + delete recv_running["winbindd_pam_chng_pswd_auth_crap_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_pam_chng_pswd_auth_crap_send", req] + delete start_time["winbindd_pam_chng_pswd_auth_crap_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_pam_chng_pswd_auth_crap_send", req] + delete idle_time["winbindd_pam_chng_pswd_auth_crap_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_pam_chng_pswd_auth_crap_send"] <<< svctime + async_runtime["winbindd_pam_chng_pswd_auth_crap_send"] <<< runtime +} + +# +# winbind async function winbindd_wins_byip +# + +probe process("winbindd").function("winbindd_wins_byip_send") { + send_running["winbindd_wins_byip_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_wins_byip_send").return { + if (!(["winbindd_wins_byip_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_wins_byip_send"] + delete send_running["winbindd_wins_byip_send"] + + start_time["winbindd_wins_byip_send", $return] = start + idle_time["winbindd_wins_byip_send", $return] = end +} + +probe process("winbindd").function("winbindd_wins_byip_recv") { + if (!(["winbindd_wins_byip_send", $req] in start_time)) + next + + recv_running["winbindd_wins_byip_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_wins_byip_recv").return { + if (!(["winbindd_wins_byip_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_wins_byip_recv"] + delete recv_running["winbindd_wins_byip_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_wins_byip_send", req] + delete start_time["winbindd_wins_byip_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_wins_byip_send", req] + delete idle_time["winbindd_wins_byip_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_wins_byip_send"] <<< svctime + async_runtime["winbindd_wins_byip_send"] <<< runtime +} + +# +# winbind async function winbindd_wins_byname +# + +probe process("winbindd").function("winbindd_wins_byname_send") { + send_running["winbindd_wins_byname_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_wins_byname_send").return { + if (!(["winbindd_wins_byname_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_wins_byname_send"] + delete send_running["winbindd_wins_byname_send"] + + start_time["winbindd_wins_byname_send", $return] = start + idle_time["winbindd_wins_byname_send", $return] = end +} + +probe process("winbindd").function("winbindd_wins_byname_recv") { + if (!(["winbindd_wins_byname_send", $req] in start_time)) + next + + recv_running["winbindd_wins_byname_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_wins_byname_recv").return { + if (!(["winbindd_wins_byname_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_wins_byname_recv"] + delete recv_running["winbindd_wins_byname_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_wins_byname_send", req] + delete start_time["winbindd_wins_byname_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_wins_byname_send", req] + delete idle_time["winbindd_wins_byname_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_wins_byname_send"] <<< svctime + async_runtime["winbindd_wins_byname_send"] <<< runtime +} + +# +# winbind async function winbindd_allocate_uid +# + +probe process("winbindd").function("winbindd_allocate_uid_send") { + send_running["winbindd_allocate_uid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_allocate_uid_send").return { + if (!(["winbindd_allocate_uid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_allocate_uid_send"] + delete send_running["winbindd_allocate_uid_send"] + + start_time["winbindd_allocate_uid_send", $return] = start + idle_time["winbindd_allocate_uid_send", $return] = end +} + +probe process("winbindd").function("winbindd_allocate_uid_recv") { + if (!(["winbindd_allocate_uid_send", $req] in start_time)) + next + + recv_running["winbindd_allocate_uid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_allocate_uid_recv").return { + if (!(["winbindd_allocate_uid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_allocate_uid_recv"] + delete recv_running["winbindd_allocate_uid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_allocate_uid_send", req] + delete start_time["winbindd_allocate_uid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_allocate_uid_send", req] + delete idle_time["winbindd_allocate_uid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_allocate_uid_send"] <<< svctime + async_runtime["winbindd_allocate_uid_send"] <<< runtime +} + +# +# winbind async function winbindd_allocate_gid +# + +probe process("winbindd").function("winbindd_allocate_gid_send") { + send_running["winbindd_allocate_gid_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_allocate_gid_send").return { + if (!(["winbindd_allocate_gid_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_allocate_gid_send"] + delete send_running["winbindd_allocate_gid_send"] + + start_time["winbindd_allocate_gid_send", $return] = start + idle_time["winbindd_allocate_gid_send", $return] = end +} + +probe process("winbindd").function("winbindd_allocate_gid_recv") { + if (!(["winbindd_allocate_gid_send", $req] in start_time)) + next + + recv_running["winbindd_allocate_gid_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_allocate_gid_recv").return { + if (!(["winbindd_allocate_gid_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_allocate_gid_recv"] + delete recv_running["winbindd_allocate_gid_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_allocate_gid_send", req] + delete start_time["winbindd_allocate_gid_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_allocate_gid_send", req] + delete idle_time["winbindd_allocate_gid_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_allocate_gid_send"] <<< svctime + async_runtime["winbindd_allocate_gid_send"] <<< runtime +} + +# +# winbind async function winbindd_change_machine_acct +# + +probe process("winbindd").function("winbindd_change_machine_acct_send") { + send_running["winbindd_change_machine_acct_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_change_machine_acct_send").return { + if (!(["winbindd_change_machine_acct_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_change_machine_acct_send"] + delete send_running["winbindd_change_machine_acct_send"] + + start_time["winbindd_change_machine_acct_send", $return] = start + idle_time["winbindd_change_machine_acct_send", $return] = end +} + +probe process("winbindd").function("winbindd_change_machine_acct_recv") { + if (!(["winbindd_change_machine_acct_send", $req] in start_time)) + next + + recv_running["winbindd_change_machine_acct_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_change_machine_acct_recv").return { + if (!(["winbindd_change_machine_acct_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_change_machine_acct_recv"] + delete recv_running["winbindd_change_machine_acct_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_change_machine_acct_send", req] + delete start_time["winbindd_change_machine_acct_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_change_machine_acct_send", req] + delete idle_time["winbindd_change_machine_acct_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_change_machine_acct_send"] <<< svctime + async_runtime["winbindd_change_machine_acct_send"] <<< runtime +} + +# +# winbind async function winbindd_pam_auth_crap +# + +probe process("winbindd").function("winbindd_pam_auth_crap_send") { + send_running["winbindd_pam_auth_crap_send"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_auth_crap_send").return { + if (!(["winbindd_pam_auth_crap_send"] in send_running)) + next + + end = gettimeofday_us() + start = send_running["winbindd_pam_auth_crap_send"] + delete send_running["winbindd_pam_auth_crap_send"] + + start_time["winbindd_pam_auth_crap_send", $return] = start + idle_time["winbindd_pam_auth_crap_send", $return] = end +} + +probe process("winbindd").function("winbindd_pam_auth_crap_recv") { + if (!(["winbindd_pam_auth_crap_send", $req] in start_time)) + next + + recv_running["winbindd_pam_auth_crap_recv"] = gettimeofday_us() +} + +probe process("winbindd").function("winbindd_pam_auth_crap_recv").return { + if (!(["winbindd_pam_auth_crap_recv"] in recv_running)) + next + + recv_end = gettimeofday_us() + recv_start = recv_running["winbindd_pam_auth_crap_recv"] + delete recv_running["winbindd_pam_auth_crap_recv"] + recv_runtime = recv_end - recv_start + + req = @entry($req) + + send_begin = start_time["winbindd_pam_auth_crap_send", req] + delete start_time["winbindd_pam_auth_crap_send", req] + svctime = recv_end - send_begin + + idle = idle_time["winbindd_pam_auth_crap_send", req] + delete idle_time["winbindd_pam_auth_crap_send", req] + runtime = (idle - send_begin) + recv_runtime + + async_svctime["winbindd_pam_auth_crap_send"] <<< svctime + async_runtime["winbindd_pam_auth_crap_send"] <<< runtime +} + +probe end { + printf("\n\n") + + printf("Winbind request service time\n") + printf("============================\n") + foreach ([name] in async_svctime) { + printf("%-40s count: %5d, sum: %6d ms (min: %6d us, avg: %6d us, max: %6d us)\n", + name, + @count(async_svctime[name]), + @sum(async_svctime[name]) / 1000, + @min(async_svctime[name]), + @avg(async_svctime[name]), + @max(async_svctime[name])) + } + printf("\n") + + printf("Winbind request runtime\n") + printf("=======================\n") + foreach ([name] in async_runtime) { + printf("%-40s count: %5d, sum: %6d ms (min: %6d us, avg: %6d us, max: %6d us)\n", + name, + @count(async_runtime[name]), + @sum(async_runtime[name]) / 1000, + @min(async_runtime[name]), + @avg(async_runtime[name]), + @max(async_runtime[name])) + } + printf("\n") + + printf("Winbind domain-child request service time\n") + printf("=========================================\n") + foreach ([name] in dc_svctime) { + printf("%-40s count: %5d, sum: %6d ms (min: %6d us, avg: %6d us, max: %6d us)\n", + name, + @count(dc_svctime[name]), + @sum(dc_svctime[name]) / 1000, + @min(dc_svctime[name]), + @avg(dc_svctime[name]), + @max(dc_svctime[name])) + } + printf("\n") + + printf("Winbind domain-child AD-backend service time\n") + printf("============================================\n") + foreach ([name] in backend_svctime) { + printf("%-40s count: %5d, sum: %6d ms (min: %6d us, avg: %6d us, max: %6d us)\n", + name, + @count(backend_svctime[name]), + @sum(backend_svctime[name]) / 1000, + @min(backend_svctime[name]), + @avg(backend_svctime[name]), + @max(backend_svctime[name])) + } + printf("\n") + + printf("Winbind request service time distributions (us)\n") + printf("===============================================\n") + foreach ([name] in async_svctime) { + printf("%s:\n", name); + println(@hist_log(async_svctime[name])) + } + printf("\n") + + printf("Winbind request runtime distributions (us)\n") + printf("==========================================\n") + foreach ([name] in async_runtime) { + printf("%s:\n", name); + println(@hist_log(async_runtime[name])) + } + + printf("Winbind domain-child request service time distributions (us)\n") + printf("============================================================\n") + foreach ([name] in dc_svctime) { + printf("%s:\n", name); + println(@hist_log(dc_svctime[name])) + } + + printf("Winbind domain-child AD-backend service time distributions (us)\n") + printf("===============================================================\n") + foreach ([name] in backend_svctime) { + printf("%s:\n", name); + println(@hist_log(backend_svctime[name])) + } +} -- cgit v1.2.1