[Bug 512777] Re: first login fails when NIC is managed by NM-wicd
Roman
tatel at infonegocio.com
Thu Jan 28 11:19:52 GMT 2010
Hi
Static IPs: Some friends have the same config working on a static
network since some 2 years (Debian Etch IIRC). With karmic they report
it's working as long as they use wicd: but they are unable to use
NetworkManager (no logon servers). They are now planning to migrate all
the school within a 2 months deadline so it seems they feel happy. I
could get a syslog from them if needed.
DHCP: tried on another machine with samba package installed, so smbd and
nmbd appear on syslog too. I tried the four combinations of lines/no
lines about eth0 on /etc/network/interfaces and managed = false/true on
/etc/NetworkManager/nm-system-settings.conf.
Two of them (managed = false) follow below. I guess this covers the two
usual scenarios. Behaviour is always the same in all four cases. No wicd
tests done.
It seems that, with any config, and even if everybody gets fired up just
in time, dhclient does not finish before samba daemons start. I hope you
can see the causes I'm unable to see.
After some googling, it seems that there is a NETWORKWAIT option on
RedHat that goes in /etc/network/interfaces equivalent, but I'm unable
to see any mention about it on .deb-based distros. I know this is a
samba bug report, but does anybody knows about it?
Funniest thing is, if you wait some 30 seconds after gdm appears on
screen, you can login at first attemp; if you don't wait, first login
fails (?) Second login attempt always work.
Syslog snippets follow, I could send corresponding samba logs with
loglevel = 3 if needed.
Best regards,
Roman
syslog snippet 0:
eth0 lines on /etc/network/interfaces
managed = false on /etc/NetworkManager/nm-system-settings.conf
==============================================================
Jan 26 12:00:32 u034755 kernel: imklog 4.2.0, log source =
/var/run/rsyslog/kmsg started.
Jan 26 12:00:32 u034755 NetworkManager: <info> starting...
Jan 26 12:00:32 u034755 NetworkManager: <info> Trying to start the
modem-manager...
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: init!
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown:
update_system_hostname
Jan 26 12:00:32 u034755 NetworkManager: SCPluginIfupdown: guessed
connection type (eth0) = 802-3-ethernet
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown:
update_connection_setting_from_if_block: name:eth0, type:802-3-ethernet,
id:Ifupdown (eth0), uuid: 681b428f-beaf-8932-dce4-687ed5bae28e
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: autoconnect
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Nokia
Jan 26 12:00:32 u034755 NetworkManager: SCPluginIfupdown: management
mode: unmanaged
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Sierra
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: devices
added (path: /sys/devices/pci0000:00/0000:00:1c.4/0000:04:00.0/net/eth0,
iface: eth0)
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Ericsson MBM
Jan 26 12:00:32 u034755 NetworkManager: SCPluginIfupdown: locking
wired connection setting
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Generic
Jan 26 12:00:32 u034755 NetworkManager: Ifupdown: get unmanaged
devices count: 1
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Huawei
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown:
(155470576) ... get_connections.
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin ZTE
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown:
(155470576) ... get_connections (managed=false): return empty list.
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Option
Jan 26 12:00:32 u034755 NetworkManager: Ifupdown: get unmanaged
devices count
: 1
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Gobi
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/lo, iface: lo)
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin MotoC
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: device
added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown
configuration found.
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Option High-Speed
Jan 26 12:00:32 u034755 NetworkManager: SCPlugin-Ifupdown: end _init.
Jan 26 12:00:32 u034755 modem-manager: Loaded plugin Novatel
Jan 26 12:00:32 u034755 NetworkManager: Loaded plugin ifupdown: (C) 2008
Canonical Ltd. To report bugs please use the NetworkManager mailing list.
Jan 26 12:00:32 u034755 NetworkManager: Loaded plugin keyfile: (c) 2007
- 2008 Red Hat, Inc. To report bugs please use the NetworkManager
mailing list.
Jan 26 12:00:32 u034755 NetworkManager: <info> Wireless now enabled by
radio killswitch
Jan 26 12:00:32 u034755 NetworkManager: <info> (eth0): carrier is ON
Jan 26 12:00:32 u034755 NetworkManager: <info> (eth0): new Ethernet
device (driver: 'r8169')
Jan 26 12:00:32 u034755 NetworkManager: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/0
Jan 26 12:00:32 u034755 kernel: [ 0.000000] Linux version
2.6.31-17-generic (buildd at palmer) (gcc version 4.4.1 (Ubuntu
4.4.1-4ubuntu8) ) #54-Ubuntu SMP Thu Dec 10 16:20:31 UTC 2009 (Ubuntu
2.6.31-17.54-generic)
Jan 26 12:00:32 u034755 NetworkManager: <info> modem-manager is now
available
Jan 26 12:00:32 u034755 NetworkManager: <WARN> default_adapter_cb():
bluez error getting default adapter: The name org.bluez was not provided
by any .service files
Jan 26 12:00:32 u034755 NetworkManager: <info> Trying to start the
supplicant...
Jan 26 12:00:32 u034755 init: apport pre-start process (1117) terminated
with status 1
Jan 26 12:00:32 u034755 cron[1138]: (CRON) INFO (pidfile fd = 3)
Jan 26 12:00:32 u034755 anacron[1194]: Anacron 2.3 started on 2010-01-26
Jan 26 12:00:32 u034755 init: apport post-stop process (1163) terminated
with status 1
Jan 26 12:00:32 u034755 cron[1198]: (CRON) STARTUP (fork ok)
Jan 26 12:00:32 u034755 cron[1198]: (CRON) INFO (Running @reboot jobs)
Jan 26 12:00:32 u034755 anacron[1194]: Normal exit (0 jobs run)
Jan 26 12:00:33 u034755 dhclient: Listening on LPF/eth0/00:1f:d0:82:04:22
Jan 26 12:00:33 u034755 dhclient: Sending on LPF/eth0/00:1f:d0:82:04:22
Jan 26 12:00:33 u034755 dhclient: Sending on Socket/fallback
Jan 26 12:00:33 u034755 dhclient: DHCPDISCOVER on eth0 to
255.255.255.255 port 67 interval 6
Jan 26 12:00:33 u034755 nmbd[1295]: [2010/01/26 12:00:33, 0]
nmbd/nmbd_subnetdb.c:206(create_subnets)
Jan 26 12:00:33 u034755 nmbd[1295]: create_subnets: No local IPv4
non-loopback interfaces !
Jan 26 12:00:33 u034755 nmbd[1295]: [2010/01/26 12:00:33, 0]
nmbd/nmbd_subnetdb.c:207(create_subnets)
Jan 26 12:00:33 u034755 nmbd[1295]: create_subnets: Waiting for an
interface to appear ...
Jan 26 12:00:33 u034755 smbd[1297]: [2010/01/26 12:00:33, 0]
printing/print_cups.c:103(cups_connect)
Jan 26 12:00:33 u034755 smbd[1297]: Unable to connect to CUPS server
localhost:631 - Connection refused
Jan 26 12:00:33 u034755 smbd[1299]: [2010/01/26 12:00:33, 0]
printing/print_cups.c:103(cups_connect)
Jan 26 12:00:33 u034755 smbd[1299]: Unable to connect to CUPS server
localhost:631 - Connection refused
Jan 26 12:00:33 u034755 smbd[1300]: [2010/01/26 12:00:33, 0]
libsmb/nmblib.c:834(send_udp)
Jan 26 12:00:33 u034755 smbd[1300]: Packet send failed to
10.16.15.1(137) ERRNO=Network is unreachable
Jan 26 12:00:33 u034755 smbd[1300]: [2010/01/26 12:00:33, 0]
printing/nt_printing.c:627(nt_printing_init)
Jan 26 12:00:33 u034755 smbd[1300]: nt_printing_init: error checking
published printers: WERR_ACCESS_DENIED
Jan 26 12:00:33 u034755 smbd[1300]: [2010/01/26 12:00:33, 0]
smbd/server.c:456(smbd_open_one_socket)
Jan 26 12:00:33 u034755 smbd[1300]: smbd_open_once_socket:
open_socket_in: Address already in use
Jan 26 12:00:33 u034755 smbd[1300]: [2010/01/26 12:00:33, 0]
smbd/server.c:456(smbd_open_one_socket)
Jan 26 12:00:33 u034755 smbd[1300]: smbd_open_once_socket:
open_socket_in: Address already in use
Jan 26 12:00:33 u034755 winbindd[1339]: [2010/01/26 12:00:33, 0]
winbindd/winbindd_cache.c:2578(initialize_winbindd_cache)
Jan 26 12:00:33 u034755 winbindd[1339]: initialize_winbindd_cache:
clearing cache and re-creating with version number 1
Jan 26 12:00:33 u034755 ntpd[1361]: ntpd 4.2.4p6 at 1.1549-o Fri Dec 4
18:08:42 UTC 2009 (1)
Jan 26 12:00:33 u034755 ntpd[1362]: precision = 1.000 usec
Jan 26 12:00:33 u034755 ntpd[1362]: Listening on interface #0 wildcard,
0.0.0.0#123 Disabled
Jan 26 12:00:33 u034755 ntpd[1362]: Listening on interface #1 wildcard,
::#123 Disabled
Jan 26 12:00:33 u034755 ntpd[1362]: Listening on interface #2 lo,
::1#123 Enabled
Jan 26 12:00:33 u034755 ntpd[1362]: bind() fd 19, family 10, port 123,
scope 2
, addr fe80::21f:d0ff:fe82:422, in6_is_addr_multicast=0 flags=0x11
fails: Cannot assign requested address
Jan 26 12:00:33 u034755 ntpd[1362]: unable to create socket on eth0 (3)
for fe80::21f:d0ff:fe82:422#123
Jan 26 12:00:33 u034755 ntpd[1362]: failed to initialize interface for
address fe80::21f:d0ff:fe82:422
Jan 26 12:00:33 u034755 ntpd[1362]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Jan 26 12:00:33 u034755 ntpd[1362]: kernel time sync status 2040
Jan 26 12:00:33 u034755 ntpd[1362]: frequency initialized -31.871 PPM
from /var/lib/ntp/ntp.drift
Jan 26 12:00:33 u034755 ntpd[1362]: Warning: the "dynamic" keyword has
been obsoleted and will be removed in the next release
Jan 26 12:00:33 u034755 winbindd[1341]: [2010/01/26 12:00:33, 0]
libsmb/nmblib.c:834(send_udp)
Jan 26 12:00:33 u034755 winbindd[1341]: Packet send failed to
10.16.15.1(137) ERRNO=Network is unreachable
Jan 26 12:00:33 u034755 dhclient: DHCPOFFER of 10.16.13.92 from 10.16.13.100
Jan 26 12:00:33 u034755 dhclient: DHCPREQUEST of 10.16.13.92 on eth0 to
255.255.255.255 port 67
Jan 26 12:00:33 u034755 dhclient: DHCPACK of 10.16.13.92 from 10.16.13.100
Jan 26 12:00:33 u034755 dhclient: bound to 10.16.13.92 -- renewal in
320632 seconds.
Jan 26 12:00:33 u034755 ntpd[1362]: ntpd exiting on signal 15
Jan 26 12:00:33 u034755 ntpdate[1647]: adjust time server 10.16.15.1
offset 0.369700 sec
Jan 26 12:00:33 u034755 ntpd[1675]: ntpd 4.2.4p6 at 1.1549-o Fri Dec 4
18:08:42 UTC 2009 (1)
Jan 26 12:00:33 u034755 ntpd[1676]: precision = 1.000 usec
Jan 26 12:00:33 u034755 ntpd[1676]: Listening on interface #0 wildcard,
0.0.0.0#123 Disabled
Jan 26 12:00:33 u034755 ntpd[1676]: Listening on interface #1 wildcard,
::#123 Disabled
Jan 26 12:00:33 u034755 ntpd[1676]: Listening on interface #2 lo,
::1#123 Enabled
Jan 26 12:00:33 u034755 ntpd[1676]: bind() fd 19, family 10, port 123,
scope 2, addr fe80::21f:d0ff:fe82:422, in6_is_addr_multicast=0
flags=0x11 fails: Cannot assign requested address
Jan 26 12:00:33 u034755 ntpd[1676]: unable to create socket on eth0 (3)
for fe80::21f:d0ff:fe82:422#123
Jan 26 12:00:33 u034755 ntpd[1676]: failed to initialize interface for
address fe80::21f:d0ff:fe82:422
Jan 26 12:00:33 u034755 ntpd[1676]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Jan 26 12:00:33 u034755 ntpd[1676]: Listening on interface #5 eth0,
10.16.13.92#123 Enabled
Jan 26 12:00:33 u034755 ntpd[1676]: kernel time sync status 2040
Jan 26 12:00:33 u034755 ntpd[1676]: frequency initialized -31.871 PPM
from /var/lib/ntp/ntp.drift
Jan 26 12:00:33 u034755 ntpd[1676]: Warning: the "dynamic" keyword has
been obsoleted and will be removed in the next release
Jan 26 12:01:00 u034755 NetworkManager: <info> Unmanaged Device found;
state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Jan 26 12:01:00 u034755 NetworkManager: <info> Unmanaged Device found;
state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Jan 26 12:01:19 u034755 kernel: [ 59.156097] CIFS VFS: Send error in
SessSetup = -13
Jan 26 12:01:19 u034755 kernel: [ 59.156148] CIFS VFS: cifs_mount
failed w/return code = -13
Jan 26 12:01:19 u034755 kernel: [ 59.158989] Status code returned
0xc000006d NT_STATUS_LOGON_FAILURE
Jan 26 12:01:19 u034755 kernel: [ 59.158995] CIFS VFS: Send error in
SessSetup = -13
Jan 26 12:01:19 u034755 kernel: [ 59.159042] CIFS VFS: cifs_mount
failed w/return code = -13
Jan 26 12:08:34 u034755 kernel: Kernel logging (proc) stopped.
=======================================================================
syslog snippet 1:
*no* eth0 lines on /etc/network/interfaces
managed = false on /etc/NetworkManager/nm-system-settings.conf
==============================================================
Jan 26 12:09:24 u034755 rsyslogd: [origin software="rsyslogd"
swVersion="4.2.0" x-pid="877" x-info="http://www.rsyslog.com"] (re)start
Jan 26 12:09:24 u034755 kernel: [ 0.000000] Linux version
2.6.31-17-generic (buildd at palmer) (gcc version 4.4.1 (Ubuntu
4.4.1-4ubuntu8) ) #54-Ubuntu SMP Thu Dec 10 16:20:31 UTC 2009 (Ubuntu
2.6.31-17.54-generic)
Jan 26 12:09:24 u034755 NetworkManager: <info> starting...
Jan 26 12:09:24 u034755 NetworkManager: <info> Trying to start the
modem-manager...
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Nokia
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Sierra
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Ericsson MBM
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Generic
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Huawei
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin ZTE
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Option
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Gobi
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin MotoC
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Option High-Speed
Jan 26 12:09:24 u034755 modem-manager: Loaded plugin Novatel
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: init!
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown:
update_system_hostname
Jan 26 12:09:24 u034755 NetworkManager: SCPluginIfupdown: management
mode: unmanaged
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: devices
added (path: /sys/devices/pci0000:00/0000:00:1c.4/0000:04:00.0/net/eth0,
iface: eth0)
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: device
added (path: /sys/devices/pci0000:00/0000:00:1c.4/0000:04:00.0/net/eth0,
iface: eth0): no ifupdown configuration found.
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/lo, iface: lo)
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: device
added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown
configuration found.
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown: end _init.
Jan 26 12:09:24 u034755 NetworkManager: Loaded plugin ifupdown: (C) 2008
Canonical Ltd. To report bugs please use the NetworkManager mailing list.
Jan 26 12:09:24 u034755 NetworkManager: Loaded plugin keyfile: (c) 2007
- 2008 Red Hat, Inc. To report bugs please use the NetworkManager
mailing list.
Jan 26 12:09:24 u034755 NetworkManager: <info> Wireless now enabled by
radio killswitch
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown:
(153344752) ... get_connections.
Jan 26 12:09:24 u034755 NetworkManager: SCPlugin-Ifupdown:
(153344752) ... get_connections (managed=false): return empty list.
Jan 26 12:09:24 u034755 NetworkManager: Ifupdown: get unmanaged
devices count: 0
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): carrier is OFF
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): new Ethernet
device (driver: 'r8169')
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/0
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): now managed
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): device state
change: 1 -> 2 (reason 2)
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): bringing up device.
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): preparing device.
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): deactivating
device (reason: 2).
Jan 26 12:09:24 u034755 NetworkManager: Added default wired connection
'Auto eth0' for /sys/devices/pci0000:00/0000:00:1c.4/0000:04:00.0/net/eth0
Jan 26 12:09:24 u034755 kernel: [ 11.837153] r8169: eth0: link up
Jan 26 12:09:24 u034755 kernel: [ 11.837159] r8169: eth0: link up
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): carrier now ON
(device state 2)
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): device state
change: 2 -> 3 (reason 40)
Jan 26 12:09:24 u034755 NetworkManager: <info> modem-manager is now
available
Jan 26 12:09:24 u034755 NetworkManager: <WARN> default_adapter_cb():
bluez error getting default adapter: The name org.bluez was not provided
by any .service files
Jan 26 12:09:24 u034755 NetworkManager: <info> Trying to start the
supplicant
...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0)
starting connection 'Auto eth0'
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): device state
change: 3 -> 4 (reason 0)
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
1 of 5 (Device Prepare) scheduled...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
1 of 5 (Device Prepare) started...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
2 of 5 (Device Configure) scheduled...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
1 of 5 (Device Prepare) complete.
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
2 of 5 (Device Configure) starting...
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): device state
change: 4 -> 5 (reason 0)
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
2 of 5
(Device Configure) successful.
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
3 of 5 (IP Configure Start) scheduled.
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
2 of 5 (Device Configure) complete.
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
3 of 5 (IP Configure Start) started...
Jan 26 12:09:24 u034755 NetworkManager: <info> (eth0): device state
change: 5 -> 7 (reason 0)
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0)
Beginning DHCP transaction (timeout in 45 seconds)
Jan 26 12:09:24 u034755 dhclient: Internet Systems Consortium DHCP
Client V3.1.2
Jan 26 12:09:24 u034755 dhclient: Copyright 2004-2008 Internet Systems
Consortium.
Jan 26 12:09:24 u034755 dhclient: All rights reserved.
Jan 26 12:09:24 u034755 dhclient: For info, please visit
http://www.isc.org/sw/dhcp/
Jan 26 12:09:24 u034755 dhclient:
Jan 26 12:09:24 u034755 NetworkManager: <info> dhclient started with
pid 983
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP6 Configure Get) scheduled...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
3 of 5 (IP Configure Start) complete.
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP6 Configure Get) started...
Jan 26 12:09:24 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP6 Configure Get) complete.
Jan 26 12:09:24 u034755 NetworkManager: <info> DHCP: device eth0 state
changed (null) -> preinit
Jan 26 12:09:24 u034755 dhclient: Listening on LPF/eth0/00:1f:d0:82:04:22
Jan 26 12:09:24 u034755 dhclient: Sending on LPF/eth0/00:1f:d0:82:04:22
Jan 26 12:09:24 u034755 dhclient: Sending on Socket/fallback
Jan 26 12:09:25 u034755 nmbd[1292]: [2010/01/26 12:09:25, 0]
nmbd/nmbd_subnetdb.c:206(create_subnets)
Jan 26 12:09:25 u034755 nmbd[1292]: create_subnets: No local IPv4
non-loopback interfaces !
Jan 26 12:09:25 u034755 nmbd[1292]: [2010/01/26 12:09:25, 0]
nmbd/nmbd_subnetdb.c:207(create_subnets)
Jan 26 12:09:25 u034755 nmbd[1292]: create_subnets: Waiting for an
interface to appear ...
Jan 26 12:09:25 u034755 smbd[1294]: [2010/01/26 12:09:25, 0]
printing/print_cups.c:103(cups_connect)
Jan 26 12:09:25 u034755 smbd[1294]: Unable to connect to CUPS server
localhost:631 - Connection refused
Jan 26 12:09:25 u034755 smbd[1296]: [2010/01/26 12:09:25, 0]
printing/print_cups.c:103(cups_connect)
Jan 26 12:09:25 u034755 smbd[1296]: Unable to connect to CUPS server
localhost:631 - Connection refused
Jan 26 12:09:25 u034755 smbd[1297]: [2010/01/26 12:09:25, 0]
libsmb/nmblib.c:834(send_udp)
Jan 26 12:09:25 u034755 smbd[1297]: Packet send failed to
10.16.15.1(137) ERRNO=Network is unreachable
Jan 26 12:09:25 u034755 smbd[1297]: [2010/01/26 12:09:25, 0]
printing/nt_printing.c:627(nt_printing_init)
Jan 26 12:09:25 u034755 smbd[1297]: nt_printing_init: error checking
published printers: WERR_ACCESS_DENIED
Jan 26 12:09:25 u034755 smbd[1297]: [2010/01/26 12:09:25, 0]
smbd/server.c:456(smbd_open_one_socket)
Jan 26 12:09:25 u034755 smbd[1297]: smbd_open_once_socket:
open_socket_in: Address already in use
Jan 26 12:09:25 u034755 smbd[1297]: [2010/01/26 12:09:25, 0]
smbd/server.c:456(smbd_open_one_socket)
Jan 26 12:09:25 u034755 smbd[1297]: smbd_open_once_socket:
open_socket_in: Address already in use
Jan 26 12:09:25 u034755 winbindd[1336]: [2010/01/26 12:09:25, 0]
winbindd/winbindd_cache.c:2578(initialize_winbindd_cache)
Jan 26 12:09:25 u034755 winbindd[1336]: initialize_winbindd_cache:
clearing cache and re-creating with version number 1
Jan 26 12:09:25 u034755 ntpd[1358]: ntpd 4.2.4p6 at 1.1549-o Fri Dec 4
18:08:42 UTC 2009 (1)
Jan 26 12:09:25 u034755 ntpd[1359]: precision = 1.000 usec
Jan 26 12:09:25 u034755 ntpd[1359]: Listening on interface #0 wildcard,
0.0.0.0#123 Disabled
Jan 26 12:09:25 u034755 ntpd[1359]: Listening on interface #1 wildcard,
::#123 Disabled
Jan 26 12:09:25 u034755 ntpd[1359]: Listening on interface #2 lo,
::1#123 Enabled
Jan 26 12:09:25 u034755 ntpd[1359]: bind() fd 19, family 10, port 123,
scope 2, addr fe80::21f:d0ff:fe82:422, in6_is_addr_multicast=0
flags=0x11 fails: Cannot assign requested address
Jan 26 12:09:25 u034755 ntpd[1359]: unable to create socket on eth0 (3)
for fe80::21f:d0ff:fe82:422#123
Jan 26 12:09:25 u034755 ntpd[1359]: failed to initialize interface for
address fe80::21f:d0ff:fe82:422
Jan 26 12:09:25 u034755 ntpd[1359]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Jan 26 12:09:25 u034755 ntpd[1359]: kernel time sync status 2040
Jan 26 12:09:25 u034755 ntpd[1359]: frequency initialized -31.871 PPM
from /var/lib/ntp/ntp.drift
Jan 26 12:09:25 u034755 ntpd[1359]: Warning: the "dynamic" keyword has
been obsoleted and will be removed in the next release
Jan 26 12:09:25 u034755 winbindd[1337]: [2010/01/26 12:09:25, 0]
libsmb/nmblib.c:834(send_udp)
Jan 26 12:09:25 u034755 winbindd[1337]: Packet send failed to
10.16.15.1(137) ERRNO=Network is unreachable
Jan 26 12:09:26 u034755 ntpd[1359]: Listening on interface #5 eth0,
fe80::21f:d0ff:fe82:422#123 Enabled
Jan 26 12:09:28 u034755 dhclient: DHCPDISCOVER on eth0 to
255.255.255.255 port 67 interval 3
Jan 26 12:09:28 u034755 dhclient: DHCPOFFER of 10.16.13.92 from 10.16.13.100
Jan 26 12:09:28 u034755 dhclient: DHCPREQUEST of 10.16.13.92 on eth0 to
255.255.255.255 port 67
Jan 26 12:09:28 u034755 dhclient: DHCPACK of 10.16.13.92 from 10.16.13.100
Jan 26 12:09:28 u034755 NetworkManager: <info> DHCP: device eth0 state
changed preinit -> bound
Jan 26 12:09:28 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP4 Configure Get) scheduled...
Jan 26 12:09:28 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP4 Configure Get) started...
Jan 26 12:09:28 u034755 NetworkManager: <info> address 10.16.13.92
Jan 26 12:09:28 u034755 NetworkManager: <info> prefix 24 (255.255.255.0)
Jan 26 12:09:28 u034755 NetworkManager: <info> gateway 10.16.13.100
Jan 26 12:09:28 u034755 NetworkManager: <info> nameserver '10.16.15.1'
Jan 26 12:09:28 u034755 NetworkManager: <info> nameserver
'212.142.144.66'
Jan 26 12:09:28 u034755 NetworkManager: <info> nameserver
'212.142.144.98'
Jan 26 12:09:28 u034755 NetworkManager: <info> domain name 'H013085'
Jan 26 12:09:28 u034755 NetworkManager: <info> wins '10.16.15.1'
Jan 26 12:09:28 u034755 NetworkManager: <info> Activation (eth0) Stage
5 of 5 (IP Configure Commit) scheduled...
Jan 26 12:09:28 u034755 NetworkManager: <info> Activation (eth0) Stage
4 of 5 (IP4 Configure Get) complete.
Jan 26 12:09:28 u034755 NetworkManager: <info> Activation (eth0) Stage
5 of 5 (IP Configure Commit) started...
Jan 26 12:09:28 u034755 dhclient: bound to 10.16.13.92 -- renewal in
332505 seconds.
Jan 26 12:09:29 u034755 NetworkManager: <info> (eth0): device state
change: 7 -> 8 (reason 0)
Jan 26 12:09:29 u034755 NetworkManager: <info> Policy set 'Auto eth0'
(eth0) as default for routing and DNS.
Jan 26 12:09:29 u034755 NetworkManager: <info> Activation (eth0)
successful, device activated.
Jan 26 12:09:29 u034755 NetworkManager: <info> Activation (eth0) Stage
5 of 5 (IP Configure Commit) complete.
Jan 26 12:09:29 u034755 ntpd[1359]: ntpd exiting on signal 15
Jan 26 12:09:29 u034755 ntpdate[1836]: adjust time server 10.16.15.1
offset 0.237228 sec
Jan 26 12:09:29 u034755 ntpd[1866]: ntpd 4.2.4p6 at 1.1549-o Fri Dec 4
18:08:42 UTC 2009 (1)
Jan 26 12:09:29 u034755 ntpd[1867]: precision = 1.000 usec
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #0 wildcard,
0.0.0.0#123 Disabled
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #1 wildcard,
::#123 Disabled
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #2 lo,
::1#123 Enabled
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #3 eth0,
fe80::21f:d0ff:fe82:422#123 Enabled
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Jan 26 12:09:29 u034755 ntpd[1867]: Listening on interface #5 eth0,
10.16.13.92#123 Enabled
Jan 26 12:09:29 u034755 ntpd[1867]: kernel time sync status 2040
Jan 26 12:09:29 u034755 ntpd[1867]: frequency initialized -31.871 PPM
from /var/lib/ntp/ntp.drift
Jan 26 12:09:29 u034755 ntpd[1867]: Warning: the "dynamic" keyword has
been obsoleted and will be removed in the next release
Jan 26 12:10:28 u034755 kernel: [ 75.366493] Status code returned
0xc000006d NT_STATUS_LOGON_FAILURE
Jan 26 12:10:28 u034755 kernel: [ 75.366500] CIFS VFS: Send error in
SessSetup = -13
Jan 26 12:10:28 u034755 kernel: [ 75.366548] CIFS VFS: cifs_mount
failed w/return code = -13
Jan 26 12:10:28 u034755 kernel: [ 75.369347] Status code returned
0xc000006d NT_STATUS_LOGON_FAILURE
Jan 26 12:10:28 u034755 kernel: [ 75.369353] CIFS VFS: Send error in
SessSetup = -13
Jan 26 12:10:28 u034755 kernel: [ 75.369399] CIFS VFS: cifs_mount
failed w/return code = -13
Jan 26 12:11:08 u034755 NetworkManager: Ifupdown: get unmanaged
devices count: 0
Jan 26 12:11:19 u034755 kernel: Kernel logging (proc) stopped.
============================================================================
Thierry Carrez(e)k dio:
> I'm not surprised that user networking setup, as defined by NM/WICD, is
> not available until the fully user logs in... so winbind won't work
> until then, chicken and egg.
>
> I'm more surprised that winbind fails when systemwide networking is defined though.
> I suppose the syslog snippet you attached comes from a DHCP configuration defined in /etc/networking/interfaces ?
> If not, could you test that ?
> Also could you try with static networking instead and let us know how that goes ?
>
> ** Changed in: samba (Ubuntu)
> Importance: Undecided => Medium
>
> ** Changed in: samba (Ubuntu)
> Status: New => Incomplete
>
> ** Summary changed:
>
> - first login fails when NIC is managed by NM-wicd
> + [winbind] first login fails when NIC is managed by NM-wicd
>
--
[winbind] first login fails when NIC is managed by NM-wicd
https://bugs.launchpad.net/bugs/512777
You received this bug notification because you are a member of Ubuntu
Server Team, which is subscribed to samba in ubuntu.
More information about the Ubuntu-server-bugs
mailing list