[Pkg-samba-maint] Bug#899269: Bug#899269: Acknowledgement (samba: upgrade of samba causes smbd crash with "failed to setup guest info")

Tue May 22 02:09:46 BST 2018

On 05/21/2018 04:06 PM, Andrew Bartlett wrote:
>
> Can you turn up the log level until it gives a more useful answer?
>

Debug logs at log level 6 attached

On this machine the user "nobody" is listed in an LDAP directory, and 
sssd connects to that ldap directory. This seems to work fine on the 
command line:

root at pico:~# getent passwd nobody
nobody:x:65534:65534:nobody:/nonexistent:/usr/sbin/nologin

root at pico:~# getent group nogroup
nogroup:x:65534:

And it worked fine before on previous version of samba, would have been 
around 4.5.x or something like that (Debian stable?)

-------------- next part --------------
...
[2018/05/21 18:00:43.939048,  3] ../source3/smbd/server.c:1840(main)
  Becoming a daemon.
[2018/05/21 18:00:43.939552,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend smbpasswd
[2018/05/21 18:00:43.939601,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'smbpasswd'
[2018/05/21 18:00:43.939628,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend tdbsam
[2018/05/21 18:00:43.939657,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'tdbsam'
[2018/05/21 18:00:43.939683,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend samba_dsdb
[2018/05/21 18:00:43.939711,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'samba_dsdb'
[2018/05/21 18:00:43.939737,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend samba4
[2018/05/21 18:00:43.939763,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'samba4'
[2018/05/21 18:00:43.939794,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend ldapsam
[2018/05/21 18:00:43.939822,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'ldapsam'
[2018/05/21 18:00:43.939848,  5] ../source3/passdb/pdb_interface.c:79(smb_register_passdb)
  Attempting to register passdb backend NDS_ldapsam
[2018/05/21 18:00:43.939875,  5] ../source3/passdb/pdb_interface.c:92(smb_register_passdb)
  Successfully added passdb backend 'NDS_ldapsam'
[2018/05/21 18:00:43.939902,  5] ../source3/passdb/pdb_interface.c:155(make_pdb_method_name)
  Attempting to find a passdb backend to match tdbsam (tdbsam)
[2018/05/21 18:00:43.939929,  5] ../source3/passdb/pdb_interface.c:176(make_pdb_method_name)
  Found pdb backend tdbsam
[2018/05/21 18:00:43.939963,  5] ../source3/passdb/pdb_interface.c:187(make_pdb_method_name)
  pdb backend tdbsam has a valid init
[2018/05/21 18:00:43.943293,  5] ../lib/dbwrap/dbwrap.c:130(dbwrap_lock_order_lock)
  dbwrap_lock_order_lock: check lock order 1 for /var/run/samba/smbXsrv_version_global.tdb
[2018/05/21 18:00:43.943451,  5] ../lib/dbwrap/dbwrap.c:159(dbwrap_lock_order_unlock)
  dbwrap_lock_order_unlock: release lock order 1 for /var/run/samba/smbXsrv_version_global.tdb
[2018/05/21 18:00:43.946659,  3] ../source3/lib/util_procid.c:54(pid_to_procid)
  pid_to_procid: messaging_dgm_get_unique failed: No such file or directory
[2018/05/21 18:00:43.947453,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 794 - private_data=0x55c82483c130
[2018/05/21 18:00:43.947543,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 795 - private_data=0x55c82483c130
[2018/05/21 18:00:43.947571,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 796 - private_data=0x55c82483c130
[2018/05/21 18:00:43.948791,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 13 - private_data=0x55c824837f70
[2018/05/21 18:00:43.948896,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 788 - private_data=0x55c824837f70
[2018/05/21 18:00:43.948925,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 785 - private_data=(nil)
[2018/05/21 18:00:43.949133,  5] ../source3/lib/messages.c:678(messaging_register)
  Registering messaging pointer for type 789 - private_data=0x55c8248376f0
[2018/05/21 18:00:43.949976,  5] ../source3/lib/username.c:181(Get_Pwnam_alloc)
  Finding user nobody
[2018/05/21 18:00:43.950016,  5] ../source3/lib/username.c:120(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2018/05/21 18:00:43.951037,  5] ../source3/lib/username.c:159(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2018/05/21 18:00:43.951106,  5] ../source3/lib/username.c:181(Get_Pwnam_alloc)
  Finding user PICO\nobody
[2018/05/21 18:00:43.951135,  5] ../source3/lib/username.c:120(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is pico\nobody
[2018/05/21 18:00:44.004667,  5] ../source3/lib/username.c:128(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as given is PICO\nobody
[2018/05/21 18:00:44.056996,  5] ../source3/lib/username.c:141(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as uppercase is PICO\NOBODY
[2018/05/21 18:00:44.109635,  5] ../source3/lib/username.c:153(Get_Pwnam_internals)
  Checking combinations of 0 uppercase letters in pico\nobody
[2018/05/21 18:00:44.109705,  5] ../source3/lib/username.c:159(Get_Pwnam_internals)
  Get_Pwnam_internals didn't find user [PICO\nobody]!
[2018/05/21 18:00:44.109742,  5] ../source3/lib/username.c:181(Get_Pwnam_alloc)
  Finding user nobody
[2018/05/21 18:00:44.109781,  5] ../source3/lib/username.c:120(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2018/05/21 18:00:44.109830,  5] ../source3/lib/username.c:159(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2018/05/21 18:00:44.109977,  4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.110018,  4] ../source3/smbd/uid.c:491(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2018/05/21 18:00:44.110046,  4] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.110074,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2018/05/21 18:00:44.110100,  5] ../source3/auth/token_util.c:810(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2018/05/21 18:00:44.110270,  4] ../source3/passdb/pdb_tdb.c:558(tdbsam_open)
  tdbsam_open: successfully opened /var/lib/samba/private/passdb.tdb
[2018/05/21 18:00:44.110310,  5] ../source3/passdb/pdb_tdb.c:600(tdbsam_getsampwnam)
  pdb_getsampwnam (TDB): error fetching database.
   Key: USER_nobody
[2018/05/21 18:00:44.110361,  4] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2018/05/21 18:00:44.110394,  4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.110422,  4] ../source3/smbd/uid.c:491(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2018/05/21 18:00:44.110451,  4] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.110478,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2018/05/21 18:00:44.110504,  5] ../source3/auth/token_util.c:810(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2018/05/21 18:00:44.110650,  4] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2018/05/21 18:00:44.110711,  5] ../source3/lib/username.c:181(Get_Pwnam_alloc)
  Finding user nobody
[2018/05/21 18:00:44.110753,  5] ../source3/lib/username.c:120(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2018/05/21 18:00:44.110784,  5] ../source3/lib/username.c:159(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2018/05/21 18:00:44.111699,  5] ../source3/lib/gencache.c:72(gencache_init)
  Opening cache file at /var/cache/samba/gencache.tdb
[2018/05/21 18:00:44.111807,  5] ../source3/lib/gencache.c:100(gencache_init)
  Opening cache file at /var/run/samba/gencache_notrans.tdb
[2018/05/21 18:00:44.112722,  1] ../source3/param/loadparm.c:2488(lp_idmap_range)
  idmap range not specified for domain '*'
[2018/05/21 18:00:44.112774,  5] ../source3/lib/username.c:181(Get_Pwnam_alloc)
  Finding user nobody
[2018/05/21 18:00:44.112803,  5] ../source3/lib/username.c:120(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2018/05/21 18:00:44.112835,  5] ../source3/lib/username.c:159(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2018/05/21 18:00:44.113576,  4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.113622,  4] ../source3/smbd/uid.c:491(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2018/05/21 18:00:44.113651,  4] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.113679,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2018/05/21 18:00:44.113705,  5] ../source3/auth/token_util.c:810(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2018/05/21 18:00:44.113789,  3] ../source3/auth/token_util.c:681(finalize_local_nt_token)
  Failed to fetch domain sid for LAN
[2018/05/21 18:00:44.113827,  4] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2018/05/21 18:00:44.113876,  4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.113905,  4] ../source3/smbd/uid.c:491(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2018/05/21 18:00:44.113932,  4] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.113982,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2018/05/21 18:00:44.114007,  5] ../source3/auth/token_util.c:810(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2018/05/21 18:00:44.114093,  5] ../source3/passdb/pdb_util.c:158(create_builtin_administrators)
  create_builtin_administrators: Failed to create Administrators
[2018/05/21 18:00:44.114131,  4] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2018/05/21 18:00:44.114162,  3] ../source3/auth/token_util.c:708(finalize_local_nt_token)
  Failed to check for local Administrators membership (NT_STATUS_INVALID_PARAMETER_MIX)
[2018/05/21 18:00:44.114216,  4] ../source3/smbd/sec_ctx.c:216(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.114245,  4] ../source3/smbd/uid.c:491(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2018/05/21 18:00:44.114272,  4] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2018/05/21 18:00:44.114299,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2018/05/21 18:00:44.114325,  5] ../source3/auth/token_util.c:810(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2018/05/21 18:00:44.114395,  5] ../source3/passdb/pdb_util.c:128(create_builtin_users)
  create_builtin_users: Failed to create Users
[2018/05/21 18:00:44.114431,  4] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2018/05/21 18:00:44.114461,  3] ../source3/auth/token_util.c:751(finalize_local_nt_token)
  Failed to check for local Guests membership (NT_STATUS_INVALID_PARAMETER_MIX)
[2018/05/21 18:00:44.114514,  0] ../source3/auth/auth_util.c:1372(make_new_session_info_guest)
  create_local_token failed: NT_STATUS_NO_MEMORY
[2018/05/21 18:00:44.114553,  0] ../source3/smbd/server.c:1993(main)
  ERROR: failed to setup guest info.