Attachment 10024 Details for Bug 10455 – wb-DOMINIOCSA.log

wb-DOMINIOCSA.log

wb-DOMINIOCSA.log (text/x-log), 112.73 KB, created by Piviul on 2014-06-12 07:05:49 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Piviul

Created: 2014-06-12 07:05:49 UTC

Size: 112.73 KB

patch

obsolete

>[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

Actions: View

Attachments on bug 10455: 9940 | 9941 | 9968 | 10024 | 10025