[2014/06/10 14:51:54.673701, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/10 17:41:42.885036, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/11 09:47:48.509916, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/11 14:45:31.105648, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/12 08:51:56.323065, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/12 08:54:08.673469, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:561(set_domain_online_request) set_domain_online_request: called for domain DOMINIOCSA [2014/06/12 08:54:08.673769, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:596(set_domain_online_request) set_domain_online_request: domain DOMINIOCSA was globally offline. [2014/06/12 08:54:08.673978, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:561(set_domain_online_request) set_domain_online_request: called for domain DOMINIOCSA [2014/06/12 08:54:08.674513, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:959(calculate_next_machine_pwd_change) password last changed 2014/06/10 14:02:02 password valid until 2014/06/17 14:02:02 [2014/06/12 08:54:08.674955, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:967(calculate_next_machine_pwd_change) machine password still valid until: mar, 17 giu 2014 14:02:02 CEST [2014/06/12 08:54:08.675284, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:892(account_lockout_policy_handler) account_lockout_policy_handler called [2014/06/12 08:54:08.675569, 5, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:171(get_cache) get_cache: Setting MS-RPC methods for domain DOMINIOCSA [2014/06/12 08:54:08.675936, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:605(centry_expired) centry_expired: Key LOC_POL/DOMINIOCSA for domain DOMINIOCSA valid as domain is offline. [2014/06/12 08:54:08.676084, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:732(wcache_fetch) wcache_fetch: returning entry LOC_POL/DOMINIOCSA for domain DOMINIOCSA [2014/06/12 08:54:08.676239, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2927(lockout_policy) lockout_policy: [Cached] - cached info for domain DOMINIOCSA status: NT_STATUS_OK [2014/06/12 08:54:08.676470, 4, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 13 [2014/06/12 08:54:08.676603, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn PAM_AUTH [2014/06/12 08:54:08.676724, 3, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1627(winbindd_dual_pam_auth) [ 2977]: dual pam auth DOMINIOCSA\psala [2014/06/12 08:54:08.676853, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1662(winbindd_dual_pam_auth) winbindd_dual_pam_auth: domain: DOMINIOCSA offline and auth request in startup mode. [2014/06/12 08:54:08.696578, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1669(winbindd_dual_pam_auth) winbindd_dual_pam_auth: domain: DOMINIOCSA last was offline [2014/06/12 08:54:08.696777, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:858(winbindd_dual_pam_auth_cached) winbindd_dual_pam_auth_cached [2014/06/12 08:54:08.696972, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:605(centry_expired) centry_expired: Key NS/DOMINIOCSA/PSALA for domain DOMINIOCSA valid as domain is offline. [2014/06/12 08:54:08.697087, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:732(wcache_fetch) wcache_fetch: returning entry NS/DOMINIOCSA/PSALA for domain DOMINIOCSA [2014/06/12 08:54:08.697261, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1830(wcache_name_to_sid) name_to_sid: [Cached] - cached name for domain DOMINIOCSA status: NT_STATUS_OK [2014/06/12 08:54:08.697476, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:605(centry_expired) centry_expired: Key CRED/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA valid as domain is offline. [2014/06/12 08:54:08.697596, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:732(wcache_fetch) wcache_fetch: returning entry CRED/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA [2014/06/12 08:54:08.697714, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1391(wcache_get_creds) wcache_get_creds: [Cached] - cached creds for user S-1-5-21-2943704632-3037471470-1787688681-11046 status: NT_STATUS_OK [2014/06/12 08:54:08.698567, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4697(wcache_tdc_fetch_domain) wcache_tdc_fetch_domain: Searching for domain DOMINIOCSA [2014/06/12 08:54:08.698796, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4712(wcache_tdc_fetch_domain) wcache_tdc_fetch_domain: Found domain DOMINIOCSA [2014/06/12 08:54:08.698914, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1767(winbindd_dual_pam_auth) winbindd_dual_pam_auth_cached failed: NT_STATUS_INVALID_PARAMETER [2014/06/12 08:54:08.699057, 2, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1868(winbindd_dual_pam_auth) Plain-text authentication for user DOMINIOCSA\psala returned NT_STATUS_INVALID_PARAMETER (PAM: 4) [2014/06/12 08:54:08.699171, 4, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 13 [2014/06/12 08:54:08.699278, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3496 bytes to parent [2014/06/12 08:54:13.679674, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:299(check_domain_online_handler) check_domain_online_handler: called for domain DOMINIOCSA (online = False) [2014/06/12 08:54:13.685371, 5, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:155(msg_try_to_go_online) msg_try_to_go_online: received for domain DOMINIOCSA. [2014/06/12 08:54:13.685741, 3, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1858(connection_ok) connection_ok: Connection to SERVERCSA for domain DOMINIOCSA is not connected [2014/06/12 08:54:13.686128, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1675(cm_open_connection) cm_open_connection: dcname is 'SERVERCSA' for domain DOMINIOCSA [2014/06/12 08:54:13.698327, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1484(find_new_dc) find_new_dc: smbsock_any_connect failed for domain DOMINIOCSA address 192.168.70.2. Error was NT_STATUS_NETWORK_UNREACHABLE [2014/06/12 08:54:13.698792, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:3491(set_global_winbindd_state_offline) set_global_winbindd_state_offline: offline requested. [2014/06/12 08:54:13.699020, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:3514(set_global_winbindd_state_offline) set_global_winbindd_state_offline: offline state not set. [2014/06/12 08:54:13.699201, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:393(set_domain_offline) set_domain_offline: called for domain DOMINIOCSA [2014/06/12 08:54:13.699395, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:437(set_domain_offline) set_domain_offline: added event handler for domain DOMINIOCSA [2014/06/12 08:54:20.133581, 4, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 13 [2014/06/12 08:54:20.133796, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn PAM_AUTH [2014/06/12 08:54:20.133944, 3, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1627(winbindd_dual_pam_auth) [ 2977]: dual pam auth DOMINIOCSA\psala [2014/06/12 08:54:20.134092, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1662(winbindd_dual_pam_auth) winbindd_dual_pam_auth: domain: DOMINIOCSA offline and auth request in startup mode. [2014/06/12 08:54:20.154112, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1669(winbindd_dual_pam_auth) winbindd_dual_pam_auth: domain: DOMINIOCSA last was offline [2014/06/12 08:54:20.154330, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:858(winbindd_dual_pam_auth_cached) winbindd_dual_pam_auth_cached [2014/06/12 08:54:20.154531, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:605(centry_expired) centry_expired: Key NS/DOMINIOCSA/PSALA for domain DOMINIOCSA valid as domain is offline. [2014/06/12 08:54:20.154673, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:732(wcache_fetch) wcache_fetch: returning entry NS/DOMINIOCSA/PSALA for domain DOMINIOCSA [2014/06/12 08:54:20.154823, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1830(wcache_name_to_sid) name_to_sid: [Cached] - cached name for domain DOMINIOCSA status: NT_STATUS_OK [2014/06/12 08:54:20.154999, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:605(centry_expired) centry_expired: Key CRED/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA valid as domain is offline. [2014/06/12 08:54:20.155166, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:732(wcache_fetch) wcache_fetch: returning entry CRED/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA [2014/06/12 08:54:20.155308, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1391(wcache_get_creds) wcache_get_creds: [Cached] - cached creds for user S-1-5-21-2943704632-3037471470-1787688681-11046 status: NT_STATUS_OK [2014/06/12 08:54:20.155574, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4697(wcache_tdc_fetch_domain) wcache_tdc_fetch_domain: Searching for domain DOMINIOCSA [2014/06/12 08:54:20.155768, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:4712(wcache_tdc_fetch_domain) wcache_tdc_fetch_domain: Found domain DOMINIOCSA [2014/06/12 08:54:20.155910, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1767(winbindd_dual_pam_auth) winbindd_dual_pam_auth_cached failed: NT_STATUS_INVALID_PARAMETER [2014/06/12 08:54:20.156048, 2, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1868(winbindd_dual_pam_auth) Plain-text authentication for user DOMINIOCSA\psala returned NT_STATUS_INVALID_PARAMETER (PAM: 4) [2014/06/12 08:54:20.156184, 4, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 13 [2014/06/12 08:54:20.156325, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3496 bytes to parent [2014/06/12 08:54:23.703222, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:299(check_domain_online_handler) check_domain_online_handler: called for domain DOMINIOCSA (online = False) [2014/06/12 08:54:23.706589, 5, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:155(msg_try_to_go_online) msg_try_to_go_online: received for domain DOMINIOCSA. [2014/06/12 08:54:23.706833, 3, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1858(connection_ok) connection_ok: Connection to SERVERCSA for domain DOMINIOCSA is not connected [2014/06/12 08:54:23.707072, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1675(cm_open_connection) cm_open_connection: dcname is 'SERVERCSA' for domain DOMINIOCSA [2014/06/12 08:54:23.719356, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1484(find_new_dc) find_new_dc: smbsock_any_connect failed for domain DOMINIOCSA address 192.168.70.2. Error was NT_STATUS_NETWORK_UNREACHABLE [2014/06/12 08:54:23.719896, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:3491(set_global_winbindd_state_offline) set_global_winbindd_state_offline: offline requested. [2014/06/12 08:54:23.720161, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:3514(set_global_winbindd_state_offline) set_global_winbindd_state_offline: offline state not set. [2014/06/12 08:54:23.720389, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:393(set_domain_offline) set_domain_offline: called for domain DOMINIOCSA [2014/06/12 08:54:23.720630, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:437(set_domain_offline) set_domain_offline: added event handler for domain DOMINIOCSA [2014/06/12 08:54:29.431590, 10, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:2882(winbind_msg_ip_dropped) IP fe80::d2df:9aff:fe0d:a5cd dropped [2014/06/12 08:54:31.683520, 0, pid=3093, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler) Got sig[15] terminate (is_parent=0) [2014/06/12 08:56:08.279233, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:561(set_domain_online_request) set_domain_online_request: called for domain DOMINIOCSA [2014/06/12 08:56:08.279910, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:596(set_domain_online_request) set_domain_online_request: domain DOMINIOCSA was globally offline. [2014/06/12 08:56:08.280138, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:561(set_domain_online_request) set_domain_online_request: called for domain DOMINIOCSA [2014/06/12 08:56:08.280632, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:959(calculate_next_machine_pwd_change) password last changed 2014/06/10 14:02:02 password valid until 2014/06/17 14:02:02 [2014/06/12 08:56:08.281018, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:967(calculate_next_machine_pwd_change) machine password still valid until: mar, 17 giu 2014 14:02:02 CEST [2014/06/12 08:56:08.281478, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:892(account_lockout_policy_handler) account_lockout_policy_handler called [2014/06/12 08:56:08.281726, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:171(get_cache) get_cache: Setting MS-RPC methods for domain DOMINIOCSA [2014/06/12 08:56:08.282040, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:453(fetch_cache_seqnum) fetch_cache_seqnum: timeout [DOMINIOCSA][1402555834 @ 1402555834] [2014/06/12 08:56:08.282261, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:858(msrpc_sequence_number) msrpc_sequence_number: fetch sequence_number for DOMINIOCSA [2014/06/12 08:56:08.282492, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1858(connection_ok) connection_ok: Connection to SERVERCSA for domain DOMINIOCSA is not connected [2014/06/12 08:56:08.282928, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1633(cm_open_connection) cm_open_connection: saf_servername is 'SERVERCSA' for domain DOMINIOCSA [2014/06/12 08:56:08.283153, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1675(cm_open_connection) cm_open_connection: dcname is 'SERVERCSA' for domain DOMINIOCSA [2014/06/12 08:56:08.293624, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:878(cm_prepare_connection) cm_prepare_connection: connecting to DC SERVERCSA for domain DOMINIOCSA [2014/06/12 08:56:08.296689, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:969(cm_prepare_connection) connecting to SERVERCSA from 10NET0512 with username [DOMINIOCSA]\[10NET0512$] [2014/06/12 08:56:08.335729, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:475(set_domain_online) set_domain_online: called for domain DOMINIOCSA [2014/06/12 08:56:08.378738, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:2385(cm_connect_sam) cm_connect_sam: connected to SAMR pipe for domain DOMINIOCSA using NTLMSSP authenticated pipe: user DOMINIOCSA\10NET0512$ [2014/06/12 08:56:08.389013, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_rpc.c:955(rpc_sequence_number) domain_sequence_number: for domain DOMINIOCSA is 1402556168 [2014/06/12 08:56:08.389621, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:496(wcache_store_seqnum) wcache_store_seqnum: success [DOMINIOCSA][1402556168 @ 1402556168] [2014/06/12 08:56:08.389903, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.390237, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key LOC_POL/DOMINIOCSA for domain DOMINIOCSA expired [2014/06/12 08:56:08.390434, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry LOC_POL/DOMINIOCSA expired for domain DOMINIOCSA [2014/06/12 08:56:08.390622, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2941(lockout_policy) lockout_policy: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:08.390821, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:987(msrpc_lockout_policy) msrpc_lockout_policy: fetch lockout policy for DOMINIOCSA [2014/06/12 08:56:08.393187, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:1018(msrpc_lockout_policy) msrpc_lockout_policy: lockout_threshold 0 [2014/06/12 08:56:08.393485, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.393637, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.393820, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1038(wcache_save_lockout_policy) wcache_save_lockout_policy: DOMINIOCSA [2014/06/12 08:56:08.394071, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 48 extra bytes [2014/06/12 08:56:08.394242, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:08.394388, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:08.394538, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPNAME (DOMINIOCSA) [2014/06/12 08:56:08.394803, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.394953, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.395128, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key NS/DOMINIOCSA/PSALA for domain DOMINIOCSA expired [2014/06/12 08:56:08.395267, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry NS/DOMINIOCSA/PSALA expired for domain DOMINIOCSA [2014/06/12 08:56:08.395406, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1869(name_to_sid) name_to_sid: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:08.395547, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:252(msrpc_name_to_sid) msrpc_name_to_sid: name=DOMINIOCSA\PSALA [2014/06/12 08:56:08.395685, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:266(msrpc_name_to_sid) name_to_sid [rpc] DOMINIOCSA\PSALA for domain DOMINIOCSA [2014/06/12 08:56:08.424818, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:2621(cm_connect_lsa) cm_connect_lsa: connected to LSA pipe for domain DOMINIOCSA using NTLMSSP authenticated pipe: user DOMINIOCSA\10NET0512$ [2014/06/12 08:56:08.434117, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.434367, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.434635, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:961(wcache_save_name_to_sid) wcache_save_name_to_sid: DOMINIOCSA\PSALA -> S-1-5-21-2943704632-3037471470-1787688681-11046 (NT_STATUS_OK) [2014/06/12 08:56:08.434949, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11046 -> DOMINIOCSA\psala (NT_STATUS_OK) [2014/06/12 08:56:08.435251, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:08.435440, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3532 bytes to parent [2014/06/12 08:56:08.436876, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:08.437217, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:08.437424, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:08.437612, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_QUERYUSER (DOMINIOCSA) [2014/06/12 08:56:08.437938, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.438142, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.438376, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key U/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA expired [2014/06/12 08:56:08.438563, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry U/S-1-5-21-2943704632-3037471470-1787688681-11046 expired for domain DOMINIOCSA [2014/06/12 08:56:08.438751, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2335(query_user) query_user: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:08.438935, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:414(msrpc_query_user) msrpc_query_user sid=S-1-5-21-2943704632-3037471470-1787688681-11046 [2014/06/12 08:56:08.439679, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:431(msrpc_query_user) msrpc_query_user: Cache lookup succeeded for S-1-5-21-2943704632-3037471470-1787688681-11046 [2014/06/12 08:56:08.439969, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.440205, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.440473, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1018(wcache_save_user) wcache_save_user: S-1-5-21-2943704632-3037471470-1787688681-11046 (acct_name psala) [2014/06/12 08:56:08.440812, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:08.441026, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3624 bytes to parent [2014/06/12 08:56:08.443653, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:08.443908, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:08.444112, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:08.444306, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:08.444548, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.444736, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.444980, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11047 for domain DOMINIOCSA expired [2014/06/12 08:56:08.445268, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11047 expired for domain DOMINIOCSA [2014/06/12 08:56:08.445493, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:08.445693, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11047 for domain DOMINIOCSA [2014/06/12 08:56:08.453465, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Paolo Sala] [2014/06/12 08:56:08.453825, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:08.454037, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:08.454337, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11047 -> DOMINIOCSA\Paolo Sala (NT_STATUS_OK) [2014/06/12 08:56:08.454596, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:08.454789, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3560 bytes to parent [2014/06/12 08:56:13.830863, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 13 [2014/06/12 08:56:13.831093, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn PAM_AUTH [2014/06/12 08:56:13.831258, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1627(winbindd_dual_pam_auth) [ 3291]: dual pam auth DOMINIOCSA\psala [2014/06/12 08:56:13.831413, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1669(winbindd_dual_pam_auth) winbindd_dual_pam_auth: domain: DOMINIOCSA last was online [2014/06/12 08:56:13.831563, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1113(winbindd_dual_pam_auth_kerberos) winbindd_dual_pam_auth_kerberos [2014/06/12 08:56:13.831759, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1156(winbindd_dual_pam_auth_kerberos) krb5 auth requested but domain is not Active Directory [2014/06/12 08:56:13.831902, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1683(winbindd_dual_pam_auth) winbindd_dual_pam_auth_kerberos failed: NT_STATUS_INVALID_LOGON_TYPE [2014/06/12 08:56:13.832062, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1712(winbindd_dual_pam_auth) falling back to samlogon [2014/06/12 08:56:13.832194, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1461(winbindd_dual_pam_auth_samlogon) winbindd_dual_pam_auth_samlogon [2014/06/12 08:56:13.955649, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1730(winbindd_dual_pam_auth) winbindd_dual_pam_auth_samlogon succeeded [2014/06/12 08:56:13.956199, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:13.956371, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:13.956569, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:961(wcache_save_name_to_sid) wcache_save_name_to_sid: DOMINIOCSA\PSALA -> S-1-5-21-2943704632-3037471470-1787688681-11046 (NT_STATUS_OK) [2014/06/12 08:56:13.956805, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:180(append_unix_username) Setting unix username to [DOMINIOCSA\psala] [2014/06/12 08:56:13.990213, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1434(wcache_save_creds) wcache_save_creds: S-1-5-21-2943704632-3037471470-1787688681-11046 [2014/06/12 08:56:13.990495, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:13.990619, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:13.990748, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key PWD_POL/DOMINIOCSA for domain DOMINIOCSA expired [2014/06/12 08:56:13.990857, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry PWD_POL/DOMINIOCSA expired for domain DOMINIOCSA [2014/06/12 08:56:13.990966, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:3013(password_policy) password_policy: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:13.991107, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:1037(msrpc_password_policy) msrpc_password_policy: fetch password policy for DOMINIOCSA [2014/06/12 08:56:13.993508, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:1067(msrpc_password_policy) msrpc_password_policy: min_length_password 5 [2014/06/12 08:56:13.993870, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:13.994104, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:13.994401, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1063(wcache_save_password_policy) wcache_save_password_policy: DOMINIOCSA [2014/06/12 08:56:13.994698, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_pam.c:1868(winbindd_dual_pam_auth) Plain-text authentication for user DOMINIOCSA\psala returned NT_STATUS_OK (PAM: 0) [2014/06/12 08:56:13.994938, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 13 [2014/06/12 08:56:13.995158, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3629 bytes to parent [2014/06/12 08:56:14.012143, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:14.012429, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:14.012669, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:14.012905, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPUSERGROUPS (DOMINIOCSA) [2014/06/12 08:56:14.013320, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:14.013571, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:14.013869, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key UG/S-1-5-21-2943704632-3037471470-1787688681-11046 for domain DOMINIOCSA expired [2014/06/12 08:56:14.014102, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry UG/S-1-5-21-2943704632-3037471470-1787688681-11046 expired for domain DOMINIOCSA [2014/06/12 08:56:14.014343, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2449(lookup_usergroups) lookup_usergroups: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:14.014574, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:486(msrpc_lookup_usergroups) msrpc_lookup_usergroups sid=S-1-5-21-2943704632-3037471470-1787688681-11046 [2014/06/12 08:56:14.014822, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1017(lookup_usergroups_cached) : lookup_usergroups_cached [2014/06/12 08:56:14.015339, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1055(lookup_usergroups_cached) : lookup_usergroups_cached succeeded [2014/06/12 08:56:14.015709, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:14.015945, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:14.016359, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:14.016605, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3676 bytes to parent [2014/06/12 08:56:15.445103, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 48 extra bytes [2014/06/12 08:56:15.445359, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.445533, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.445705, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPNAME (DOMINIOCSA) [2014/06/12 08:56:15.445939, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.446125, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.446326, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key NS/DOMINIOCSA/CRABONI for domain DOMINIOCSA expired [2014/06/12 08:56:15.446512, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry NS/DOMINIOCSA/CRABONI expired for domain DOMINIOCSA [2014/06/12 08:56:15.446691, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1869(name_to_sid) name_to_sid: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.446867, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:252(msrpc_name_to_sid) msrpc_name_to_sid: name=DOMINIOCSA\CRABONI [2014/06/12 08:56:15.447034, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:266(msrpc_name_to_sid) name_to_sid [rpc] DOMINIOCSA\CRABONI for domain DOMINIOCSA [2014/06/12 08:56:15.452893, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.453118, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.453417, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:961(wcache_save_name_to_sid) wcache_save_name_to_sid: DOMINIOCSA\CRABONI -> S-1-5-21-2943704632-3037471470-1787688681-11036 (NT_STATUS_OK) [2014/06/12 08:56:15.453629, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11036 -> DOMINIOCSA\craboni (NT_STATUS_OK) [2014/06/12 08:56:15.453843, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.453993, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3532 bytes to parent [2014/06/12 08:56:15.454710, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.454914, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.455066, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.455211, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_QUERYUSER (DOMINIOCSA) [2014/06/12 08:56:15.455401, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.455544, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.455721, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key U/S-1-5-21-2943704632-3037471470-1787688681-11036 for domain DOMINIOCSA expired [2014/06/12 08:56:15.455874, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry U/S-1-5-21-2943704632-3037471470-1787688681-11036 expired for domain DOMINIOCSA [2014/06/12 08:56:15.456021, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2335(query_user) query_user: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.456163, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:414(msrpc_query_user) msrpc_query_user sid=S-1-5-21-2943704632-3037471470-1787688681-11036 [2014/06/12 08:56:15.456431, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:431(msrpc_query_user) msrpc_query_user: Cache lookup succeeded for S-1-5-21-2943704632-3037471470-1787688681-11036 [2014/06/12 08:56:15.456616, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.456762, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.456962, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1018(wcache_save_user) wcache_save_user: S-1-5-21-2943704632-3037471470-1787688681-11036 (acct_name craboni) [2014/06/12 08:56:15.457182, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.457334, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3628 bytes to parent [2014/06/12 08:56:15.458536, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.458717, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.458894, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.459037, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:15.459216, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.459354, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.459523, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11037 for domain DOMINIOCSA expired [2014/06/12 08:56:15.459661, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11037 expired for domain DOMINIOCSA [2014/06/12 08:56:15.459803, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.459940, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11037 for domain DOMINIOCSA [2014/06/12 08:56:15.463280, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Cinzia Raboni] [2014/06/12 08:56:15.463516, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.463662, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.463883, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11037 -> DOMINIOCSA\Cinzia Raboni (NT_STATUS_OK) [2014/06/12 08:56:15.464074, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.464219, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3564 bytes to parent [2014/06/12 08:56:15.466329, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.466512, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.466660, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.466808, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPUSERGROUPS (DOMINIOCSA) [2014/06/12 08:56:15.466990, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.467133, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.467345, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key UG/S-1-5-21-2943704632-3037471470-1787688681-11036 for domain DOMINIOCSA expired [2014/06/12 08:56:15.467491, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry UG/S-1-5-21-2943704632-3037471470-1787688681-11036 expired for domain DOMINIOCSA [2014/06/12 08:56:15.467639, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2449(lookup_usergroups) lookup_usergroups: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.467782, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:486(msrpc_lookup_usergroups) msrpc_lookup_usergroups sid=S-1-5-21-2943704632-3037471470-1787688681-11036 [2014/06/12 08:56:15.467939, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1017(lookup_usergroups_cached) : lookup_usergroups_cached [2014/06/12 08:56:15.468203, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1055(lookup_usergroups_cached) : lookup_usergroups_cached succeeded [2014/06/12 08:56:15.468374, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.468509, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.468769, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.468920, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3760 bytes to parent [2014/06/12 08:56:15.490355, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 52 extra bytes [2014/06/12 08:56:15.490545, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.490702, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.490854, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPNAME (DOMINIOCSA) [2014/06/12 08:56:15.491058, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.491207, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.491387, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key NS/DOMINIOCSA/RDONNINI for domain DOMINIOCSA expired [2014/06/12 08:56:15.491527, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry NS/DOMINIOCSA/RDONNINI expired for domain DOMINIOCSA [2014/06/12 08:56:15.491685, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1869(name_to_sid) name_to_sid: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.491835, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:252(msrpc_name_to_sid) msrpc_name_to_sid: name=DOMINIOCSA\RDONNINI [2014/06/12 08:56:15.492011, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:266(msrpc_name_to_sid) name_to_sid [rpc] DOMINIOCSA\RDONNINI for domain DOMINIOCSA [2014/06/12 08:56:15.496303, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.496518, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.496740, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:961(wcache_save_name_to_sid) wcache_save_name_to_sid: DOMINIOCSA\RDONNINI -> S-1-5-21-2943704632-3037471470-1787688681-11294 (NT_STATUS_OK) [2014/06/12 08:56:15.496972, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11294 -> DOMINIOCSA\rdonnini (NT_STATUS_OK) [2014/06/12 08:56:15.497281, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.497472, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3532 bytes to parent [2014/06/12 08:56:15.498582, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.498856, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.499060, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.499214, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_QUERYUSER (DOMINIOCSA) [2014/06/12 08:56:15.499415, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.499559, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.499735, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key U/S-1-5-21-2943704632-3037471470-1787688681-11294 for domain DOMINIOCSA expired [2014/06/12 08:56:15.499879, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry U/S-1-5-21-2943704632-3037471470-1787688681-11294 expired for domain DOMINIOCSA [2014/06/12 08:56:15.500021, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2335(query_user) query_user: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.500161, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:414(msrpc_query_user) msrpc_query_user sid=S-1-5-21-2943704632-3037471470-1787688681-11294 [2014/06/12 08:56:15.500450, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:431(msrpc_query_user) msrpc_query_user: Cache lookup succeeded for S-1-5-21-2943704632-3037471470-1787688681-11294 [2014/06/12 08:56:15.500638, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.500824, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.501024, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1018(wcache_save_user) wcache_save_user: S-1-5-21-2943704632-3037471470-1787688681-11294 (acct_name rdonnini) [2014/06/12 08:56:15.501271, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.501460, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3632 bytes to parent [2014/06/12 08:56:15.502704, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.502863, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.502977, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.503087, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:15.503236, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.503350, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.503486, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11295 for domain DOMINIOCSA expired [2014/06/12 08:56:15.503599, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11295 expired for domain DOMINIOCSA [2014/06/12 08:56:15.503710, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.503819, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11295 for domain DOMINIOCSA [2014/06/12 08:56:15.507229, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Roberto Donnini] [2014/06/12 08:56:15.507438, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.507552, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.507720, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11295 -> DOMINIOCSA\Roberto Donnini (NT_STATUS_OK) [2014/06/12 08:56:15.507865, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.508011, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3564 bytes to parent [2014/06/12 08:56:15.510175, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.510341, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.510455, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.510564, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPUSERGROUPS (DOMINIOCSA) [2014/06/12 08:56:15.510705, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.510831, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.510970, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key UG/S-1-5-21-2943704632-3037471470-1787688681-11294 for domain DOMINIOCSA expired [2014/06/12 08:56:15.511083, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry UG/S-1-5-21-2943704632-3037471470-1787688681-11294 expired for domain DOMINIOCSA [2014/06/12 08:56:15.511200, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2449(lookup_usergroups) lookup_usergroups: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.511309, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:486(msrpc_lookup_usergroups) msrpc_lookup_usergroups sid=S-1-5-21-2943704632-3037471470-1787688681-11294 [2014/06/12 08:56:15.511433, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1017(lookup_usergroups_cached) : lookup_usergroups_cached [2014/06/12 08:56:15.511645, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1055(lookup_usergroups_cached) : lookup_usergroups_cached succeeded [2014/06/12 08:56:15.511776, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.511886, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.512257, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.512439, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3676 bytes to parent [2014/06/12 08:56:15.531572, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 48 extra bytes [2014/06/12 08:56:15.531743, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.531858, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.531967, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPNAME (DOMINIOCSA) [2014/06/12 08:56:15.532158, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.532276, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.532412, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key NS/DOMINIOCSA/AFONTI for domain DOMINIOCSA expired [2014/06/12 08:56:15.532521, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry NS/DOMINIOCSA/AFONTI expired for domain DOMINIOCSA [2014/06/12 08:56:15.532630, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1869(name_to_sid) name_to_sid: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.532742, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:252(msrpc_name_to_sid) msrpc_name_to_sid: name=DOMINIOCSA\AFONTI [2014/06/12 08:56:15.532851, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:266(msrpc_name_to_sid) name_to_sid [rpc] DOMINIOCSA\AFONTI for domain DOMINIOCSA [2014/06/12 08:56:15.538865, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.539230, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.539571, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:961(wcache_save_name_to_sid) wcache_save_name_to_sid: DOMINIOCSA\AFONTI -> S-1-5-21-2943704632-3037471470-1787688681-11456 (NT_STATUS_OK) [2014/06/12 08:56:15.539885, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11456 -> DOMINIOCSA\afonti (NT_STATUS_OK) [2014/06/12 08:56:15.540158, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.540384, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3532 bytes to parent [2014/06/12 08:56:15.541611, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.541894, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.542130, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.542356, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_QUERYUSER (DOMINIOCSA) [2014/06/12 08:56:15.542644, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.542867, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.543191, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key U/S-1-5-21-2943704632-3037471470-1787688681-11456 for domain DOMINIOCSA expired [2014/06/12 08:56:15.543419, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry U/S-1-5-21-2943704632-3037471470-1787688681-11456 expired for domain DOMINIOCSA [2014/06/12 08:56:15.543653, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2335(query_user) query_user: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.543879, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:414(msrpc_query_user) msrpc_query_user sid=S-1-5-21-2943704632-3037471470-1787688681-11456 [2014/06/12 08:56:15.544275, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:431(msrpc_query_user) msrpc_query_user: Cache lookup succeeded for S-1-5-21-2943704632-3037471470-1787688681-11456 [2014/06/12 08:56:15.544557, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.544782, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.545085, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1018(wcache_save_user) wcache_save_user: S-1-5-21-2943704632-3037471470-1787688681-11456 (acct_name afonti) [2014/06/12 08:56:15.545446, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.545679, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3628 bytes to parent [2014/06/12 08:56:15.547440, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.547706, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.547943, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.548174, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:15.548472, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.548699, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.548981, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11457 for domain DOMINIOCSA expired [2014/06/12 08:56:15.549309, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11457 expired for domain DOMINIOCSA [2014/06/12 08:56:15.549497, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:15.549700, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11457 for domain DOMINIOCSA [2014/06/12 08:56:15.554371, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Andrea Fonti] [2014/06/12 08:56:15.554634, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.554814, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.555066, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11457 -> DOMINIOCSA\Andrea Fonti (NT_STATUS_OK) [2014/06/12 08:56:15.555284, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.555457, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3564 bytes to parent [2014/06/12 08:56:15.558293, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:15.558513, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:15.558706, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:15.558882, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPUSERGROUPS (DOMINIOCSA) [2014/06/12 08:56:15.559103, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.559269, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.559484, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key UG/S-1-5-21-2943704632-3037471470-1787688681-11456 for domain DOMINIOCSA expired [2014/06/12 08:56:15.559657, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry UG/S-1-5-21-2943704632-3037471470-1787688681-11456 expired for domain DOMINIOCSA [2014/06/12 08:56:15.559835, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2449(lookup_usergroups) lookup_usergroups: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:15.560008, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:486(msrpc_lookup_usergroups) msrpc_lookup_usergroups sid=S-1-5-21-2943704632-3037471470-1787688681-11456 [2014/06/12 08:56:15.560201, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1017(lookup_usergroups_cached) : lookup_usergroups_cached [2014/06/12 08:56:15.560505, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_util.c:1055(lookup_usergroups_cached) : lookup_usergroups_cached succeeded [2014/06/12 08:56:15.560704, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:15.560908, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:15.561322, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:15.561572, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3676 bytes to parent [2014/06/12 08:56:20.633277, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:20.633576, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.633754, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.633922, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:20.634142, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.634312, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.634519, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11543 for domain DOMINIOCSA expired [2014/06/12 08:56:20.634709, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11543 expired for domain DOMINIOCSA [2014/06/12 08:56:20.634880, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:20.635046, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11543 for domain DOMINIOCSA [2014/06/12 08:56:20.645644, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Gruppo per Gestione NethAudit] [2014/06/12 08:56:20.645931, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.646106, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.646325, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11543 -> DOMINIOCSA\Gruppo per Gestione NethAudit (NT_STATUS_OK) [2014/06/12 08:56:20.646554, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.646739, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3580 bytes to parent [2014/06/12 08:56:20.657290, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:20.657619, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.657792, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.657952, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:20.658208, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.658384, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.658584, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-11543 for domain DOMINIOCSA expired [2014/06/12 08:56:20.658748, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-11543 expired for domain DOMINIOCSA [2014/06/12 08:56:20.658905, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:20.659059, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-11543 [2014/06/12 08:56:20.684775, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.685031, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.685407, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.685605, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3716 bytes to parent [2014/06/12 08:56:20.689065, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:20.689361, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.689538, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.689700, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:20.689910, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.690073, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.690264, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11551 for domain DOMINIOCSA expired [2014/06/12 08:56:20.690470, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11551 expired for domain DOMINIOCSA [2014/06/12 08:56:20.690635, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:20.690792, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11551 for domain DOMINIOCSA [2014/06/12 08:56:20.705644, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[rete_amm] [2014/06/12 08:56:20.705932, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.706108, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.706324, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11551 -> DOMINIOCSA\rete_amm (NT_STATUS_OK) [2014/06/12 08:56:20.706540, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.706712, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3560 bytes to parent [2014/06/12 08:56:20.708037, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:20.708262, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.708433, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.708597, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:20.708811, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.708983, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.709248, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-11551 for domain DOMINIOCSA expired [2014/06/12 08:56:20.709448, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-11551 expired for domain DOMINIOCSA [2014/06/12 08:56:20.709624, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:20.709785, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-11551 [2014/06/12 08:56:20.732306, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.732544, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.732861, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.733044, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3716 bytes to parent [2014/06/12 08:56:20.736174, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:20.736384, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.736542, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.736682, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:20.736873, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.737037, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.737274, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11163 for domain DOMINIOCSA expired [2014/06/12 08:56:20.737437, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11163 expired for domain DOMINIOCSA [2014/06/12 08:56:20.737587, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:20.737729, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11163 for domain DOMINIOCSA [2014/06/12 08:56:20.742314, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[utenti] [2014/06/12 08:56:20.742576, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.742735, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.742938, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11163 -> DOMINIOCSA\utenti (NT_STATUS_OK) [2014/06/12 08:56:20.743138, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.743342, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3556 bytes to parent [2014/06/12 08:56:20.744625, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:20.744833, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.744990, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.745257, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:20.745487, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.745637, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.745825, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-11163 for domain DOMINIOCSA expired [2014/06/12 08:56:20.745969, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-11163 expired for domain DOMINIOCSA [2014/06/12 08:56:20.746112, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:20.746249, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-11163 [2014/06/12 08:56:20.973432, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.973797, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.975860, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.976181, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 12172 bytes to parent [2014/06/12 08:56:20.983576, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:20.983915, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.984157, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.984380, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:20.984664, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.984965, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.985435, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-11001 for domain DOMINIOCSA expired [2014/06/12 08:56:20.985688, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-11001 expired for domain DOMINIOCSA [2014/06/12 08:56:20.985921, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:20.986149, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11001 for domain DOMINIOCSA [2014/06/12 08:56:20.992167, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Gruppo per Gestione FaxWeb] [2014/06/12 08:56:20.992431, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.992584, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.992812, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-11001 -> DOMINIOCSA\Gruppo per Gestione FaxWeb (NT_STATUS_OK) [2014/06/12 08:56:20.993015, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:20.993330, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3576 bytes to parent [2014/06/12 08:56:20.994988, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:20.995264, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:20.995475, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:20.995666, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:20.995929, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:20.996145, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:20.996415, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-11001 for domain DOMINIOCSA expired [2014/06/12 08:56:20.996630, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-11001 expired for domain DOMINIOCSA [2014/06/12 08:56:20.996893, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:20.997106, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-11001 [2014/06/12 08:56:21.156853, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.157114, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.159062, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:21.159288, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 12176 bytes to parent [2014/06/12 08:56:21.165031, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 28 extra bytes [2014/06/12 08:56:21.165384, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:21.165546, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:21.165695, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPSID (DOMINIOCSA) [2014/06/12 08:56:21.165899, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.166052, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.166228, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key SN/S-1-5-21-2943704632-3037471470-1787688681-513 for domain DOMINIOCSA expired [2014/06/12 08:56:21.166369, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry SN/S-1-5-21-2943704632-3037471470-1787688681-513 expired for domain DOMINIOCSA [2014/06/12 08:56:21.166521, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:1983(sid_to_name) sid_to_name: [Cached] - doing backend query for name for domain DOMINIOCSA [2014/06/12 08:56:21.166665, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:300(msrpc_sid_to_name) msrpc_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-513 for domain DOMINIOCSA [2014/06/12 08:56:21.171437, 5, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:320(msrpc_sid_to_name) Mapped sid to [DOMINIOCSA]\[Domain Users] [2014/06/12 08:56:21.171696, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.171853, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.172061, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:991(wcache_save_sid_to_name) wcache_save_sid_to_name: S-1-5-21-2943704632-3037471470-1787688681-513 -> DOMINIOCSA\Domain Users (NT_STATUS_OK) [2014/06/12 08:56:21.172313, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:21.172474, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3564 bytes to parent [2014/06/12 08:56:21.173907, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:21.174138, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:21.174293, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:21.174442, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:21.174647, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.174803, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.175000, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-513 for domain DOMINIOCSA expired [2014/06/12 08:56:21.175158, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-513 expired for domain DOMINIOCSA [2014/06/12 08:56:21.175307, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:21.175457, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-513 [2014/06/12 08:56:21.340544, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.340797, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.342932, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:21.343215, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 12804 bytes to parent [2014/06/12 08:56:21.349363, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:71(child_read_request) Need to read 30 extra bytes [2014/06/12 08:56:21.349625, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1338(child_handler) child daemon request 59 [2014/06/12 08:56:21.349794, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:458(child_process_request) child_process_request: request fn NDRCMD [2014/06/12 08:56:21.349955, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual_ndr.c:315(winbindd_dual_ndrcmd) winbindd_dual_ndrcmd: Running command WBINT_LOOKUPGROUPMEMBERS (DOMINIOCSA) [2014/06/12 08:56:21.350217, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.350393, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.350597, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:629(centry_expired) centry_expired: Key GM/S-1-5-21-2943704632-3037471470-1787688681-11047 for domain DOMINIOCSA expired [2014/06/12 08:56:21.350766, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:724(wcache_fetch) wcache_fetch: entry GM/S-1-5-21-2943704632-3037471470-1787688681-11047 expired for domain DOMINIOCSA [2014/06/12 08:56:21.350938, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:2738(lookup_groupmem) lookup_groupmem: [Cached] - doing backend query for info for domain DOMINIOCSA [2014/06/12 08:56:21.351100, 3, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_msrpc.c:631(msrpc_lookup_groupmem) msrpc_lookup_groupmem: DOMINIOCSA sid=S-1-5-21-2943704632-3037471470-1787688681-11047 [2014/06/12 08:56:21.360174, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:538(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA time ok [2014/06/12 08:56:21.360422, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cache.c:583(refresh_sequence_number) refresh_sequence_number: DOMINIOCSA seq number is now 1402556168 [2014/06/12 08:56:21.360702, 4, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1346(child_handler) Finished processing child request 59 [2014/06/12 08:56:21.360882, 10, pid=3329, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_dual.c:1363(child_handler) Writing 3576 bytes to parent