[Bug 1472584] Comment bridged from LTC Bugzilla
bugproxy
bugproxy at us.ibm.com
Thu Jul 9 03:10:41 UTC 2015
------- Comment From fengmt at cn.ibm.com 2015-07-09 03:01 EDT-------
(In reply to comment #13)
> Not sure what to suggest next except to increase the debug level of smbd and
> repeat the operation.
>
> You can edit /etc/init/smbd.conf and add "-d 10" to the "exec smbd" cmdline.
> Then:
>
> service smbd restart
> tail -f /var/log/samba/log.smbd
> smbclient -L localhost -U root
>
> That will show the operation from the server side which will hopefully shed
> light on the problem.
>
> Also: can you in fact connect from any client, or is it completely broken?
> Even trying the smbclient test from a different node might give a different
> result, possibly?
Hi?
We did your instructions and got the following output:
service smbd restart
tail -f /var/log/samba/log.smbd
smbclient -L localhost -U root
[2015/07/08 21:58:11.990413, 5, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order)
check lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 21:58:11.990489, 10, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:/var/run/samba/serverid.tdb 3:<none>
[2015/07/08 21:58:11.990571, 10, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Locking key 403B000000000000FFFF
[2015/07/08 21:58:11.990665, 10, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
Allocated locked data 0x0x3fffeb866dc0
[2015/07/08 21:58:11.990743, 10, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Unlocking key 403B000000000000FFFF
[2015/07/08 21:58:11.990805, 5, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor)
release lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 21:58:11.990861, 10, pid=15168, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:<none> 3:<none>
[2015/07/08 21:58:11.990943, 5, pid=15168, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
SO_BROADCAST = 0
TCP_NODELAY = 1
TCP_KEEPCNT = 9
TCP_KEEPIDLE = 7200
TCP_KEEPINTVL = 75
IPTOS_LOWDELAY = 0
IPTOS_THROUGHPUT = 0
SO_REUSEPORT = 1
SO_SNDBUF = 2626560
SO_RCVBUF = 1062720
SO_SNDLOWAT = 1
SO_RCVLOWAT = 1
SO_SNDTIMEO = 0
SO_RCVTIMEO = 0
TCP_QUICKACK = 1
TCP_DEFER_ACCEPT = 0
[2015/07/08 21:58:11.991497, 5, pid=15168, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
SO_BROADCAST = 0
TCP_NODELAY = 1
TCP_KEEPCNT = 9
TCP_KEEPIDLE = 7200
TCP_KEEPINTVL = 75
IPTOS_LOWDELAY = 0
IPTOS_THROUGHPUT = 0
SO_REUSEPORT = 1
SO_SNDBUF = 2626560
SO_RCVBUF = 1062720
SO_SNDLOWAT = 1
SO_RCVLOWAT = 1
SO_SNDTIMEO = 0
SO_RCVTIMEO = 0
TCP_QUICKACK = 1
TCP_DEFER_ACCEPT = 0
[2015/07/08 21:58:32.014703, 5, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order)
check lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 21:58:32.014770, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:/var/run/samba/serverid.tdb 3:<none>
[2015/07/08 21:58:32.014845, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Locking key 403B000000000000FFFF
[2015/07/08 21:58:32.014918, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
Allocated locked data 0x0x3fffeb85bf20
[2015/07/08 21:58:32.015027, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Unlocking key 403B000000000000FFFF
[2015/07/08 21:58:32.015080, 5, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor)
release lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 21:58:32.015147, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:<none> 3:<none>
[2015/07/08 21:58:48.917043, 2, pid=15083, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid)
Could not find child 15170 -- ignoring
[2015/07/08 21:58:49.040846, 10, pid=15085, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called
[2015/07/08 21:58:49.040902, 5, pid=15085, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping)
print queue housekeeping
[2015/07/08 21:58:49.040966, 10, pid=15085, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled
------- Comment From fengmt at cn.ibm.com 2015-07-09 03:09 EDT-------
(In reply to comment #13)
> Not sure what to suggest next except to increase the debug level of smbd and
> repeat the operation.
>
> You can edit /etc/init/smbd.conf and add "-d 10" to the "exec smbd" cmdline.
> Then:
>
> service smbd restart
> tail -f /var/log/samba/log.smbd
> smbclient -L localhost -U root
>
> That will show the operation from the server side which will hopefully shed
> light on the problem.
>
> Also: can you in fact connect from any client, or is it completely broken?
> Even trying the smbclient test from a different node might give a different
> result, possibly?
Hi,
We tried to run smbclient from other lpar roselp1. The following is the samba sever
output.
On roselp1:
root at roselp1:~# smbclient -L pole2lp1 -U root
protocol negotiation failed: NT_STATUS_IO_TIMEOUT
On pole2lp1:
[2015/07/08 22:05:46.536748, 5, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order)
check lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 22:05:46.536826, 10, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:/var/run/samba/serverid.tdb 3:<none>
[2015/07/08 22:05:46.536899, 10, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Locking key 4A3B000000000000FFFF
[2015/07/08 22:05:46.536977, 10, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
Allocated locked data 0x0x3fffeb866dc0
[2015/07/08 22:05:46.537062, 10, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Unlocking key 4A3B000000000000FFFF
[2015/07/08 22:05:46.537125, 5, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor)
release lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 22:05:46.537192, 10, pid=15178, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:<none> 3:<none>
[2015/07/08 22:05:46.537281, 5, pid=15178, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
SO_BROADCAST = 0
TCP_NODELAY = 1
TCP_KEEPCNT = 9
TCP_KEEPIDLE = 7200
TCP_KEEPINTVL = 75
IPTOS_LOWDELAY = 0
IPTOS_THROUGHPUT = 0
SO_REUSEPORT = 1
SO_SNDBUF = 87040
SO_RCVBUF = 384640
SO_SNDLOWAT = 1
SO_RCVLOWAT = 1
SO_SNDTIMEO = 0
SO_RCVTIMEO = 0
TCP_QUICKACK = 1
TCP_DEFER_ACCEPT = 0
[2015/07/08 22:05:46.537884, 5, pid=15178, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:848(print_socket_options)
Socket options:
SO_KEEPALIVE = 1
SO_REUSEADDR = 1
SO_BROADCAST = 0
TCP_NODELAY = 1
TCP_KEEPCNT = 9
TCP_KEEPIDLE = 7200
TCP_KEEPINTVL = 75
IPTOS_LOWDELAY = 0
IPTOS_THROUGHPUT = 0
SO_REUSEPORT = 1
SO_SNDBUF = 87040
SO_RCVBUF = 384640
SO_SNDLOWAT = 1
SO_RCVLOWAT = 1
SO_SNDTIMEO = 0
SO_RCVTIMEO = 0
TCP_QUICKACK = 1
TCP_DEFER_ACCEPT = 0
[2015/07/08 22:05:49.285721, 2, pid=15083, effective(0, 0), real(0, 0)] ../source3/smbd/server.c:437(remove_child_pid)
Could not find child 15180 -- ignoring
[2015/07/08 22:05:49.462348, 10, pid=15085, effective(0, 0), real(0, 0)] ../source3/lib/events.c:426(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) called
[2015/07/08 22:05:49.462401, 5, pid=15085, effective(0, 0), real(0, 0)] ../source3/printing/queue_process.c:70(print_queue_housekeeping)
print queue housekeeping
[2015/07/08 22:05:49.462467, 10, pid=15085, effective(0, 0), real(0, 0)] ../source3/lib/events.c:437(smbd_idle_event_handler)
smbd_idle_event_handler: idle_evt(print_queue_housekeeping) (nil) rescheduled
[2015/07/08 22:06:06.568451, 5, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:187(dbwrap_check_lock_order)
check lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 22:06:06.568503, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:/var/run/samba/serverid.tdb 3:<none>
[2015/07/08 22:06:06.568571, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Locking key 4A3B000000000000FFFF
[2015/07/08 22:06:06.568643, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
Allocated locked data 0x0x3fffeb85bf20
[2015/07/08 22:06:06.568718, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
Unlocking key 4A3B000000000000FFFF
[2015/07/08 22:06:06.568783, 5, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_state_destructor)
release lock order 2 for /var/run/samba/serverid.tdb
[2015/07/08 22:06:06.568844, 10, pid=15083, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
lock order: 1:<none> 2:<none> 3:<none>
--
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to samba in Ubuntu.
https://bugs.launchpad.net/bugs/1472584
Title:
ISST-LTE: Samba server doesn't work well on Ubuntu 14.04.3
Status in samba package in Ubuntu:
New
Bug description:
Today we install ubuntu 14.04.3 on pole2lp1. We found that the samba
server doesn't work well.
root at pole2lp1:/etc/samba# /etc/init.d/smbd status
* smbd is running
root at pole2lp1:/etc/samba# smbclient -L pole2lp1
protocol negotiation failed: NT_STATUS_IO_TIMEOUT
We also tried to add a user and got the same result.
root at pole2lp1:/etc/samba# smbpasswd -a root
New SMB password:
Retype new SMB password:
root at pole2lp1:/etc/samba# smbclient -L pole2lp1
Enter root's password:
protocol negotiation failed: NT_STATUS_IO_TIMEOUT
root at pole2lp1:/etc/samba# testparm
Load smb config files from /etc/samba/smb.conf
rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384)
Processing section "[printers]"
Processing section "[print$]"
Loaded services file OK.
Server role: ROLE_STANDALONE
Press enter to see a dump of your service definitions
[global]
server string = %h server (Samba, Ubuntu)
server role = standalone server
map to guest = Bad User
obey pam restrictions = Yes
pam password change = Yes
passwd program = /usr/bin/passwd %u
passwd chat = *Enter\snew\s*\spassword:* %n\n *Retype\snew\s*\spassword:* %n\n *password\supdated\ssuccessfully* .
unix password sync = Yes
syslog = 0
log file = /var/log/samba/log.%m
max log size = 1000
dns proxy = No
usershare allow guests = Yes
panic action = /usr/share/samba/panic-action %d
idmap config * : backend = tdb
[printers]
comment = All Printers
path = /var/spool/samba
create mask = 0700
printable = Yes
print ok = Yes
browseable = No
[print$]
comment = Printer Drivers
path = /var/lib/samba/printers
== Comment: #2 - Henish V. Patel <hepatel2 at in.ibm.com> - 2015-07-08 07:29:58 ==
smbclient -L pole2lp1 -U root --debuglevel=10
INFO: Current debug levels:
all: 10
tdb: 10
printdrivers: 10
lanman: 10
smb: 10
rpc_parse: 10
rpc_srv: 10
rpc_cli: 10
passdb: 10
sam: 10
auth: 10
winbind: 10
vfs: 10
idmap: 10
quota: 10
acls: 10
locking: 10
msdfs: 10
dmapi: 10
registry: 10
scavenger: 10
dns: 10
ldb: 10
lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384)
INFO: Current debug levels:
all: 10
tdb: 10
printdrivers: 10
lanman: 10
smb: 10
rpc_parse: 10
rpc_srv: 10
rpc_cli: 10
passdb: 10
sam: 10
auth: 10
winbind: 10
vfs: 10
idmap: 10
quota: 10
acls: 10
locking: 10
msdfs: 10
dmapi: 10
registry: 10
scavenger: 10
dns: 10
ldb: 10
params.c:pm_process() - Processing configuration file "/etc/samba/smb.conf"
Processing section "[global]"
doing parameter workgroup = WORKGROUP
doing parameter server string = %h server (Samba, Ubuntu)
doing parameter dns proxy = no
doing parameter log file = /var/log/samba/log.%m
doing parameter max log size = 1000
doing parameter syslog = 0
doing parameter panic action = /usr/share/samba/panic-action %d
doing parameter server role = standalone server
doing parameter passdb backend = tdbsam
doing parameter obey pam restrictions = yes
doing parameter unix password sync = yes
doing parameter passwd program = /usr/bin/passwd %u
doing parameter passwd chat = *Enter\snew\s*\spassword:* %n\n *Retype\snew\s*\spassword:* %n\n *password\supdated\ssuccessfully* .
doing parameter pam password change = yes
doing parameter map to guest = bad user
doing parameter usershare allow guests = yes
pm_process() returned Yes
lp_servicenumber: couldn't find homes
added interface eth0 ip=2002:903:15f:1130:586b:3eff:fe27:8d02 bcast= netmask=ffff:ffff:ffff:ffff::
added interface eth0 ip=10.33.8.14 bcast=10.33.255.255 netmask=255.255.0.0
Netbios name list:-
my_netbios_names[0]="POLE2LP1"
Client started (version 4.1.6-Ubuntu).
Enter root's password:
Opening cache file at /var/cache/samba/gencache.tdb
Opening cache file at /var/run/samba/gencache_notrans.tdb
sitename_fetch: No stored sitename for
internal_resolve_name: looking up pole2lp1#20 (sitename (null))
Adding cache entry with key=[NBT/POLE2LP1#20] and timeout=[Wed Dec 31 06:00:00 PM 1969 CST] (-1436353427 seconds in the past)
no entry for pole2lp1#20 found.
resolve_lmhosts: Attempting lmhosts lookup for name pole2lp1<0x20>
resolve_lmhosts: Attempting lmhosts lookup for name pole2lp1<0x20>
startlmhosts: Can't open lmhosts file /etc/samba/lmhosts. Error was No such file or directory
resolve_wins: WINS server resolution selected and no WINS servers listed.
resolve_hosts: Attempting host lookup for name pole2lp1<0x20>
remove_duplicate_addrs2: looking for duplicate address/port pairs
namecache_store: storing 1 address for pole2lp1#20: 127.0.1.1
Adding cache entry with key=[NBT/POLE2LP1#20] and timeout=[Wed Jul 8 06:14:47 AM 2015 CDT] (660 seconds ahead)
internal_resolve_name: returning 1 addresses: 127.0.1.1:0
Connecting to 127.0.1.1 at port 445
Socket options:
SO_KEEPALIVE = 0
SO_REUSEADDR = 0
SO_BROADCAST = 0
TCP_NODELAY = 1
TCP_KEEPCNT = 9
TCP_KEEPIDLE = 7200
TCP_KEEPINTVL = 75
IPTOS_LOWDELAY = 0
IPTOS_THROUGHPUT = 0
SO_REUSEPORT = 0
SO_SNDBUF = 2626560
SO_RCVBUF = 1062832
SO_SNDLOWAT = 1
SO_RCVLOWAT = 1
SO_SNDTIMEO = 0
SO_RCVTIMEO = 0
TCP_QUICKACK = 1
TCP_DEFER_ACCEPT = 0
session request ok
protocol negotiation failed: NT_STATUS_IO_TIMEOUT
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/samba/+bug/1472584/+subscriptions
More information about the foundations-bugs
mailing list