[Samba-it] Problemi in winbind dopo aggiornamento samba 3.0.25a

Thu May 31 07:51:55 MDT 2007

simo scrisse in data 31/05/2007 15:33:
> Questo e' il log di nmbd, sostanzialmente inutile, mi devi dire che
> trovi nei log di winbindd (gira winbindd vero?).
>   
Si, ho controllato: winbind esiste fra i processi attivi. Il problema è
che in log.winbindd non viene registrato nulla se non al momento del
riavvio: né wbinfo -u né un accesso da un client esterno produce
cambiamenti a log.winbindd. Ecco cosa viene loggato con log level 3
durante un restart di winbindd
> [2007/05/31 15:45:59, 1] nsswitch/winbindd.c:main(987)
>   winbindd version 3.0.25a started.
>   Copyright Andrew Tridgell and the Samba Team 1992-2007
> [2007/05/31 15:45:59, 2] param/loadparm.c:do_section(3788)
>   Processing section "[setup]"
> [2007/05/31 15:45:59, 2] param/loadparm.c:do_section(3788)
>   Processing section "[soci]"
> [2007/05/31 15:45:59, 2] param/loadparm.c:do_section(3788)
>   Processing section "[cdrom]"
> [2007/05/31 15:45:59, 2] param/loadparm.c:do_section(3788)
>   Processing section "[dvdrom]"
> [2007/05/31 15:45:59, 3] param/loadparm.c:lp_add_ipc(2709)
>   adding IPC service
> [2007/05/31 15:46:00, 2] lib/interface.c:add_interface(81)
>   added interface ip=192.168.70.114 bcast=192.168.70.255
> nmask=255.255.255.0
> [2007/05/31 15:46:00, 2] lib/interface.c:add_interface(81)
>   added interface ip=192.168.70.114 bcast=192.168.70.255
> nmask=255.255.255.0
> [2007/05/31 15:46:00, 2] lib/tallocmsg.c:register_msg_pool_usage(105)
>   Registered MSG_REQ_POOL_USAGE
> [2007/05/31 15:46:00, 2] lib/dmallocmsg.c:register_dmalloc_msgs(75)
>   Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED
> [2007/05/31 15:46:00, 0]
> nsswitch/winbindd_cache.c:initialize_winbindd_cache(2221)
>   initialize_winbindd_cache: clearing cache and re-creating with
> version number 1
> [2007/05/31 15:46:00, 0]
> nsswitch/winbindd_cache.c:initialize_winbindd_cache(2229)

Sempre con loglevel a 3 ho provato ad accedere da un host al server e
l'unico log che cambia (oltre a nmbd che mi dici non c'entrare nulla) è
il log relativo all'host che ti invio:
> [2007/05/31 15:39:30, 3] smbd/process.c:process_smb(1068)
>   Transaction 1 of length 137
> [2007/05/31 15:39:30, 3] smbd/process.c:switch_message(926)
>   switch message SMBnegprot (pid 2114) conn 0x0
> [2007/05/31 15:39:30, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [PC NETWORK PROGRAM 1.0]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [LANMAN1.0]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [Windows for Workgroups 3.1a]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [LM1.2X002]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [LANMAN2.1]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(505)
>   Requested protocol [NT LM 0.12]
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_nt1(364)
>   using SPNEGO
> [2007/05/31 15:39:30, 3] smbd/negprot.c:reply_negprot(606)
>   Selected protocol NT LM 0.12
> [2007/05/31 15:39:30, 3] smbd/process.c:process_smb(1068)
>   Transaction 2 of length 210
> [2007/05/31 15:39:30, 3] smbd/process.c:switch_message(926)
>   switch message SMBsesssetupX (pid 2114) conn 0x0
> [2007/05/31 15:39:30, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:30, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>   wct=12 flg2=0xc807
> [2007/05/31 15:39:30, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>   setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
> [2007/05/31 15:39:30, 3]
smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>   Doing spnego session setup
> [2007/05/31 15:39:30, 3]
smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>   NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
PrimaryDomain=[]
> [2007/05/31 15:39:30, 3] smbd/sesssetup.c:reply_spnego_negotiate(697)
>   reply_spnego_negotiate: Got secblob of size 40
> [2007/05/31 15:39:30, 3] libsmb/ntlmssp.c:debug_ntlmssp_flags(63)
>   Got NTLMSSP neg_flags=0xe2088297
> [2007/05/31 15:39:30, 3] smbd/process.c:process_smb(1068)
>   Transaction 3 of length 340
> [2007/05/31 15:39:30, 3] smbd/process.c:switch_message(926)
>   switch message SMBsesssetupX (pid 2114) conn 0x0
> [2007/05/31 15:39:30, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:30, 3] smbd/sesssetup.c:reply_sesssetup_and_X(1244)
>   wct=12 flg2=0xc807
> [2007/05/31 15:39:30, 2] smbd/sesssetup.c:setup_new_vc_session(1200)
>   setup_new_vc_session: New VC == 0, if NT4.x compatible we would
close all old resources.
> [2007/05/31 15:39:30, 3]
smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1029)
>   Doing spnego session setup
> [2007/05/31 15:39:30, 3]
smbd/sesssetup.c:reply_sesssetup_and_X_spnego(1060)
>   NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000 5.0]
PrimaryDomain=[]
> [2007/05/31 15:39:30, 3] libsmb/ntlmssp.c:ntlmssp_server_auth(739)
>   Got user=[aarcangeli] domain=[DOMINIOCSA] workstation=[SERVER2K]
len1=24 len2=24
> [2007/05/31 15:39:30, 3] libsmb/namequery.c:get_dc_list(1489)
>   get_dc_list: preferred server list: "SERVERCSA, *"
> [2007/05/31 15:39:30, 3] libsmb/namequery.c:resolve_lmhosts(966)
>   resolve_lmhosts: Attempting lmhosts lookup for name DOMINIOCSA<0x1c>
> [2007/05/31 15:39:30, 3] libsmb/namequery.c:resolve_wins(863)
>   resolve_wins: Attempting wins lookup for name DOMINIOCSA<0x1c>
> [2007/05/31 15:39:30, 3] libsmb/namequery.c:resolve_wins(866)
>   resolve_wins: WINS server resolution selected and no WINS servers
listed.
> [2007/05/31 15:39:30, 3] libsmb/namequery.c:name_resolve_bcast(805)
>   name_resolve_bcast: Attempting broadcast lookup for name
DOMINIOCSA<0x1c>
> [2007/05/31 15:39:30, 2] libsmb/namequery.c:name_query(604)
>   Got a positive name query response from 192.168.70.2 ( 192.168.70.2 )
> [2007/05/31 15:39:31, 3] libsmb/namequery.c:resolve_lmhosts(966)
>   resolve_lmhosts: Attempting lmhosts lookup for name SERVERCSA<0x20>
> [2007/05/31 15:39:31, 3] libsmb/namequery.c:resolve_wins(863)
>   resolve_wins: Attempting wins lookup for name SERVERCSA<0x20>
> [2007/05/31 15:39:31, 3] libsmb/namequery.c:resolve_wins(866)
>   resolve_wins: WINS server resolution selected and no WINS servers
listed.
> [2007/05/31 15:39:31, 3] libsmb/namequery.c:resolve_hosts(1029)
>   resolve_hosts: Attempting host lookup for name SERVERCSA<0x20>
> [2007/05/31 15:39:31, 3] libsmb/namequery_dc.c:rpc_dc_name(186)
>   rpc_dc_name: Returning DC SERVERCSA (192.168.70.2) for domain DOMINIOCSA
> [2007/05/31 15:39:31, 3] libsmb/cliconnect.c:cli_start_connection(1505)
>   Connecting to host=SERVERCSA
> [2007/05/31 15:39:31, 3] lib/util_sock.c:open_socket_out(874)
>   Connecting to 192.168.70.2 at port 445
> [2007/05/31 15:39:31, 2] lib/util_sock.c:open_socket_out(911)
>   error connecting to 192.168.70.2:445 (Connessione rifiutata)
> [2007/05/31 15:39:31, 3] lib/util_sock.c:open_socket_out(874)
>   Connecting to 192.168.70.2 at port 139
> [2007/05/31 15:39:31, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081)
>   rpc_pipe_bind: Remote machine SERVERCSA pipe \lsarpc fnum 0x7032
bind request returned ok.
> [2007/05/31 15:39:31, 3] rpc_parse/parse_lsa.c:lsa_io_sec_qos(224)
>   lsa_io_sec_qos: length c does not match size 8
> [2007/05/31 15:39:31, 3] auth/auth.c:check_ntlm_password(221)
>   check_ntlm_password:  Checking password for unmapped user
[DOMINIOCSA]\[aarcangeli]@[SERVER2K] with the new password interface
> [2007/05/31 15:39:31, 3] auth/auth.c:check_ntlm_password(224)
>   check_ntlm_password:  mapped user is:
[DOMINIOCSA]\[aarcangeli]@[SERVER2K]
> [2007/05/31 15:39:31, 3] smbd/sec_ctx.c:push_sec_ctx(208)
>   push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
> [2007/05/31 15:39:31, 3] smbd/uid.c:push_conn_ctx(358)
>   push_conn_ctx(0) : conn_ctx_stack_ndx = 0
> [2007/05/31 15:39:31, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
> [2007/05/31 15:39:31, 3] smbd/sec_ctx.c:pop_sec_ctx(356)
>   pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:31, 3] libsmb/namequery.c:get_dc_list(1489)
>   get_dc_list: preferred server list: "SERVERCSA, *"
> [2007/05/31 15:39:31, 3] libsmb/namequery_dc.c:rpc_dc_name(186)
>   rpc_dc_name: Returning DC SERVERCSA (192.168.70.2) for domain DOMINIOCSA
> [2007/05/31 15:39:31, 3] libsmb/cliconnect.c:cli_start_connection(1505)
>   Connecting to host=SERVERCSA
> [2007/05/31 15:39:31, 3] lib/util_sock.c:open_socket_out(874)
>   Connecting to 192.168.70.2 at port 445
> [2007/05/31 15:39:31, 2] lib/util_sock.c:open_socket_out(911)
>   error connecting to 192.168.70.2:445 (Connessione rifiutata)
> [2007/05/31 15:39:31, 3] lib/util_sock.c:open_socket_out(874)
>   Connecting to 192.168.70.2 at port 139
> [2007/05/31 15:39:31, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081)
>   rpc_pipe_bind: Remote machine SERVERCSA pipe \NETLOGON fnum 0x7033
bind request returned ok.
> [2007/05/31 15:39:31, 3] rpc_client/cli_pipe.c:rpc_pipe_bind(2081)
>   rpc_pipe_bind: Remote machine SERVERCSA pipe \NETLOGON fnum 0x7034
bind request returned ok.
> [2007/05/31 15:39:32, 2] auth/auth.c:check_ntlm_password(319)
>   check_ntlm_password:  Authentication for user [aarcangeli] ->
[aarcangeli] FAILED with error NT_STATUS_NO_SUCH_USER
> [2007/05/31 15:39:32, 3] smbd/error.c:error_packet_set(106)
>   error packet at smbd/sesssetup.c(105) cmd=115 (SMBsesssetupX)
NT_STATUS_LOGON_FAILURE
> [2007/05/31 15:39:32, 3] smbd/process.c:process_smb(1068)
>   Transaction 4 of length 43
> [2007/05/31 15:39:32, 3] smbd/process.c:switch_message(926)
>   switch message SMBulogoffX (pid 2114) conn 0x0
> [2007/05/31 15:39:32, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:32, 3] smbd/reply.c:reply_ulogoffX(1549)
>   ulogoff, vuser id 100 does not map to user.
> [2007/05/31 15:39:32, 3] smbd/reply.c:reply_ulogoffX(1560)
>   ulogoffX vuid=100
> [2007/05/31 15:39:32, 3] smbd/process.c:timeout_processing(1328)
>   timeout_processing: End of file from client (client has disconnected).
> [2007/05/31 15:39:32, 3] smbd/sec_ctx.c:set_sec_ctx(241)
>   setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
> [2007/05/31 15:39:32, 3] smbd/connection.c:yield_connection(69)
>   Yielding connection to
> [2007/05/31 15:39:32, 3] smbd/server.c:exit_server_common(768)
>   Server exit (normal exit)

...oppure sbaglio a settare i log level, e se fosse se mi sai dare
qualche dritta in più.

Ciao e grazie ancora

Piviul