The Samba-Bugzilla – Attachment 9872 Details for
Bug 10564
Lock order violation and file lost
Home
|
New
|
Browse
|
Search
|
[?]
|
Reports
|
Requests
|
Help
|
New Account
|
Log In
[x]
|
Forgot Password
Login:
[x]
log.smbd
log.smbd (text/plain), 240.22 KB, created by
Tom Schulz
on 2014-04-25 14:12:32 UTC
(
hide
)
Description:
log.smbd
Filename:
MIME Type:
Creator:
Tom Schulz
Created:
2014-04-25 14:12:32 UTC
Size:
240.22 KB
patch
obsolete
> tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.453051, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.453593, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x001a (26) > name_size : 0x001a (26) > name : * > name : 'ErrorControl' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.454650, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.454890, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON:ErrorControl] >[2014/04/25 09:45:50.455022, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.455156, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.455501, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0016 (22) > name_size : 0x0016 (22) > name : * > name : 'ObjectName' > type : REG_SZ (1) > data : * > data: ARRAY(24) > [0] : 0x4c (76) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x63 (99) > [5] : 0x00 (0) > [6] : 0x61 (97) > [7] : 0x00 (0) > [8] : 0x6c (108) > [9] : 0x00 (0) > [10] : 0x53 (83) > [11] : 0x00 (0) > [12] : 0x79 (121) > [13] : 0x00 (0) > [14] : 0x73 (115) > [15] : 0x00 (0) > [16] : 0x74 (116) > [17] : 0x00 (0) > [18] : 0x65 (101) > [19] : 0x00 (0) > [20] : 0x6d (109) > [21] : 0x00 (0) > [22] : 0x00 (0) > [23] : 0x00 (0) > size : 0x00000018 (24) >[2014/04/25 09:45:50.457595, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.457827, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON:ObjectName] >[2014/04/25 09:45:50.457956, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.458088, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.458448, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'DisplayName' > type : REG_SZ (1) > data : * > data: ARRAY(20) > [0] : 0x4e (78) > [1] : 0x00 (0) > [2] : 0x65 (101) > [3] : 0x00 (0) > [4] : 0x74 (116) > [5] : 0x00 (0) > [6] : 0x20 (32) > [7] : 0x00 (0) > [8] : 0x4c (76) > [9] : 0x00 (0) > [10] : 0x6f (111) > [11] : 0x00 (0) > [12] : 0x67 (103) > [13] : 0x00 (0) > [14] : 0x6f (111) > [15] : 0x00 (0) > [16] : 0x6e (110) > [17] : 0x00 (0) > [18] : 0x00 (0) > [19] : 0x00 (0) > size : 0x00000014 (20) >[2014/04/25 09:45:50.460329, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.460609, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON:DisplayName] >[2014/04/25 09:45:50.460739, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.460874, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.461193, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0014 (20) > name_size : 0x0014 (20) > name : * > name : 'ImagePath' > type : REG_SZ (1) > data : * > data: ARRAY(68) > [0] : 0x2f (47) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x70 (112) > [5] : 0x00 (0) > [6] : 0x74 (116) > [7] : 0x00 (0) > [8] : 0x2f (47) > [9] : 0x00 (0) > [10] : 0x6c (108) > [11] : 0x00 (0) > [12] : 0x6f (111) > [13] : 0x00 (0) > [14] : 0x63 (99) > [15] : 0x00 (0) > [16] : 0x61 (97) > [17] : 0x00 (0) > [18] : 0x6c (108) > [19] : 0x00 (0) > [20] : 0x2f (47) > [21] : 0x00 (0) > [22] : 0x73 (115) > [23] : 0x00 (0) > [24] : 0x61 (97) > [25] : 0x00 (0) > [26] : 0x6d (109) > [27] : 0x00 (0) > [28] : 0x62 (98) > [29] : 0x00 (0) > [30] : 0x61 (97) > [31] : 0x00 (0) > [32] : 0x34 (52) > [33] : 0x00 (0) > [34] : 0x2f (47) > [35] : 0x00 (0) > [36] : 0x6c (108) > [37] : 0x00 (0) > [38] : 0x69 (105) > [39] : 0x00 (0) > [40] : 0x62 (98) > [41] : 0x00 (0) > [42] : 0x2f (47) > [43] : 0x00 (0) > [44] : 0x73 (115) > [45] : 0x00 (0) > [46] : 0x76 (118) > [47] : 0x00 (0) > [48] : 0x63 (99) > [49] : 0x00 (0) > [50] : 0x63 (99) > [51] : 0x00 (0) > [52] : 0x74 (116) > [53] : 0x00 (0) > [54] : 0x6c (108) > [55] : 0x00 (0) > [56] : 0x2f (47) > [57] : 0x00 (0) > [58] : 0x73 (115) > [59] : 0x00 (0) > [60] : 0x6d (109) > [61] : 0x00 (0) > [62] : 0x62 (98) > [63] : 0x00 (0) > [64] : 0x64 (100) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > size : 0x00000044 (68) >[2014/04/25 09:45:50.465603, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.465846, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON:ImagePath] >[2014/04/25 09:45:50.465975, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.466108, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.466456, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'Description' > type : REG_SZ (1) > data : * > data: ARRAY(164) > [0] : 0x46 (70) > [1] : 0x00 (0) > [2] : 0x69 (105) > [3] : 0x00 (0) > [4] : 0x6c (108) > [5] : 0x00 (0) > [6] : 0x65 (101) > [7] : 0x00 (0) > [8] : 0x20 (32) > [9] : 0x00 (0) > [10] : 0x73 (115) > [11] : 0x00 (0) > [12] : 0x65 (101) > [13] : 0x00 (0) > [14] : 0x72 (114) > [15] : 0x00 (0) > [16] : 0x76 (118) > [17] : 0x00 (0) > [18] : 0x69 (105) > [19] : 0x00 (0) > [20] : 0x63 (99) > [21] : 0x00 (0) > [22] : 0x65 (101) > [23] : 0x00 (0) > [24] : 0x20 (32) > [25] : 0x00 (0) > [26] : 0x70 (112) > [27] : 0x00 (0) > [28] : 0x72 (114) > [29] : 0x00 (0) > [30] : 0x6f (111) > [31] : 0x00 (0) > [32] : 0x76 (118) > [33] : 0x00 (0) > [34] : 0x69 (105) > [35] : 0x00 (0) > [36] : 0x64 (100) > [37] : 0x00 (0) > [38] : 0x69 (105) > [39] : 0x00 (0) > [40] : 0x6e (110) > [41] : 0x00 (0) > [42] : 0x67 (103) > [43] : 0x00 (0) > [44] : 0x20 (32) > [45] : 0x00 (0) > [46] : 0x61 (97) > [47] : 0x00 (0) > [48] : 0x63 (99) > [49] : 0x00 (0) > [50] : 0x63 (99) > [51] : 0x00 (0) > [52] : 0x65 (101) > [53] : 0x00 (0) > [54] : 0x73 (115) > [55] : 0x00 (0) > [56] : 0x73 (115) > [57] : 0x00 (0) > [58] : 0x20 (32) > [59] : 0x00 (0) > [60] : 0x74 (116) > [61] : 0x00 (0) > [62] : 0x6f (111) > [63] : 0x00 (0) > [64] : 0x20 (32) > [65] : 0x00 (0) > [66] : 0x70 (112) > [67] : 0x00 (0) > [68] : 0x6f (111) > [69] : 0x00 (0) > [70] : 0x6c (108) > [71] : 0x00 (0) > [72] : 0x69 (105) > [73] : 0x00 (0) > [74] : 0x63 (99) > [75] : 0x00 (0) > [76] : 0x79 (121) > [77] : 0x00 (0) > [78] : 0x20 (32) > [79] : 0x00 (0) > [80] : 0x61 (97) > [81] : 0x00 (0) > [82] : 0x6e (110) > [83] : 0x00 (0) > [84] : 0x64 (100) > [85] : 0x00 (0) > [86] : 0x20 (32) > [87] : 0x00 (0) > [88] : 0x70 (112) > [89] : 0x00 (0) > [90] : 0x72 (114) > [91] : 0x00 (0) > [92] : 0x6f (111) > [93] : 0x00 (0) > [94] : 0x66 (102) > [95] : 0x00 (0) > [96] : 0x69 (105) > [97] : 0x00 (0) > [98] : 0x6c (108) > [99] : 0x00 (0) > [100] : 0x65 (101) > [101] : 0x00 (0) > [102] : 0x20 (32) > [103] : 0x00 (0) > [104] : 0x64 (100) > [105] : 0x00 (0) > [106] : 0x61 (97) > [107] : 0x00 (0) > [108] : 0x74 (116) > [109] : 0x00 (0) > [110] : 0x61 (97) > [111] : 0x00 (0) > [112] : 0x20 (32) > [113] : 0x00 (0) > [114] : 0x28 (40) > [115] : 0x00 (0) > [116] : 0x6e (110) > [117] : 0x00 (0) > [118] : 0x6f (111) > [119] : 0x00 (0) > [120] : 0x74 (116) > [121] : 0x00 (0) > [122] : 0x72 (114) > [123] : 0x00 (0) > [124] : 0x65 (101) > [125] : 0x00 (0) > [126] : 0x6d (109) > [127] : 0x00 (0) > [128] : 0x6f (111) > [129] : 0x00 (0) > [130] : 0x74 (116) > [131] : 0x00 (0) > [132] : 0x65 (101) > [133] : 0x00 (0) > [134] : 0x6c (108) > [135] : 0x00 (0) > [136] : 0x79 (121) > [137] : 0x00 (0) > [138] : 0x20 (32) > [139] : 0x00 (0) > [140] : 0x6d (109) > [141] : 0x00 (0) > [142] : 0x61 (97) > [143] : 0x00 (0) > [144] : 0x6e (110) > [145] : 0x00 (0) > [146] : 0x61 (97) > [147] : 0x00 (0) > [148] : 0x67 (103) > [149] : 0x00 (0) > [150] : 0x65 (101) > [151] : 0x00 (0) > [152] : 0x61 (97) > [153] : 0x00 (0) > [154] : 0x62 (98) > [155] : 0x00 (0) > [156] : 0x6c (108) > [157] : 0x00 (0) > [158] : 0x65 (101) > [159] : 0x00 (0) > [160] : 0x29 (41) > [161] : 0x00 (0) > [162] : 0x00 (0) > [163] : 0x00 (0) > size : 0x000000a4 (164) >[2014/04/25 09:45:50.475863, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.476102, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON:Description] >[2014/04/25 09:45:50.476233, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.476393, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.476707, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000005-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.477090, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.477361, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.477598, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.477729, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.477852, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.478410, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > in: struct winreg_CreateKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000001-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0068 (104) > name_size : 0x0068 (104) > name : * > name : 'SYSTEM\CurrentControlSet\Services\NETLOGON\Security' > keyclass: struct winreg_String > name_len : 0x0002 (2) > name_size : 0x0002 (2) > name : * > name : '' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY > secdesc : NULL > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) >[2014/04/25 09:45:50.480166, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[1] [0000] 00 00 00 00 01 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.480431, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:785(_winreg_CreateKey) > _winreg_CreateKey called with parent key 'HKLM' and subkey name 'SYSTEM\CurrentControlSet\Services\NETLOGON\Security' >[2014/04/25 09:45:50.480566, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.480694, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.480821, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.480946, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.481077, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.481197, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.481351, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.481469, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.481689, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.481819, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.481942, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.482108, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.482227, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.482381, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.482499, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.482692, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.482822, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.482946, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.483067, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.483195, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.483338, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.483466, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.483582, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.483839, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.483973, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.484097, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [NETLOGON] >[2014/04/25 09:45:50.484218, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.484373, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] >[2014/04/25 09:45:50.484492, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] >[2014/04/25 09:45:50.484622, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.484738, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] >[2014/04/25 09:45:50.484926, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.485086, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Security] >[2014/04/25 09:45:50.485208, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.485363, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] >[2014/04/25 09:45:50.485482, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] >[2014/04/25 09:45:50.485613, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.485730, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] >[2014/04/25 09:45:50.485892, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.486023, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[3] [0000] 00 00 00 00 06 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.486254, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > out: struct winreg_CreateKey > new_handle : * > new_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000006-0000-0000-5a53-0e679c0f0000 > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) > result : WERR_OK >[2014/04/25 09:45:50.487000, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000006-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0012 (18) > name_size : 0x0012 (18) > name : * > name : 'Security' > type : REG_BINARY (3) > data : * > data: ARRAY(120) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x04 (4) > [3] : 0x80 (128) > [4] : 0x00 (0) > [5] : 0x00 (0) > [6] : 0x00 (0) > [7] : 0x00 (0) > [8] : 0x00 (0) > [9] : 0x00 (0) > [10] : 0x00 (0) > [11] : 0x00 (0) > [12] : 0x00 (0) > [13] : 0x00 (0) > [14] : 0x00 (0) > [15] : 0x00 (0) > [16] : 0x14 (20) > [17] : 0x00 (0) > [18] : 0x00 (0) > [19] : 0x00 (0) > [20] : 0x02 (2) > [21] : 0x00 (0) > [22] : 0x64 (100) > [23] : 0x00 (0) > [24] : 0x04 (4) > [25] : 0x00 (0) > [26] : 0x00 (0) > [27] : 0x00 (0) > [28] : 0x00 (0) > [29] : 0x00 (0) > [30] : 0x14 (20) > [31] : 0x00 (0) > [32] : 0x8d (141) > [33] : 0x01 (1) > [34] : 0x02 (2) > [35] : 0x00 (0) > [36] : 0x01 (1) > [37] : 0x01 (1) > [38] : 0x00 (0) > [39] : 0x00 (0) > [40] : 0x00 (0) > [41] : 0x00 (0) > [42] : 0x00 (0) > [43] : 0x01 (1) > [44] : 0x00 (0) > [45] : 0x00 (0) > [46] : 0x00 (0) > [47] : 0x00 (0) > [48] : 0x00 (0) > [49] : 0x00 (0) > [50] : 0x18 (24) > [51] : 0x00 (0) > [52] : 0xfd (253) > [53] : 0x01 (1) > [54] : 0x02 (2) > [55] : 0x00 (0) > [56] : 0x01 (1) > [57] : 0x02 (2) > [58] : 0x00 (0) > [59] : 0x00 (0) > [60] : 0x00 (0) > [61] : 0x00 (0) > [62] : 0x00 (0) > [63] : 0x05 (5) > [64] : 0x20 (32) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > [68] : 0x23 (35) > [69] : 0x02 (2) > [70] : 0x00 (0) > [71] : 0x00 (0) > [72] : 0x00 (0) > [73] : 0x00 (0) > [74] : 0x18 (24) > [75] : 0x00 (0) > [76] : 0xff (255) > [77] : 0x01 (1) > [78] : 0x0f (15) > [79] : 0x00 (0) > [80] : 0x01 (1) > [81] : 0x02 (2) > [82] : 0x00 (0) > [83] : 0x00 (0) > [84] : 0x00 (0) > [85] : 0x00 (0) > [86] : 0x00 (0) > [87] : 0x05 (5) > [88] : 0x20 (32) > [89] : 0x00 (0) > [90] : 0x00 (0) > [91] : 0x00 (0) > [92] : 0x25 (37) > [93] : 0x02 (2) > [94] : 0x00 (0) > [95] : 0x00 (0) > [96] : 0x00 (0) > [97] : 0x00 (0) > [98] : 0x18 (24) > [99] : 0x00 (0) > [100] : 0xff (255) > [101] : 0x01 (1) > [102] : 0x0f (15) > [103] : 0x00 (0) > [104] : 0x01 (1) > [105] : 0x02 (2) > [106] : 0x00 (0) > [107] : 0x00 (0) > [108] : 0x00 (0) > [109] : 0x00 (0) > [110] : 0x00 (0) > [111] : 0x05 (5) > [112] : 0x20 (32) > [113] : 0x00 (0) > [114] : 0x00 (0) > [115] : 0x00 (0) > [116] : 0x20 (32) > [117] : 0x02 (2) > [118] : 0x00 (0) > [119] : 0x00 (0) > size : 0x00000078 (120) >[2014/04/25 09:45:50.494108, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.494369, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security:Security] >[2014/04/25 09:45:50.494505, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.494631, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security' (ops 0xe3cbc0) >[2014/04/25 09:45:50.494757, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] >[2014/04/25 09:45:50.494939, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[Security] len[120] >[2014/04/25 09:45:50.495070, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.495401, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000006-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.495849, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.496087, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.496350, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.496472, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.496594, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.497142, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > in: struct winreg_CreateKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000001-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0062 (98) > name_size : 0x0062 (98) > name : * > name : 'SYSTEM\CurrentControlSet\Services\RemoteRegistry' > keyclass: struct winreg_String > name_len : 0x0002 (2) > name_size : 0x0002 (2) > name : * > name : '' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY > secdesc : NULL > action_taken : * > action_taken : REG_ACTION_NONE (0) >[2014/04/25 09:45:50.498881, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[1] [0000] 00 00 00 00 01 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.499112, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:785(_winreg_CreateKey) > _winreg_CreateKey called with parent key 'HKLM' and subkey name 'SYSTEM\CurrentControlSet\Services\RemoteRegistry' >[2014/04/25 09:45:50.499307, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.499437, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.499562, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.499687, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.499817, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.499938, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.500065, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.500182, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.500412, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.500538, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.500661, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.500788, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.500905, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.501029, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.501143, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.501354, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.501482, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.501605, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.501724, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.501850, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.501966, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.502124, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.502242, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.502520, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.502647, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [RemoteRegistry] >[2014/04/25 09:45:50.502769, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.502897, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.503014, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.503140, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.503257, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.503458, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.503590, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[3] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.503822, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > out: struct winreg_CreateKey > new_handle : * > new_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) > result : WERR_OK >[2014/04/25 09:45:50.504520, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x000c (12) > name_size : 0x000c (12) > name : * > name : 'Start' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x02 (2) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.505571, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.505854, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:Start] >[2014/04/25 09:45:50.505987, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.506112, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry' (ops 0xe3cbc0) >[2014/04/25 09:45:50.506237, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.506439, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[Start] len[4] >[2014/04/25 09:45:50.506574, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[1]: name[Type] len[4] >[2014/04/25 09:45:50.506703, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[2]: name[ErrorControl] len[4] >[2014/04/25 09:45:50.506829, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[3]: name[ObjectName] len[24] >[2014/04/25 09:45:50.506956, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[4]: name[DisplayName] len[48] >[2014/04/25 09:45:50.507083, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[5]: name[ImagePath] len[68] >[2014/04/25 09:45:50.507211, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[6]: name[Description] len[126] >[2014/04/25 09:45:50.507364, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.507693, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x000a (10) > name_size : 0x000a (10) > name : * > name : 'Type' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x10 (16) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.508734, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.509016, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:Type] >[2014/04/25 09:45:50.509146, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.509300, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.509610, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x001a (26) > name_size : 0x001a (26) > name : * > name : 'ErrorControl' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.510644, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.510879, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:ErrorControl] >[2014/04/25 09:45:50.511006, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.511133, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.511472, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0016 (22) > name_size : 0x0016 (22) > name : * > name : 'ObjectName' > type : REG_SZ (1) > data : * > data: ARRAY(24) > [0] : 0x4c (76) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x63 (99) > [5] : 0x00 (0) > [6] : 0x61 (97) > [7] : 0x00 (0) > [8] : 0x6c (108) > [9] : 0x00 (0) > [10] : 0x53 (83) > [11] : 0x00 (0) > [12] : 0x79 (121) > [13] : 0x00 (0) > [14] : 0x73 (115) > [15] : 0x00 (0) > [16] : 0x74 (116) > [17] : 0x00 (0) > [18] : 0x65 (101) > [19] : 0x00 (0) > [20] : 0x6d (109) > [21] : 0x00 (0) > [22] : 0x00 (0) > [23] : 0x00 (0) > size : 0x00000018 (24) >[2014/04/25 09:45:50.513601, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.513839, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:ObjectName] >[2014/04/25 09:45:50.513969, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.514100, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.514454, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'DisplayName' > type : REG_SZ (1) > data : * > data: ARRAY(48) > [0] : 0x52 (82) > [1] : 0x00 (0) > [2] : 0x65 (101) > [3] : 0x00 (0) > [4] : 0x6d (109) > [5] : 0x00 (0) > [6] : 0x6f (111) > [7] : 0x00 (0) > [8] : 0x74 (116) > [9] : 0x00 (0) > [10] : 0x65 (101) > [11] : 0x00 (0) > [12] : 0x20 (32) > [13] : 0x00 (0) > [14] : 0x52 (82) > [15] : 0x00 (0) > [16] : 0x65 (101) > [17] : 0x00 (0) > [18] : 0x67 (103) > [19] : 0x00 (0) > [20] : 0x69 (105) > [21] : 0x00 (0) > [22] : 0x73 (115) > [23] : 0x00 (0) > [24] : 0x74 (116) > [25] : 0x00 (0) > [26] : 0x72 (114) > [27] : 0x00 (0) > [28] : 0x79 (121) > [29] : 0x00 (0) > [30] : 0x20 (32) > [31] : 0x00 (0) > [32] : 0x53 (83) > [33] : 0x00 (0) > [34] : 0x65 (101) > [35] : 0x00 (0) > [36] : 0x72 (114) > [37] : 0x00 (0) > [38] : 0x76 (118) > [39] : 0x00 (0) > [40] : 0x69 (105) > [41] : 0x00 (0) > [42] : 0x63 (99) > [43] : 0x00 (0) > [44] : 0x65 (101) > [45] : 0x00 (0) > [46] : 0x00 (0) > [47] : 0x00 (0) > size : 0x00000030 (48) >[2014/04/25 09:45:50.517767, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.518009, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:DisplayName] >[2014/04/25 09:45:50.518141, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.518296, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.518620, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0014 (20) > name_size : 0x0014 (20) > name : * > name : 'ImagePath' > type : REG_SZ (1) > data : * > data: ARRAY(68) > [0] : 0x2f (47) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x70 (112) > [5] : 0x00 (0) > [6] : 0x74 (116) > [7] : 0x00 (0) > [8] : 0x2f (47) > [9] : 0x00 (0) > [10] : 0x6c (108) > [11] : 0x00 (0) > [12] : 0x6f (111) > [13] : 0x00 (0) > [14] : 0x63 (99) > [15] : 0x00 (0) > [16] : 0x61 (97) > [17] : 0x00 (0) > [18] : 0x6c (108) > [19] : 0x00 (0) > [20] : 0x2f (47) > [21] : 0x00 (0) > [22] : 0x73 (115) > [23] : 0x00 (0) > [24] : 0x61 (97) > [25] : 0x00 (0) > [26] : 0x6d (109) > [27] : 0x00 (0) > [28] : 0x62 (98) > [29] : 0x00 (0) > [30] : 0x61 (97) > [31] : 0x00 (0) > [32] : 0x34 (52) > [33] : 0x00 (0) > [34] : 0x2f (47) > [35] : 0x00 (0) > [36] : 0x6c (108) > [37] : 0x00 (0) > [38] : 0x69 (105) > [39] : 0x00 (0) > [40] : 0x62 (98) > [41] : 0x00 (0) > [42] : 0x2f (47) > [43] : 0x00 (0) > [44] : 0x73 (115) > [45] : 0x00 (0) > [46] : 0x76 (118) > [47] : 0x00 (0) > [48] : 0x63 (99) > [49] : 0x00 (0) > [50] : 0x63 (99) > [51] : 0x00 (0) > [52] : 0x74 (116) > [53] : 0x00 (0) > [54] : 0x6c (108) > [55] : 0x00 (0) > [56] : 0x2f (47) > [57] : 0x00 (0) > [58] : 0x73 (115) > [59] : 0x00 (0) > [60] : 0x6d (109) > [61] : 0x00 (0) > [62] : 0x62 (98) > [63] : 0x00 (0) > [64] : 0x64 (100) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > size : 0x00000044 (68) >[2014/04/25 09:45:50.523009, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.523307, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:ImagePath] >[2014/04/25 09:45:50.523441, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.523576, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.523897, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'Description' > type : REG_SZ (1) > data : * > data: ARRAY(126) > [0] : 0x49 (73) > [1] : 0x00 (0) > [2] : 0x6e (110) > [3] : 0x00 (0) > [4] : 0x74 (116) > [5] : 0x00 (0) > [6] : 0x65 (101) > [7] : 0x00 (0) > [8] : 0x72 (114) > [9] : 0x00 (0) > [10] : 0x6e (110) > [11] : 0x00 (0) > [12] : 0x61 (97) > [13] : 0x00 (0) > [14] : 0x6c (108) > [15] : 0x00 (0) > [16] : 0x20 (32) > [17] : 0x00 (0) > [18] : 0x73 (115) > [19] : 0x00 (0) > [20] : 0x65 (101) > [21] : 0x00 (0) > [22] : 0x72 (114) > [23] : 0x00 (0) > [24] : 0x76 (118) > [25] : 0x00 (0) > [26] : 0x69 (105) > [27] : 0x00 (0) > [28] : 0x63 (99) > [29] : 0x00 (0) > [30] : 0x65 (101) > [31] : 0x00 (0) > [32] : 0x20 (32) > [33] : 0x00 (0) > [34] : 0x70 (112) > [35] : 0x00 (0) > [36] : 0x72 (114) > [37] : 0x00 (0) > [38] : 0x6f (111) > [39] : 0x00 (0) > [40] : 0x76 (118) > [41] : 0x00 (0) > [42] : 0x69 (105) > [43] : 0x00 (0) > [44] : 0x64 (100) > [45] : 0x00 (0) > [46] : 0x69 (105) > [47] : 0x00 (0) > [48] : 0x6e (110) > [49] : 0x00 (0) > [50] : 0x67 (103) > [51] : 0x00 (0) > [52] : 0x20 (32) > [53] : 0x00 (0) > [54] : 0x72 (114) > [55] : 0x00 (0) > [56] : 0x65 (101) > [57] : 0x00 (0) > [58] : 0x6d (109) > [59] : 0x00 (0) > [60] : 0x6f (111) > [61] : 0x00 (0) > [62] : 0x74 (116) > [63] : 0x00 (0) > [64] : 0x65 (101) > [65] : 0x00 (0) > [66] : 0x20 (32) > [67] : 0x00 (0) > [68] : 0x61 (97) > [69] : 0x00 (0) > [70] : 0x63 (99) > [71] : 0x00 (0) > [72] : 0x63 (99) > [73] : 0x00 (0) > [74] : 0x65 (101) > [75] : 0x00 (0) > [76] : 0x73 (115) > [77] : 0x00 (0) > [78] : 0x73 (115) > [79] : 0x00 (0) > [80] : 0x20 (32) > [81] : 0x00 (0) > [82] : 0x74 (116) > [83] : 0x00 (0) > [84] : 0x6f (111) > [85] : 0x00 (0) > [86] : 0x20 (32) > [87] : 0x00 (0) > [88] : 0x74 (116) > [89] : 0x00 (0) > [90] : 0x68 (104) > [91] : 0x00 (0) > [92] : 0x65 (101) > [93] : 0x00 (0) > [94] : 0x20 (32) > [95] : 0x00 (0) > [96] : 0x53 (83) > [97] : 0x00 (0) > [98] : 0x61 (97) > [99] : 0x00 (0) > [100] : 0x6d (109) > [101] : 0x00 (0) > [102] : 0x62 (98) > [103] : 0x00 (0) > [104] : 0x61 (97) > [105] : 0x00 (0) > [106] : 0x20 (32) > [107] : 0x00 (0) > [108] : 0x72 (114) > [109] : 0x00 (0) > [110] : 0x65 (101) > [111] : 0x00 (0) > [112] : 0x67 (103) > [113] : 0x00 (0) > [114] : 0x69 (105) > [115] : 0x00 (0) > [116] : 0x73 (115) > [117] : 0x00 (0) > [118] : 0x74 (116) > [119] : 0x00 (0) > [120] : 0x72 (114) > [121] : 0x00 (0) > [122] : 0x79 (121) > [123] : 0x00 (0) > [124] : 0x00 (0) > [125] : 0x00 (0) > size : 0x0000007e (126) >[2014/04/25 09:45:50.531314, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.531550, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry:Description] >[2014/04/25 09:45:50.531680, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.531816, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.532127, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000007-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.532541, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.532778, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.533010, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.533138, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.533284, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.533817, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > in: struct winreg_CreateKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000001-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0074 (116) > name_size : 0x0074 (116) > name : * > name : 'SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security' > keyclass: struct winreg_String > name_len : 0x0002 (2) > name_size : 0x0002 (2) > name : * > name : '' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY > secdesc : NULL > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) >[2014/04/25 09:45:50.535613, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[1] [0000] 00 00 00 00 01 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.535849, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:785(_winreg_CreateKey) > _winreg_CreateKey called with parent key 'HKLM' and subkey name 'SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security' >[2014/04/25 09:45:50.535983, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.536110, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.536237, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.536387, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.536521, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.536641, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.536769, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.536886, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.537100, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.537227, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.537414, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.537546, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.537664, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.537790, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.537906, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.538097, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.538226, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.538374, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.538495, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.538623, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.538741, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.538868, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.538985, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.539234, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.539388, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.539512, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [RemoteRegistry] >[2014/04/25 09:45:50.539634, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.539763, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.539881, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.540009, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.540126, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] >[2014/04/25 09:45:50.540377, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.540504, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Security] >[2014/04/25 09:45:50.540625, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.540752, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] >[2014/04/25 09:45:50.540871, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] >[2014/04/25 09:45:50.540998, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.541114, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] >[2014/04/25 09:45:50.541298, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.541430, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[3] [0000] 00 00 00 00 08 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.541666, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > out: struct winreg_CreateKey > new_handle : * > new_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000008-0000-0000-5a53-0e679c0f0000 > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) > result : WERR_OK >[2014/04/25 09:45:50.542417, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000008-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0012 (18) > name_size : 0x0012 (18) > name : * > name : 'Security' > type : REG_BINARY (3) > data : * > data: ARRAY(120) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x04 (4) > [3] : 0x80 (128) > [4] : 0x00 (0) > [5] : 0x00 (0) > [6] : 0x00 (0) > [7] : 0x00 (0) > [8] : 0x00 (0) > [9] : 0x00 (0) > [10] : 0x00 (0) > [11] : 0x00 (0) > [12] : 0x00 (0) > [13] : 0x00 (0) > [14] : 0x00 (0) > [15] : 0x00 (0) > [16] : 0x14 (20) > [17] : 0x00 (0) > [18] : 0x00 (0) > [19] : 0x00 (0) > [20] : 0x02 (2) > [21] : 0x00 (0) > [22] : 0x64 (100) > [23] : 0x00 (0) > [24] : 0x04 (4) > [25] : 0x00 (0) > [26] : 0x00 (0) > [27] : 0x00 (0) > [28] : 0x00 (0) > [29] : 0x00 (0) > [30] : 0x14 (20) > [31] : 0x00 (0) > [32] : 0x8d (141) > [33] : 0x01 (1) > [34] : 0x02 (2) > [35] : 0x00 (0) > [36] : 0x01 (1) > [37] : 0x01 (1) > [38] : 0x00 (0) > [39] : 0x00 (0) > [40] : 0x00 (0) > [41] : 0x00 (0) > [42] : 0x00 (0) > [43] : 0x01 (1) > [44] : 0x00 (0) > [45] : 0x00 (0) > [46] : 0x00 (0) > [47] : 0x00 (0) > [48] : 0x00 (0) > [49] : 0x00 (0) > [50] : 0x18 (24) > [51] : 0x00 (0) > [52] : 0xfd (253) > [53] : 0x01 (1) > [54] : 0x02 (2) > [55] : 0x00 (0) > [56] : 0x01 (1) > [57] : 0x02 (2) > [58] : 0x00 (0) > [59] : 0x00 (0) > [60] : 0x00 (0) > [61] : 0x00 (0) > [62] : 0x00 (0) > [63] : 0x05 (5) > [64] : 0x20 (32) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > [68] : 0x23 (35) > [69] : 0x02 (2) > [70] : 0x00 (0) > [71] : 0x00 (0) > [72] : 0x00 (0) > [73] : 0x00 (0) > [74] : 0x18 (24) > [75] : 0x00 (0) > [76] : 0xff (255) > [77] : 0x01 (1) > [78] : 0x0f (15) > [79] : 0x00 (0) > [80] : 0x01 (1) > [81] : 0x02 (2) > [82] : 0x00 (0) > [83] : 0x00 (0) > [84] : 0x00 (0) > [85] : 0x00 (0) > [86] : 0x00 (0) > [87] : 0x05 (5) > [88] : 0x20 (32) > [89] : 0x00 (0) > [90] : 0x00 (0) > [91] : 0x00 (0) > [92] : 0x25 (37) > [93] : 0x02 (2) > [94] : 0x00 (0) > [95] : 0x00 (0) > [96] : 0x00 (0) > [97] : 0x00 (0) > [98] : 0x18 (24) > [99] : 0x00 (0) > [100] : 0xff (255) > [101] : 0x01 (1) > [102] : 0x0f (15) > [103] : 0x00 (0) > [104] : 0x01 (1) > [105] : 0x02 (2) > [106] : 0x00 (0) > [107] : 0x00 (0) > [108] : 0x00 (0) > [109] : 0x00 (0) > [110] : 0x00 (0) > [111] : 0x05 (5) > [112] : 0x20 (32) > [113] : 0x00 (0) > [114] : 0x00 (0) > [115] : 0x00 (0) > [116] : 0x20 (32) > [117] : 0x02 (2) > [118] : 0x00 (0) > [119] : 0x00 (0) > size : 0x00000078 (120) >[2014/04/25 09:45:50.549516, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.549754, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security:Security] >[2014/04/25 09:45:50.549891, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.550017, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security' (ops 0xe3cbc0) >[2014/04/25 09:45:50.550142, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] >[2014/04/25 09:45:50.550348, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[Security] len[120] >[2014/04/25 09:45:50.550481, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.550785, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000008-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.551209, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.551477, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.551714, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.551837, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.551959, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.552527, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > in: struct winreg_CreateKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000001-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x004e (78) > name_size : 0x004e (78) > name : * > name : 'SYSTEM\CurrentControlSet\Services\WINS' > keyclass: struct winreg_String > name_len : 0x0002 (2) > name_size : 0x0002 (2) > name : * > name : '' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY > secdesc : NULL > action_taken : * > action_taken : REG_ACTION_NONE (0) >[2014/04/25 09:45:50.554234, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[1] [0000] 00 00 00 00 01 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.554494, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:785(_winreg_CreateKey) > _winreg_CreateKey called with parent key 'HKLM' and subkey name 'SYSTEM\CurrentControlSet\Services\WINS' >[2014/04/25 09:45:50.554659, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.554786, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.554911, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.555035, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.555165, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.555310, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.555437, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.555554, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.555760, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.555888, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.556011, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.556136, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.556252, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.556403, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.556519, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.556706, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.556833, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.556956, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.557077, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.557203, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.557345, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.557508, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.557624, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.557874, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.558000, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [WINS] >[2014/04/25 09:45:50.558120, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.558247, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.558392, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.558519, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.558634, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.558806, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.558939, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[3] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.559173, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > out: struct winreg_CreateKey > new_handle : * > new_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) > result : WERR_OK >[2014/04/25 09:45:50.559877, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x000c (12) > name_size : 0x000c (12) > name : * > name : 'Start' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x02 (2) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.560977, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.561216, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:Start] >[2014/04/25 09:45:50.561375, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.561500, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\WINS' (ops 0xe3cbc0) >[2014/04/25 09:45:50.561624, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.561803, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[Start] len[4] >[2014/04/25 09:45:50.561935, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[1]: name[Type] len[4] >[2014/04/25 09:45:50.562063, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[2]: name[ErrorControl] len[4] >[2014/04/25 09:45:50.562191, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[3]: name[ObjectName] len[24] >[2014/04/25 09:45:50.562345, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[4]: name[DisplayName] len[74] >[2014/04/25 09:45:50.562474, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[5]: name[ImagePath] len[68] >[2014/04/25 09:45:50.562604, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[6]: name[Description] len[178] >[2014/04/25 09:45:50.562732, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.563053, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x000a (10) > name_size : 0x000a (10) > name : * > name : 'Type' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x10 (16) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.564137, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.564400, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:Type] >[2014/04/25 09:45:50.564528, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.564661, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.564968, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x001a (26) > name_size : 0x001a (26) > name : * > name : 'ErrorControl' > type : REG_DWORD (4) > data : * > data: ARRAY(4) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x00 (0) > [3] : 0x00 (0) > size : 0x00000004 (4) >[2014/04/25 09:45:50.566003, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.566241, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:ErrorControl] >[2014/04/25 09:45:50.566396, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.566525, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.566834, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0016 (22) > name_size : 0x0016 (22) > name : * > name : 'ObjectName' > type : REG_SZ (1) > data : * > data: ARRAY(24) > [0] : 0x4c (76) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x63 (99) > [5] : 0x00 (0) > [6] : 0x61 (97) > [7] : 0x00 (0) > [8] : 0x6c (108) > [9] : 0x00 (0) > [10] : 0x53 (83) > [11] : 0x00 (0) > [12] : 0x79 (121) > [13] : 0x00 (0) > [14] : 0x73 (115) > [15] : 0x00 (0) > [16] : 0x74 (116) > [17] : 0x00 (0) > [18] : 0x65 (101) > [19] : 0x00 (0) > [20] : 0x6d (109) > [21] : 0x00 (0) > [22] : 0x00 (0) > [23] : 0x00 (0) > size : 0x00000018 (24) >[2014/04/25 09:45:50.568931, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.569168, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:ObjectName] >[2014/04/25 09:45:50.569322, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.569453, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.569782, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'DisplayName' > type : REG_SZ (1) > data : * > data: ARRAY(74) > [0] : 0x57 (87) > [1] : 0x00 (0) > [2] : 0x69 (105) > [3] : 0x00 (0) > [4] : 0x6e (110) > [5] : 0x00 (0) > [6] : 0x64 (100) > [7] : 0x00 (0) > [8] : 0x6f (111) > [9] : 0x00 (0) > [10] : 0x77 (119) > [11] : 0x00 (0) > [12] : 0x73 (115) > [13] : 0x00 (0) > [14] : 0x20 (32) > [15] : 0x00 (0) > [16] : 0x49 (73) > [17] : 0x00 (0) > [18] : 0x6e (110) > [19] : 0x00 (0) > [20] : 0x74 (116) > [21] : 0x00 (0) > [22] : 0x65 (101) > [23] : 0x00 (0) > [24] : 0x72 (114) > [25] : 0x00 (0) > [26] : 0x6e (110) > [27] : 0x00 (0) > [28] : 0x65 (101) > [29] : 0x00 (0) > [30] : 0x74 (116) > [31] : 0x00 (0) > [32] : 0x20 (32) > [33] : 0x00 (0) > [34] : 0x4e (78) > [35] : 0x00 (0) > [36] : 0x61 (97) > [37] : 0x00 (0) > [38] : 0x6d (109) > [39] : 0x00 (0) > [40] : 0x65 (101) > [41] : 0x00 (0) > [42] : 0x20 (32) > [43] : 0x00 (0) > [44] : 0x53 (83) > [45] : 0x00 (0) > [46] : 0x65 (101) > [47] : 0x00 (0) > [48] : 0x72 (114) > [49] : 0x00 (0) > [50] : 0x76 (118) > [51] : 0x00 (0) > [52] : 0x69 (105) > [53] : 0x00 (0) > [54] : 0x63 (99) > [55] : 0x00 (0) > [56] : 0x65 (101) > [57] : 0x00 (0) > [58] : 0x20 (32) > [59] : 0x00 (0) > [60] : 0x28 (40) > [61] : 0x00 (0) > [62] : 0x57 (87) > [63] : 0x00 (0) > [64] : 0x49 (73) > [65] : 0x00 (0) > [66] : 0x4e (78) > [67] : 0x00 (0) > [68] : 0x53 (83) > [69] : 0x00 (0) > [70] : 0x29 (41) > [71] : 0x00 (0) > [72] : 0x00 (0) > [73] : 0x00 (0) > size : 0x0000004a (74) >[2014/04/25 09:45:50.574477, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.574717, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:DisplayName] >[2014/04/25 09:45:50.574879, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.575014, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.575371, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0014 (20) > name_size : 0x0014 (20) > name : * > name : 'ImagePath' > type : REG_SZ (1) > data : * > data: ARRAY(68) > [0] : 0x2f (47) > [1] : 0x00 (0) > [2] : 0x6f (111) > [3] : 0x00 (0) > [4] : 0x70 (112) > [5] : 0x00 (0) > [6] : 0x74 (116) > [7] : 0x00 (0) > [8] : 0x2f (47) > [9] : 0x00 (0) > [10] : 0x6c (108) > [11] : 0x00 (0) > [12] : 0x6f (111) > [13] : 0x00 (0) > [14] : 0x63 (99) > [15] : 0x00 (0) > [16] : 0x61 (97) > [17] : 0x00 (0) > [18] : 0x6c (108) > [19] : 0x00 (0) > [20] : 0x2f (47) > [21] : 0x00 (0) > [22] : 0x73 (115) > [23] : 0x00 (0) > [24] : 0x61 (97) > [25] : 0x00 (0) > [26] : 0x6d (109) > [27] : 0x00 (0) > [28] : 0x62 (98) > [29] : 0x00 (0) > [30] : 0x61 (97) > [31] : 0x00 (0) > [32] : 0x34 (52) > [33] : 0x00 (0) > [34] : 0x2f (47) > [35] : 0x00 (0) > [36] : 0x6c (108) > [37] : 0x00 (0) > [38] : 0x69 (105) > [39] : 0x00 (0) > [40] : 0x62 (98) > [41] : 0x00 (0) > [42] : 0x2f (47) > [43] : 0x00 (0) > [44] : 0x73 (115) > [45] : 0x00 (0) > [46] : 0x76 (118) > [47] : 0x00 (0) > [48] : 0x63 (99) > [49] : 0x00 (0) > [50] : 0x63 (99) > [51] : 0x00 (0) > [52] : 0x74 (116) > [53] : 0x00 (0) > [54] : 0x6c (108) > [55] : 0x00 (0) > [56] : 0x2f (47) > [57] : 0x00 (0) > [58] : 0x6e (110) > [59] : 0x00 (0) > [60] : 0x6d (109) > [61] : 0x00 (0) > [62] : 0x62 (98) > [63] : 0x00 (0) > [64] : 0x64 (100) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > size : 0x00000044 (68) >[2014/04/25 09:45:50.579770, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.580013, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:ImagePath] >[2014/04/25 09:45:50.580144, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.580300, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.580627, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0018 (24) > name_size : 0x0018 (24) > name : * > name : 'Description' > type : REG_SZ (1) > data : * > data: ARRAY(178) > [0] : 0x49 (73) > [1] : 0x00 (0) > [2] : 0x6e (110) > [3] : 0x00 (0) > [4] : 0x74 (116) > [5] : 0x00 (0) > [6] : 0x65 (101) > [7] : 0x00 (0) > [8] : 0x72 (114) > [9] : 0x00 (0) > [10] : 0x6e (110) > [11] : 0x00 (0) > [12] : 0x61 (97) > [13] : 0x00 (0) > [14] : 0x6c (108) > [15] : 0x00 (0) > [16] : 0x20 (32) > [17] : 0x00 (0) > [18] : 0x73 (115) > [19] : 0x00 (0) > [20] : 0x65 (101) > [21] : 0x00 (0) > [22] : 0x72 (114) > [23] : 0x00 (0) > [24] : 0x76 (118) > [25] : 0x00 (0) > [26] : 0x69 (105) > [27] : 0x00 (0) > [28] : 0x63 (99) > [29] : 0x00 (0) > [30] : 0x65 (101) > [31] : 0x00 (0) > [32] : 0x20 (32) > [33] : 0x00 (0) > [34] : 0x70 (112) > [35] : 0x00 (0) > [36] : 0x72 (114) > [37] : 0x00 (0) > [38] : 0x6f (111) > [39] : 0x00 (0) > [40] : 0x76 (118) > [41] : 0x00 (0) > [42] : 0x69 (105) > [43] : 0x00 (0) > [44] : 0x64 (100) > [45] : 0x00 (0) > [46] : 0x69 (105) > [47] : 0x00 (0) > [48] : 0x6e (110) > [49] : 0x00 (0) > [50] : 0x67 (103) > [51] : 0x00 (0) > [52] : 0x20 (32) > [53] : 0x00 (0) > [54] : 0x61 (97) > [55] : 0x00 (0) > [56] : 0x20 (32) > [57] : 0x00 (0) > [58] : 0x4e (78) > [59] : 0x00 (0) > [60] : 0x65 (101) > [61] : 0x00 (0) > [62] : 0x74 (116) > [63] : 0x00 (0) > [64] : 0x42 (66) > [65] : 0x00 (0) > [66] : 0x49 (73) > [67] : 0x00 (0) > [68] : 0x4f (79) > [69] : 0x00 (0) > [70] : 0x53 (83) > [71] : 0x00 (0) > [72] : 0x20 (32) > [73] : 0x00 (0) > [74] : 0x70 (112) > [75] : 0x00 (0) > [76] : 0x6f (111) > [77] : 0x00 (0) > [78] : 0x69 (105) > [79] : 0x00 (0) > [80] : 0x6e (110) > [81] : 0x00 (0) > [82] : 0x74 (116) > [83] : 0x00 (0) > [84] : 0x2d (45) > [85] : 0x00 (0) > [86] : 0x74 (116) > [87] : 0x00 (0) > [88] : 0x6f (111) > [89] : 0x00 (0) > [90] : 0x2d (45) > [91] : 0x00 (0) > [92] : 0x70 (112) > [93] : 0x00 (0) > [94] : 0x6f (111) > [95] : 0x00 (0) > [96] : 0x69 (105) > [97] : 0x00 (0) > [98] : 0x6e (110) > [99] : 0x00 (0) > [100] : 0x74 (116) > [101] : 0x00 (0) > [102] : 0x20 (32) > [103] : 0x00 (0) > [104] : 0x6e (110) > [105] : 0x00 (0) > [106] : 0x61 (97) > [107] : 0x00 (0) > [108] : 0x6d (109) > [109] : 0x00 (0) > [110] : 0x65 (101) > [111] : 0x00 (0) > [112] : 0x20 (32) > [113] : 0x00 (0) > [114] : 0x73 (115) > [115] : 0x00 (0) > [116] : 0x65 (101) > [117] : 0x00 (0) > [118] : 0x72 (114) > [119] : 0x00 (0) > [120] : 0x76 (118) > [121] : 0x00 (0) > [122] : 0x65 (101) > [123] : 0x00 (0) > [124] : 0x72 (114) > [125] : 0x00 (0) > [126] : 0x28 (40) > [127] : 0x00 (0) > [128] : 0x6e (110) > [129] : 0x00 (0) > [130] : 0x6f (111) > [131] : 0x00 (0) > [132] : 0x74 (116) > [133] : 0x00 (0) > [134] : 0x20 (32) > [135] : 0x00 (0) > [136] : 0x72 (114) > [137] : 0x00 (0) > [138] : 0x65 (101) > [139] : 0x00 (0) > [140] : 0x6d (109) > [141] : 0x00 (0) > [142] : 0x6f (111) > [143] : 0x00 (0) > [144] : 0x74 (116) > [145] : 0x00 (0) > [146] : 0x65 (101) > [147] : 0x00 (0) > [148] : 0x6c (108) > [149] : 0x00 (0) > [150] : 0x79 (121) > [151] : 0x00 (0) > [152] : 0x20 (32) > [153] : 0x00 (0) > [154] : 0x6d (109) > [155] : 0x00 (0) > [156] : 0x61 (97) > [157] : 0x00 (0) > [158] : 0x6e (110) > [159] : 0x00 (0) > [160] : 0x61 (97) > [161] : 0x00 (0) > [162] : 0x67 (103) > [163] : 0x00 (0) > [164] : 0x65 (101) > [165] : 0x00 (0) > [166] : 0x61 (97) > [167] : 0x00 (0) > [168] : 0x62 (98) > [169] : 0x00 (0) > [170] : 0x6c (108) > [171] : 0x00 (0) > [172] : 0x65 (101) > [173] : 0x00 (0) > [174] : 0x29 (41) > [175] : 0x00 (0) > [176] : 0x00 (0) > [177] : 0x00 (0) > size : 0x000000b2 (178) >[2014/04/25 09:45:50.591979, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.592292, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS:Description] >[2014/04/25 09:45:50.592444, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.592601, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.593000, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000009-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.593468, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.593707, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.593940, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.594072, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.594196, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.594784, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > in: struct winreg_CreateKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000001-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0060 (96) > name_size : 0x0060 (96) > name : * > name : 'SYSTEM\CurrentControlSet\Services\WINS\Security' > keyclass: struct winreg_String > name_len : 0x0002 (2) > name_size : 0x0002 (2) > name : * > name : '' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY > secdesc : NULL > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) >[2014/04/25 09:45:50.596546, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[1] [0000] 00 00 00 00 01 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.596786, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:785(_winreg_CreateKey) > _winreg_CreateKey called with parent key 'HKLM' and subkey name 'SYSTEM\CurrentControlSet\Services\WINS\Security' >[2014/04/25 09:45:50.596963, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.597092, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.597221, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.597373, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.597505, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.597626, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.597757, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.597875, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.598094, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.598223, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.598374, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.598504, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.598622, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.598748, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.598866, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.599055, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.599185, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.599334, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.599455, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.599583, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.599701, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.599863, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.599981, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.600238, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.600394, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 2 >[2014/04/25 09:45:50.600519, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [WINS] >[2014/04/25 09:45:50.600641, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.600770, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.600887, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.601016, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.601134, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\WINS] >[2014/04/25 09:45:50.601332, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.601459, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Security] >[2014/04/25 09:45:50.601579, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.601708, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] >[2014/04/25 09:45:50.601826, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] >[2014/04/25 09:45:50.601953, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.602072, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] >[2014/04/25 09:45:50.602233, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.602390, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[3] [0000] 00 00 00 00 0A 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.602626, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CreateKey: struct winreg_CreateKey > out: struct winreg_CreateKey > new_handle : * > new_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000a-0000-0000-5a53-0e679c0f0000 > action_taken : * > action_taken : REG_OPENED_EXISTING_KEY (2) > result : WERR_OK >[2014/04/25 09:45:50.603420, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > in: struct winreg_SetValue > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000a-0000-0000-5a53-0e679c0f0000 > name: struct winreg_String > name_len : 0x0012 (18) > name_size : 0x0012 (18) > name : * > name : 'Security' > type : REG_BINARY (3) > data : * > data: ARRAY(120) > [0] : 0x01 (1) > [1] : 0x00 (0) > [2] : 0x04 (4) > [3] : 0x80 (128) > [4] : 0x00 (0) > [5] : 0x00 (0) > [6] : 0x00 (0) > [7] : 0x00 (0) > [8] : 0x00 (0) > [9] : 0x00 (0) > [10] : 0x00 (0) > [11] : 0x00 (0) > [12] : 0x00 (0) > [13] : 0x00 (0) > [14] : 0x00 (0) > [15] : 0x00 (0) > [16] : 0x14 (20) > [17] : 0x00 (0) > [18] : 0x00 (0) > [19] : 0x00 (0) > [20] : 0x02 (2) > [21] : 0x00 (0) > [22] : 0x64 (100) > [23] : 0x00 (0) > [24] : 0x04 (4) > [25] : 0x00 (0) > [26] : 0x00 (0) > [27] : 0x00 (0) > [28] : 0x00 (0) > [29] : 0x00 (0) > [30] : 0x14 (20) > [31] : 0x00 (0) > [32] : 0x8d (141) > [33] : 0x01 (1) > [34] : 0x02 (2) > [35] : 0x00 (0) > [36] : 0x01 (1) > [37] : 0x01 (1) > [38] : 0x00 (0) > [39] : 0x00 (0) > [40] : 0x00 (0) > [41] : 0x00 (0) > [42] : 0x00 (0) > [43] : 0x01 (1) > [44] : 0x00 (0) > [45] : 0x00 (0) > [46] : 0x00 (0) > [47] : 0x00 (0) > [48] : 0x00 (0) > [49] : 0x00 (0) > [50] : 0x18 (24) > [51] : 0x00 (0) > [52] : 0xfd (253) > [53] : 0x01 (1) > [54] : 0x02 (2) > [55] : 0x00 (0) > [56] : 0x01 (1) > [57] : 0x02 (2) > [58] : 0x00 (0) > [59] : 0x00 (0) > [60] : 0x00 (0) > [61] : 0x00 (0) > [62] : 0x00 (0) > [63] : 0x05 (5) > [64] : 0x20 (32) > [65] : 0x00 (0) > [66] : 0x00 (0) > [67] : 0x00 (0) > [68] : 0x23 (35) > [69] : 0x02 (2) > [70] : 0x00 (0) > [71] : 0x00 (0) > [72] : 0x00 (0) > [73] : 0x00 (0) > [74] : 0x18 (24) > [75] : 0x00 (0) > [76] : 0xff (255) > [77] : 0x01 (1) > [78] : 0x0f (15) > [79] : 0x00 (0) > [80] : 0x01 (1) > [81] : 0x02 (2) > [82] : 0x00 (0) > [83] : 0x00 (0) > [84] : 0x00 (0) > [85] : 0x00 (0) > [86] : 0x00 (0) > [87] : 0x05 (5) > [88] : 0x20 (32) > [89] : 0x00 (0) > [90] : 0x00 (0) > [91] : 0x00 (0) > [92] : 0x25 (37) > [93] : 0x02 (2) > [94] : 0x00 (0) > [95] : 0x00 (0) > [96] : 0x00 (0) > [97] : 0x00 (0) > [98] : 0x18 (24) > [99] : 0x00 (0) > [100] : 0xff (255) > [101] : 0x01 (1) > [102] : 0x0f (15) > [103] : 0x00 (0) > [104] : 0x01 (1) > [105] : 0x02 (2) > [106] : 0x00 (0) > [107] : 0x00 (0) > [108] : 0x00 (0) > [109] : 0x00 (0) > [110] : 0x00 (0) > [111] : 0x05 (5) > [112] : 0x20 (32) > [113] : 0x00 (0) > [114] : 0x00 (0) > [115] : 0x00 (0) > [116] : 0x20 (32) > [117] : 0x02 (2) > [118] : 0x00 (0) > [119] : 0x00 (0) > size : 0x00000078 (120) >[2014/04/25 09:45:50.611577, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0A 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.611827, 8, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/winreg/srv_winreg_nt.c:815(_winreg_SetValue) > _winreg_SetValue: Setting value for [HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security:Security] >[2014/04/25 09:45:50.611969, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:65(tdb_wrap_log) > tdb(/opt/local/samba4/var/locks/registry.tdb): tdb_transaction_start: nesting 1 >[2014/04/25 09:45:50.612097, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security' (ops 0xe3cbc0) >[2014/04/25 09:45:50.612225, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] >[2014/04/25 09:45:50.612443, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[Security] len[120] >[2014/04/25 09:45:50.612579, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_SetValue: struct winreg_SetValue > out: struct winreg_SetValue > result : WERR_OK >[2014/04/25 09:45:50.612921, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000a-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.613354, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0A 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.613595, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0A 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.613832, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.613956, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.614078, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.614593, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000002-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.615021, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 02 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.615283, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 02 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.615520, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.615647, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (3->2) >[2014/04/25 09:45:50.615770, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.616330, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (2->1) >[2014/04/25 09:45:50.616519, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (1->0) >[2014/04/25 09:45:50.616673, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:416(close_policy_by_pipe) > Deleted handle list for RPC connection \winreg >[2014/04/25 09:45:50.616943, 3, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/rpc_server/eventlog/srv_eventlog_reg.c:59(eventlog_init_winreg) > Initialise the eventlog registry keys if needed. >[2014/04/25 09:45:50.617300, 4, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_ncacn_np.c:60(make_internal_rpc_pipe_p) > Create pipe requested \winreg >[2014/04/25 09:45:50.617447, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:221(init_pipe_handles) > init_pipe_handle_list: created handle list for pipe \winreg >[2014/04/25 09:45:50.617576, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:238(init_pipe_handles) > init_pipe_handle_list: pipe_handles ref count = 1 for pipe \winreg >[2014/04/25 09:45:50.617889, 4, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_ncacn_np.c:100(make_internal_rpc_pipe_p) > Created internal pipe \winreg >[2014/04/25 09:45:50.618070, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_OpenHKLM: struct winreg_OpenHKLM > in: struct winreg_OpenHKLM > system_name : NULL > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY >[2014/04/25 09:45:50.618786, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [HKLM] >[2014/04/25 09:45:50.618978, 4, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:216(push_sec_ctx) > push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 >[2014/04/25 09:45:50.619110, 4, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:485(push_conn_ctx) > push_conn_ctx(0) : conn_ctx_stack_ndx = 0 >[2014/04/25 09:45:50.619237, 4, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) > setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 >[2014/04/25 09:45:50.619386, 5, pid=3996, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) > Security token: (NULL) >[2014/04/25 09:45:50.619505, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:528(debug_unix_user_token) > UNIX token of user 0 > Primary group is 0 and contains 0 supplementary groups >[2014/04/25 09:45:50.619963, 4, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:424(pop_sec_ctx) > pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 >[2014/04/25 09:45:50.620093, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:866(regdb_open) > regdb_open: registry db opened. refcount reset (1) >[2014/04/25 09:45:50.620228, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM] >[2014/04/25 09:45:50.620378, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM] >[2014/04/25 09:45:50.620506, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.620625, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM] >[2014/04/25 09:45:50.620887, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[1] [0000] 00 00 00 00 0B 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.621143, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_OpenHKLM: struct winreg_OpenHKLM > out: struct winreg_OpenHKLM > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000b-0000-0000-5a53-0e679c0f0000 > result : WERR_OK >[2014/04/25 09:45:50.621788, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_OpenKey: struct winreg_OpenKey > in: struct winreg_OpenKey > parent_handle : * > parent_handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000b-0000-0000-5a53-0e679c0f0000 > keyname: struct winreg_String > name_len : 0x0056 (86) > name_size : 0x0056 (86) > name : * > name : 'SYSTEM\CurrentControlSet\Services\Eventlog' > options : 0x00000000 (0) > 0: REG_OPTION_VOLATILE > 0: REG_OPTION_CREATE_LINK > 0: REG_OPTION_BACKUP_RESTORE > 0: REG_OPTION_OPEN_LINK > access_mask : 0x02000000 (33554432) > 0: KEY_QUERY_VALUE > 0: KEY_SET_VALUE > 0: KEY_CREATE_SUB_KEY > 0: KEY_ENUMERATE_SUB_KEYS > 0: KEY_NOTIFY > 0: KEY_CREATE_LINK > 0: KEY_WOW64_64KEY > 0: KEY_WOW64_32KEY >[2014/04/25 09:45:50.623210, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0B 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.623488, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [SYSTEM] >[2014/04/25 09:45:50.623614, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (1->2) >[2014/04/25 09:45:50.623746, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM] >[2014/04/25 09:45:50.623867, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM] >[2014/04/25 09:45:50.623996, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.624115, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM] >[2014/04/25 09:45:50.624368, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [CurrentControlSet] >[2014/04/25 09:45:50.624497, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (2->3) >[2014/04/25 09:45:50.624628, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.624748, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.624875, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.624993, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet] >[2014/04/25 09:45:50.625200, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Services] >[2014/04/25 09:45:50.625353, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (3->4) >[2014/04/25 09:45:50.625483, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.625603, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.625732, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.625851, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services] >[2014/04/25 09:45:50.626124, 7, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_api.c:143(regkey_open_onelevel) > regkey_open_onelevel: name = [Eventlog] >[2014/04/25 09:45:50.626250, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:846(regdb_open) > regdb_open: incrementing refcount (4->5) >[2014/04/25 09:45:50.626444, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:125(reghook_cache_find) > reghook_cache_find: Searching for keyname [\HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] >[2014/04/25 09:45:50.626566, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:367(pathtree_find) > pathtree_find: Enter [\HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] >[2014/04/25 09:45:50.626695, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/adt_tree.c:440(pathtree_find) > pathtree_find: Exit >[2014/04/25 09:45:50.626814, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_cachehook.c:130(reghook_cache_find) > reghook_cache_find: found ops 0xe3cbc0 for key [\HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] >[2014/04/25 09:45:50.627001, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (5->4) >[2014/04/25 09:45:50.627129, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (4->3) >[2014/04/25 09:45:50.627253, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (3->2) >[2014/04/25 09:45:50.627408, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:302(create_rpc_handle_internal) > Opened policy hnd[2] [0000] 00 00 00 00 0C 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.627643, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_OpenKey: struct winreg_OpenKey > out: struct winreg_OpenKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000c-0000-0000-5a53-0e679c0f0000 > result : WERR_OK >[2014/04/25 09:45:50.628196, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_QueryInfoKey: struct winreg_QueryInfoKey > in: struct winreg_QueryInfoKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000c-0000-0000-5a53-0e679c0f0000 > classname : * > classname: struct winreg_String > name_len : 0x0000 (0) > name_size : 0x0000 (0) > name : NULL >[2014/04/25 09:45:50.628863, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0C 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.629112, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_dispatcher.c:151(fetch_reg_values) > fetch_reg_values called for key 'HKLM\SYSTEM\CurrentControlSet\Services\Eventlog' (ops 0xe3cbc0) >[2014/04/25 09:45:50.629237, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1891(regdb_fetch_values_internal) > regdb_fetch_values: Looking for values of key [HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] >[2014/04/25 09:45:50.629459, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[0]: name[DisplayName] len[20] >[2014/04/25 09:45:50.629595, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:1836(regdb_unpack_values) > regdb_unpack_values: value[1]: name[ErrorControl] len[4] >[2014/04/25 09:45:50.629802, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:2074(regdb_get_secdesc) > regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] >[2014/04/25 09:45:50.630001, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_QueryInfoKey: struct winreg_QueryInfoKey > out: struct winreg_QueryInfoKey > classname : * > classname: struct winreg_String > name_len : 0x0000 (0) > name_size : 0x0000 (0) > name : NULL > num_subkeys : * > num_subkeys : 0x00000000 (0) > max_subkeylen : * > max_subkeylen : 0x00000000 (0) > max_classlen : * > max_classlen : 0x00000000 (0) > num_values : * > num_values : 0x00000002 (2) > max_valnamelen : * > max_valnamelen : 0x0000001a (26) > max_valbufsize : * > max_valbufsize : 0x00000014 (20) > secdescsize : * > secdescsize : 0x00000078 (120) > last_changed_time : * > last_changed_time : NTTIME(0) > result : WERR_OK >[2014/04/25 09:45:50.631420, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > in: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 0000000c-0000-0000-5a53-0e679c0f0000 >[2014/04/25 09:45:50.631818, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0C 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.632061, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:337(find_policy_by_hnd_internal) > Found policy hnd[0] [0000] 00 00 00 00 0C 00 00 00 00 00 00 00 5A 53 0E 67 ........ ....ZS.g > [0010] 9C 0F 00 00 .... >[2014/04/25 09:45:50.632326, 6, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:386(close_policy_hnd) > Closed policy >[2014/04/25 09:45:50.632452, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (2->1) >[2014/04/25 09:45:50.632577, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:333(ndr_print_function_debug) > winreg_CloseKey: struct winreg_CloseKey > out: struct winreg_CloseKey > handle : * > handle: struct policy_handle > handle_type : 0x00000000 (0) > uuid : 00000000-0000-0000-0000-000000000000 > result : WERR_OK >[2014/04/25 09:45:50.633050, 10, pid=3996, effective(0, 0), real(0, 0), class=registry] ../source3/registry/reg_backend_db.c:883(regdb_close) > regdb_close: decrementing refcount (1->0) >[2014/04/25 09:45:50.633207, 10, pid=3996, effective(0, 0), real(0, 0), class=rpc_srv] ../source3/rpc_server/rpc_handles.c:416(close_policy_by_pipe) > Deleted handle list for RPC connection \winreg >[2014/04/25 09:45:50.654994, 7, pid=3996, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:5167(lp_servicenumber) > lp_servicenumber: couldn't find print$ >[2014/04/25 09:45:50.655250, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/printing/nt_printing.c:87(print_driver_directories_init) > No print$ share has been configured. >[2014/04/25 09:45:50.655534, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 515 - private_data=(nil) >[2014/04/25 09:45:50.655705, 3, pid=3996, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:242(start_background_queue) > start_background_queue: Starting background LPQ thread >[2014/04/25 09:45:50.657657, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:278(start_background_queue) > start_background_queue: background LPQ thread started >[2014/04/25 09:45:50.659475, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:483(event_add_idle) > event_add_idle: idle_evt(print_queue_housekeeping) 0xb8ab2e60 >[2014/04/25 09:45:50.660108, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:45:50.660327, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:45:50.660487, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 9D0F000000000000FFFF >[2014/04/25 09:45:50.660673, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab3150 >[2014/04/25 09:45:50.660898, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 9D0F000000000000FFFF >[2014/04/25 09:45:50.661032, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:45:50.661153, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:45:50.661336, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 33 - private_data=0xb8aa1c30 >[2014/04/25 09:45:50.661469, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 517 - private_data=(nil) >[2014/04/25 09:45:50.661660, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/pcap.c:138(pcap_cache_reload) > reloading printcap cache >[2014/04/25 09:45:50.661906, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 1 for /opt/local/samba4/var/lock/printer_list.tdb >[2014/04/25 09:45:50.662039, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:/opt/local/samba4/var/lock/printer_list.tdb 2:<none> 3:<none> >[2014/04/25 09:45:50.662171, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 5052494E5445524C4953 >[2014/04/25 09:45:50.662350, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab3458 >[2014/04/25 09:45:50.662759, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 5052494E5445524C4953 >[2014/04/25 09:45:50.662897, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 1 for /opt/local/samba4/var/lock/printer_list.tdb >[2014/04/25 09:45:50.663019, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:45:50.663226, 0, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/print_standard.c:68(std_pcap_cache_reload) > Unable to open printcap file /etc/printers.samba for read! >[2014/04/25 09:45:50.664433, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/pcap.c:189(pcap_cache_reload) > reload status: error >[2014/04/25 09:45:50.664569, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:328(start_background_queue) > start_background_queue: background LPQ thread waiting for messages >[2014/04/25 09:45:50.666102, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) > bind succeeded on port 445 >[2014/04/25 09:45:50.666547, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 0 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.667509, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.668678, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) > bind succeeded on port 139 >[2014/04/25 09:45:50.668840, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 0 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.669761, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.670820, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) > bind succeeded on port 445 >[2014/04/25 09:45:50.670969, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 0 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.671886, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.672907, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:499(open_socket_in) > bind succeeded on port 139 >[2014/04/25 09:45:50.673055, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 0 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.674048, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:45:50.675729, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:45:50.675893, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:45:50.676038, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 9C0F000000000000FFFF >[2014/04/25 09:45:50.676206, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab2d80 >[2014/04/25 09:45:50.676458, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 9C0F000000000000FFFF >[2014/04/25 09:45:50.676593, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:45:50.676716, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:45:50.676852, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 13 - private_data=(nil) >[2014/04/25 09:45:50.676984, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 33 - private_data=0xb8aa1c30 >[2014/04/25 09:45:50.677106, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 783 - private_data=(nil) >[2014/04/25 09:45:50.677231, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 1 - private_data=(nil) >[2014/04/25 09:45:50.677382, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:308(messaging_register) > Overriding messaging pointer for type 1 - private_data=(nil) >[2014/04/25 09:45:50.677507, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 519 - private_data=0xb8aa1c30 >[2014/04/25 09:45:50.677631, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 785 - private_data=(nil) >[2014/04/25 09:45:50.677755, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 770 - private_data=(nil) >[2014/04/25 09:45:50.677880, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 15 - private_data=(nil) >[2014/04/25 09:45:50.678005, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:293(messaging_register) > Registering messaging pointer for type 16 - private_data=(nil) >[2014/04/25 09:45:50.678556, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:938(smbd_parent_loop) > waiting for connections >[2014/04/25 09:46:50.239335, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4041 -- ignoring >[2014/04/25 09:46:50.660417, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:46:50.660792, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:46:50.660919, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:76(print_queue_housekeeping) > Printcap cache time expired. >[2014/04/25 09:46:50.661038, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/pcap.c:138(pcap_cache_reload) > reloading printcap cache >[2014/04/25 09:46:50.661182, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 1 for /opt/local/samba4/var/lock/printer_list.tdb >[2014/04/25 09:46:50.661342, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:/opt/local/samba4/var/lock/printer_list.tdb 2:<none> 3:<none> >[2014/04/25 09:46:50.661479, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 5052494E5445524C4953 >[2014/04/25 09:46:50.661646, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab3598 >[2014/04/25 09:46:50.661864, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 5052494E5445524C4953 >[2014/04/25 09:46:50.662005, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 1 for /opt/local/samba4/var/lock/printer_list.tdb >[2014/04/25 09:46:50.662125, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:46:50.662301, 0, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/print_standard.c:68(std_pcap_cache_reload) > Unable to open printcap file /etc/printers.samba for read! >[2014/04/25 09:46:50.662558, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/pcap.c:189(pcap_cache_reload) > reload status: error >[2014/04/25 09:46:50.662695, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:47:50.244990, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4068 -- ignoring >[2014/04/25 09:47:50.661364, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:47:50.661699, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:47:50.661827, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:48:50.250986, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4072 -- ignoring >[2014/04/25 09:48:50.662367, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:48:50.662701, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:48:50.662833, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:49:34.114571, 5, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:49:34.115028, 10, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:49:34.115213, 10, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key ED0F000000000000FFFF >[2014/04/25 09:49:34.115439, 10, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8aa6770 >[2014/04/25 09:49:34.115643, 10, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key ED0F000000000000FFFF >[2014/04/25 09:49:34.115773, 5, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:49:34.115894, 10, pid=4077, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:49:34.116136, 5, pid=4077, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:49:34.117095, 5, pid=4077, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:49:50.258014, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4214 -- ignoring >[2014/04/25 09:49:50.663371, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:49:50.663727, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:49:50.663860, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:50:34.965904, 3, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:447(remove_child_pid) > ../source3/smbd/server.c:447 Unclean shutdown of pid 4077 >[2014/04/25 09:50:34.966351, 1, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:456(remove_child_pid) > Scheduled cleanup of brl and lock database after unclean shutdown >[2014/04/25 09:50:34.966519, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:50:34.966654, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:50:34.966796, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key ED0F000000000000FFFF >[2014/04/25 09:50:34.966968, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8aa49b8 >[2014/04/25 09:50:34.967237, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key ED0F000000000000FFFF >[2014/04/25 09:50:34.967459, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:50:34.967583, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:50:34.974389, 5, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:50:34.974800, 10, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:50:34.974982, 10, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 7910000000000000FFFF >[2014/04/25 09:50:34.975168, 10, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8aa1aa0 >[2014/04/25 09:50:34.975389, 10, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 7910000000000000FFFF >[2014/04/25 09:50:34.975519, 5, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:50:34.975639, 10, pid=4217, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:50:34.975881, 5, pid=4217, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:50:34.976792, 5, pid=4217, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options) > Socket options: > SO_KEEPALIVE = 1 > SO_REUSEADDR = 1 > SO_BROADCAST = 0 > TCP_NODELAY = 1 > TCP_KEEPCNT = 9 > TCP_KEEPIDLE = 7200 > TCP_KEEPINTVL = 75 > IPTOS_LOWDELAY = 0 > IPTOS_THROUGHPUT = 0 > SO_SNDBUF = 16384 > SO_RCVBUF = 87380 > SO_SNDLOWAT = 1 > SO_RCVLOWAT = 1 > SO_SNDTIMEO = 0 > SO_RCVTIMEO = 0 > TCP_QUICKACK = 1 > TCP_DEFER_ACCEPT = 0 >[2014/04/25 09:50:50.263931, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4220 -- ignoring >[2014/04/25 09:50:50.664348, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:50:50.664616, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:50:50.664744, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:50:54.967373, 1, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:409(cleanup_timeout_fn) > Cleaning up brl and lock database after unclean shutdown >[2014/04/25 09:50:54.968216, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:282(messaging_tdb_store) > messaging_tdb_store: >[2014/04/25 09:50:54.968436, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) > array: struct messaging_array > num_messages : 0x00000001 (1) > messages: ARRAY(1) > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_UNLOCK (773) > dest: struct server_id > pid : 0x0000000000001079 (4217) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x546fe652a6d4f629 (6084334864261510697) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 >[2014/04/25 09:50:54.972160, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:282(messaging_tdb_store) > messaging_tdb_store: >[2014/04/25 09:50:54.972347, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) > array: struct messaging_array > num_messages : 0x00000001 (1) > messages: ARRAY(1) > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_UNLOCK (773) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 >[2014/04/25 09:50:54.973615, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:242(messaging_tdb_fetch) > messaging_tdb_fetch: >[2014/04/25 09:50:54.973766, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) > result: struct messaging_array > num_messages : 0x00000001 (1) > messages: ARRAY(1) > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_UNLOCK (773) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 >[2014/04/25 09:50:54.974765, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:282(messaging_tdb_store) > messaging_tdb_store: >[2014/04/25 09:50:54.974887, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) > array: struct messaging_array > num_messages : 0x00000002 (2) > messages: ARRAY(2) > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_UNLOCK (773) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_BRL_VALIDATE (785) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 >[2014/04/25 09:50:54.976738, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:75(messaging_tdb_signal_handler) > messaging_tdb_signal_handler: sig[10] count[2] msgs[1] >[2014/04/25 09:50:54.976866, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:496(message_dispatch) > message_dispatch: received_messages = 1 >[2014/04/25 09:50:54.977040, 10, pid=3996, effective(0, 0), real(0, 0)] ../source3/lib/messages_local.c:242(messaging_tdb_fetch) > messaging_tdb_fetch: >[2014/04/25 09:50:54.977159, 1, pid=3996, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:296(ndr_print_debug) > result: struct messaging_array > num_messages : 0x00000002 (2) > messages: ARRAY(2) > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_UNLOCK (773) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 > messages: struct messaging_rec > msg_version : 0x00000002 (2) > msg_type : MSG_SMB_BRL_VALIDATE (785) > dest: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > src: struct server_id > pid : 0x0000000000000f9c (3996) > task_id : 0x00000000 (0) > vnn : 0xffffffff (4294967295) > unique_id : 0x4b72cb4430ed1c04 (5436631193928670212) > buf : DATA_BLOB length=0 >[2014/04/25 09:51:50.270083, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4223 -- ignoring >[2014/04/25 09:51:50.665366, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:51:50.665724, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:51:50.665855, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:52:50.277022, 2, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid) > Could not find child 4228 -- ignoring >[2014/04/25 09:52:50.666342, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called >[2014/04/25 09:52:50.666612, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping) > print queue housekeeping >[2014/04/25 09:52:50.666743, 10, pid=3997, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler) > smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled >[2014/04/25 09:53:21.866432, 4, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) > setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 >[2014/04/25 09:53:21.866771, 5, pid=3996, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) > Security token: (NULL) >[2014/04/25 09:53:21.866901, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:528(debug_unix_user_token) > UNIX token of user 0 > Primary group is 0 and contains 0 supplementary groups >[2014/04/25 09:53:21.867115, 5, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) > change_to_root_user: now uid=(0,0) gid=(0,0) >[2014/04/25 09:53:21.867340, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:53:21.867477, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:53:21.867707, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 9C0F000000000000FFFF >[2014/04/25 09:53:21.867879, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab46a0 >[2014/04/25 09:53:21.868082, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 9C0F000000000000FFFF >[2014/04/25 09:53:21.868215, 5, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:53:21.868368, 10, pid=3996, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:53:21.868895, 3, pid=3996, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:212(exit_server_common) > Server exit (termination signal) >[2014/04/25 09:53:21.874568, 4, pid=3997, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:316(set_sec_ctx) > setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 >[2014/04/25 09:53:21.874871, 5, pid=3997, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) > Security token: (NULL) >[2014/04/25 09:53:21.875012, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:528(debug_unix_user_token) > UNIX token of user 0 > Primary group is 0 and contains 0 supplementary groups >[2014/04/25 09:53:21.875355, 5, pid=3997, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) > change_to_root_user: now uid=(0,0) gid=(0,0) >[2014/04/25 09:53:21.875564, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order) > check lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:53:21.875690, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:/opt/local/samba4/var/lock/serverid.tdb 3:<none> >[2014/04/25 09:53:21.875833, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Locking key 9D0F000000000000FFFF >[2014/04/25 09:53:21.875992, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal) > Allocated locked data 0x0xb8ab3b40 >[2014/04/25 09:53:21.876219, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key) > Unlocking key 9D0F000000000000FFFF >[2014/04/25 09:53:21.876382, 5, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor) > release lock order 2 for /opt/local/samba4/var/lock/serverid.tdb >[2014/04/25 09:53:21.876505, 10, pid=3997, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order) > lock order: 1:<none> 2:<none> 3:<none> >[2014/04/25 09:53:21.876940, 3, pid=3997, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:212(exit_server_common) > Server exit (termination signal) >[2014/04/25 09:53:21.877160, 0, pid=3997, effective(0, 0), real(0, 0)] ../lib/util/pidfile.c:153(pidfile_unlink) > Failed to delete pidfile /opt/local/samba4/var/run/smbd.pid. Error was No such file or directory
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 10564
: 9872 |
9873
|
9874
|
9876
|
9878
|
9885
|
9895
|
9896