Re: Ad-hoc network: cannot ping other machine

[Dan Williams <dcbw redhat com>]

On Mon, 2011-10-10 at 21:03 -0500, Patrick McMunn wrote:
> I rebooted and attempted to connect after reboot before connecting to
> the internet. Here's the relevant parts of /var/log/messages
> 
> (This line about Avahi was a bit earlier in the log, but I'm including
> it in case it's relevant)

Oct 10 20:50:32 localhost dnsmasq[5359]: failed to create listening
socket for 10.42.43.1: Address already in use
Oct 10 20:50:32 localhost dnsmasq[5359]: FAILED to start up

This is the problem.  And it's a problem with dnsmasq packaging on
distros in general.  What's happening here is that NM is spawning
dnsmasq for its private use with specific options that aren't going to
be in the global dnsmasq config file.  dnsmasq isn't just a global
service, it gets spawned on demand by various programs including NM for
specific tasks like this.

So when dnsmasq got installed on your machine, it installed a global
config file in /etc/dnsmasq.conf and then enabled itself as a service,
and now there's a global dnsmasq running (which we probably don't need)
and it's tried to grab all addresses for itself.  That conflicts with
the specific dnsmasq instance that NM tries to spawn for sharing.

So what's in your /etc/dnsmasq.conf file?  It's likely something like
'bind-interfaces' or 'listen-address=*" or something like that.  dnsmasq
instances should always use bind-interfaces *and* a specific listen
address to make sure they don't interfere with other dnsmasq instances.

This is what makes networking fun :(

Dan

> Oct 10 20:49:07 localhost dbus[4111]: [system] Failed to activate
> service 'org.freedesktop.Avahi': timed out
> 
> 
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) starting connection 'share'
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: disconnected -> prepare (reason 'none') [30 40 0]
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 1 of 5 (Device Prepare) started...
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 2 of 5 (Device Configure) starting...
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: prepare -> config (reason 'none') [40 50 0]
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0/wireless): connection 'share' requires no security.  No secrets
> needed.
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Config: added
> 'ssid' value 'share'
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Config: added
> 'mode' value '1'
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Config: added
> 'frequency' value '2457'
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Config: added
> 'key_mgmt' value 'NONE'
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 2 of 5 (Device Configure) complete.
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> Config: set
> interface ap_scan to 2
> Oct 10 20:50:20 localhost kernel: [  212.847047] b43-phy0: Loading
> firmware version 478.104 (2008-07-01 00:50:23)
> Oct 10 20:50:20 localhost kernel: [  212.896155] wlan0: Trigger new
> scan to find an IBSS to join
> Oct 10 20:50:20 localhost NetworkManager[4128]: <info> (wlan0):
> supplicant interface state: inactive -> associating
> Oct 10 20:50:25 localhost kernel: [  218.004183] wlan0: Trigger new
> scan to find an IBSS to join
> Oct 10 20:50:29 localhost kernel: [  222.008033] wlan0: Trigger new
> scan to find an IBSS to join
> Oct 10 20:50:30 localhost kernel: [  223.090029] wlan0: no IPv6 routers present
> Oct 10 20:50:30 localhost kernel: [  223.303136] wlan0: Creating new
> IBSS network, BSSID aa:2e:d1:95:8d:23
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> (wlan0):
> supplicant interface state: associating -> completed
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.
> Connected to wireless network 'share'.
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 3 of 5 (IP Configure Start) started...
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: config -> ip-config (reason 'none') [50 70 0]
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 3 of 5 (IP Configure Start) complete.
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
> Oct 10 20:50:30 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 5 of 5 (IP Configure Commit) started...
> Oct 10 20:50:31 localhost NetworkManager[4128]: <info> (wlan0):
> removing resolv.conf from /sbin/resolvconf
> Oct 10 20:50:31 localhost NetworkManager[4128]: <info> Clearing nscd
> hosts cache.
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert INPUT --in-interface wlan0
> --protocol tcp --destination-port 53 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert INPUT --in-interface wlan0
> --protocol udp --destination-port 53 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert INPUT --in-interface wlan0
> --protocol tcp --destination-port 67 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert INPUT --in-interface wlan0
> --protocol udp --destination-port 67 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert FORWARD --in-interface wlan0
> --jump REJECT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert FORWARD --out-interface wlan0
> --jump REJECT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert FORWARD --in-interface wlan0
> --out-interface wlan0 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert FORWARD --source
> 10.42.43.0/255.255.255.0 --in-interface wlan0 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --insert FORWARD --destination
> 10.42.43.0/255.255.255.0 --out-interface wlan0 --match state --state
> ESTABLISHED,RELATED --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table nat --insert POSTROUTING --source
> 10.42.43.0/255.255.255.0 ! --destination 10.42.43.0/255.255.255.0
> --jump MASQUERADE
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Starting dnsmasq...
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: ip-config -> activated (reason 'none') [70 100 0]
> Oct 10 20:50:32 localhost dnsmasq[5359]: failed to create listening
> socket for 10.42.43.1: Address already in use
> Oct 10 20:50:32 localhost dnsmasq[5359]: FAILED to start up
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Activation
> (wlan0) successful, device activated.
> Oct 10 20:50:32 localhost dbus[4111]: [system] Activating service
> name='org.freedesktop.nm_dispatcher' (using servicehelper)
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Activation
> (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
> Oct 10 20:50:32 localhost NetworkManager[4128]: <warn> dnsmasq exited
> with error: Network access problem (address in use; permissions; etc)
> (2)
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: activated -> failed (reason 'sharing-start-failed') [100
> 120 18]
> Oct 10 20:50:32 localhost NetworkManager[4128]: <warn> Activation
> (wlan0) failed for access point (share)
> Oct 10 20:50:32 localhost NetworkManager[4128]: <warn> Activation
> (wlan0) failed.
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0): device
> state change: failed -> disconnected (reason 'none') [120 30 0]
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0):
> deactivating device (reason 'none') [0]
> Oct 10 20:50:32 localhost NetworkManager[4128]: <error>
> [1318297832.295257] [nm-device-wifi.c:1815] nm_device_wifi_set_mode():
> (wlan0): error setting mode 2
> Oct 10 20:50:32 localhost dnsmasq[4477]: no servers found in
> /etc/resolv.conf, will retry
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0):
> removing resolv.conf from /sbin/resolvconf
> Oct 10 20:50:32 localhost dbus[4111]: [system] Successfully activated
> service 'org.freedesktop.nm_dispatcher'
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Clearing nscd
> hosts cache.
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table nat --delete POSTROUTING --source
> 10.42.43.0/255.255.255.0 ! --destination 10.42.43.0/255.255.255.0
> --jump MASQUERADE
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete FORWARD --destination
> 10.42.43.0/255.255.255.0 --out-interface wlan0 --match state --state
> ESTABLISHED,RELATED --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete FORWARD --source
> 10.42.43.0/255.255.255.0 --in-interface wlan0 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete FORWARD --in-interface wlan0
> --out-interface wlan0 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete FORWARD --out-interface wlan0
> --jump REJECT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete FORWARD --in-interface wlan0
> --jump REJECT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete INPUT --in-interface wlan0
> --protocol udp --destination-port 67 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete INPUT --in-interface wlan0
> --protocol tcp --destination-port 67 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete INPUT --in-interface wlan0
> --protocol udp --destination-port 53 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> Executing:
> /sbin/iptables --table filter --delete INPUT --in-interface wlan0
> --protocol tcp --destination-port 53 --jump ACCEPT
> Oct 10 20:50:32 localhost NetworkManager[4128]: <info> (wlan0):
> supplicant interface state: completed -> disconnected
> _______________________________________________
> networkmanager-list mailing list
> networkmanager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list