[SOLVED]bridge issue: bridged interface disconnected

Ryu · Jan 14, 2015, 3:12 PM

hi to all,
i have an annoying issue from some months that i don't understand.
My home pc is connected to an interface that belongs to a bridge (which contains other 3 interfaces). Sometimes, my pc interface becomes unable to reach its default gateway even if it is physically up and on pfsense dashboard no errors are reported (status-interfaces). The only solution that i found is disable and re-enable the interface from pfsense dashboard.
This happens often when i power on my pc.
On systems logs i have these entries when i power on the pc

php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
php: rc.newwanip: rc.newwanip: on (IP address: ) (interface: TRUST_HOMEPC[opt2]) (real interface: em2).
php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )

my home net bridge interface is configured like that

bridge interface 192.168.10.1: HOME PC 192.168.10.2
decoder and TV 192.168.10.3,192.168.10.4
server 192.168.10.100
Wifi access point 192.168.10.50

the pfsense arp table is populated correctly for 192.168.10.2 but my PC doesn't have the arp entry for 192.168.10.1.
Trying to ping 192.168.10.1 and capturing traffic with wireshark/tcpdump i see that:

on my pc i see arp request but not arp replies
tcpdump on pfsense bridge interface shows the arp requests and also replies
tcpdump on pfsense home pc interface only shows the received arp requests

My little infrastructure is online from over 1 year but this issue is present from only two month or less. Maybe is related with some change that i did but i don't understand which one.
Has some one encountered a similar issue?
Thanks

stephenw10 · Dec 7, 2014, 6:48 PM

Which pfSense version? There was an issue with bridged em interfaces a few versions back.
So you have the bridge interface itself assigned as 192.168.10.1 and the bridge member interfaces have IP type 'none'?

Steve

Ryu · Dec 7, 2014, 8:03 PM

@stephenw10:

Which pfSense version? There was an issue with bridged em interfaces a few versions back.

i'm running 2.1.5.

@stephenw10:

So you have the bridge interface itself assigned as 192.168.10.1 and the bridge member interfaces have IP type 'none'?

Steve

exactly

stephenw10 · Dec 8, 2014, 2:18 PM

Ok. So what is shown in the system logs when you powerup your connected PC? Is it different to what is shown if you plug/unplug the ethernet cable after it's powered up. I assume that if the client is already on when pfSense is powered then there's no problem?

Steve

Ryu · Dec 8, 2014, 10:39 PM

Thanky you stephen for support.
My pfsense box is always powered up, it's my pc that is powered on/off often. Sometimes the issue appears sometime no.
Anyway i tried to save logs first unplugging and plug pc cable

Dec 8 23:24:48 php: rc.start_packages: Restarting/Starting all packages.
Dec 8 23:24:46 check_reload_status: Reloading filter
Dec 8 23:24:46 check_reload_status: Starting packages
Dec 8 23:24:46 php: rc.newwanip: pfSense package system has detected an ip change 0.0.0.0 -> … Restarting packages.
Dec 8 23:24:44 php: rc.newwanip: Creating rrd update script
Dec 8 23:24:44 php: rc.newwanip: Resyncing OpenVPN instances for interface TRUST_HOMEPC.
Dec 8 23:24:44 php: rc.newwanip: Ignoring IPsec racoon daemon reload since there are no tunnels on interface opt2
Dec 8 23:24:43 php: rc.newwanip: rc.newwanip: on (IP address: ) (interface: TRUST_HOMEPC[opt2]) (real interface: em2).
Dec 8 23:24:43 php: rc.newwanip: rc.newwanip: Informational is starting em2.
Dec 8 23:24:41 check_reload_status: rc.newwanip starting em2
Dec 8 23:24:41 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:24:38 kernel: em2: link state changed to UP
Dec 8 23:24:38 check_reload_status: Linkup starting em2
Dec 8 23:24:29 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:24:27 kernel: em2: link state changed to DOWN
Dec 8 23:24:27 check_reload_status: Linkup starting em2

power off / power on logs

Dec 8 23:29:10 php: rc.filter_configure_sync: Sending HUP signal to 48213
Dec 8 23:29:10 php: rc.filter_configure_sync: Sending HUP signal to 47758
Dec 8 23:29:10 php: rc.filter_configure_sync: Sending HUP signal to 47378
Dec 8 23:29:10 php: rc.filter_configure_sync: Sending HUP signal to 46987
Dec 8 23:29:10 php: rc.filter_configure_sync: Sending HUP signal to 46083
Dec 8 23:29:07 php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).
Dec 8 23:29:03 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:29:02 kernel: Bump sched buckets to 256 (was 0)
Dec 8 23:28:57 check_reload_status: Reloading filter
Dec 8 23:28:56 check_reload_status: Syncing firewall
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: telnet (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Reloading config…
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: bittorrent (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: ssl (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: httpvideo (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: httpaudio (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: http (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: ftp (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: dns (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: worldofwarcraft (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Loaded Protocol: ssh (rule altq)
Dec 8 23:28:54 ipfw-classifyd: Reloading config...
Dec 8 23:28:54 ipfw-classifyd: Reloading config...
Dec 8 23:28:54 ipfw-classifyd: Reloading config...
Dec 8 23:28:54 ipfw-classifyd: Reloading config...
Dec 8 23:28:53 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:53 check_reload_status: Syncing firewall
Dec 8 23:28:51 php: rc.start_packages: Stopping HAVP
Dec 8 23:28:50 squid[824]: Squid Parent: (squid-1) process 1479 started
Dec 8 23:28:50 squid[824]: Squid Parent: will start 1 kids
Dec 8 23:28:49 php: rc.start_packages: XML error: Invalid document end at line 150 in /usr/local/pkg/haproxy_xmlrpcsyncclient.inc
Dec 8 23:28:48 php: rc.start_packages: The command '/usr/local/etc/rc.d/squid.sh stop' returned exit code '1', the output was 'squid: No running copy'
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:43 php: rc.start_packages: [Squid] - Squid_resync function call pr:1 bp:1 rpc:no
Dec 8 23:28:41 php: rc.start_packages: The command '/usr/local/etc/rc.d/cron.sh stop' returned exit code '1', the output was ''
Dec 8 23:28:39 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:39 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:39 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:39 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:38 php: rc.filter_configure_sync: Sending HUP signal to 48213
Dec 8 23:28:38 php: rc.filter_configure_sync: Sending HUP signal to 47758
Dec 8 23:28:38 php: rc.filter_configure_sync: Sending HUP signal to 47378
Dec 8 23:28:38 php: rc.filter_configure_sync: Sending HUP signal to 46987
Dec 8 23:28:38 php: rc.filter_configure_sync: Sending HUP signal to 46083
Dec 8 23:28:35 php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).
Dec 8 23:28:30 kernel: Bump sched buckets to 256 (was 0)
Dec 8 23:28:30 php: rc.start_packages: Restarting/Starting all packages.
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: telnet (rule altq)
Dec 8 23:28:24 check_reload_status: Reloading filter
Dec 8 23:28:24 check_reload_status: Starting packages
Dec 8 23:28:24 php: rc.newwanip: pfSense package system has detected an ip change 0.0.0.0 -> … Restarting packages.
Dec 8 23:28:24 ipfw-classifyd: Reloading config...
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: ssl (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: httpvideo (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: httpaudio (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: http (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: ftp (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: dns (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: bittorrent (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: ssh (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Loaded Protocol: worldofwarcraft (rule altq)
Dec 8 23:28:24 ipfw-classifyd: Reloading config...
Dec 8 23:28:24 ipfw-classifyd: Reloading config...
Dec 8 23:28:24 ipfw-classifyd: Reloading config...
Dec 8 23:28:24 ipfw-classifyd: Reloading config...
Dec 8 23:28:22 php: rc.newwanip: Creating rrd update script
Dec 8 23:28:22 php: rc.newwanip: Resyncing OpenVPN instances for interface TRUST_HOMEPC.
Dec 8 23:28:22 php: rc.newwanip: Ignoring IPsec racoon daemon reload since there are no tunnels on interface opt2
Dec 8 23:28:22 php: rc.newwanip: rc.newwanip: on (IP address: ) (interface: TRUST_HOMEPC[opt2]) (real interface: em2).
Dec 8 23:28:22 php: rc.newwanip: rc.newwanip: Informational is starting em2.
Dec 8 23:28:21 check_reload_status: Syncing firewall
Dec 8 23:28:19 check_reload_status: rc.newwanip starting em2
Dec 8 23:28:19 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:28:19 check_reload_status: Syncing firewall
Dec 8 23:28:14 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:28:14 kernel: em2: link state changed to UP
Dec 8 23:28:14 check_reload_status: Linkup starting em2
Dec 8 23:28:13 php: rc.start_packages: XML error: Invalid document end at line 150 in /usr/local/pkg/haproxy_xmlrpcsyncclient.inc
Dec 8 23:28:11 kernel: em2: link state changed to DOWN
Dec 8 23:28:11 check_reload_status: Linkup starting em2
Dec 8 23:28:02 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:02 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:02 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:02 php: rc.start_packages: No pfBlocker action during boot process.
Dec 8 23:28:00 php: rc.filter_configure_sync: Sending HUP signal to 48213
Dec 8 23:28:00 php: rc.filter_configure_sync: Sending HUP signal to 47758
Dec 8 23:28:00 php: rc.filter_configure_sync: Sending HUP signal to 47378
Dec 8 23:28:00 php: rc.filter_configure_sync: Sending HUP signal to 46987
Dec 8 23:28:00 php: rc.filter_configure_sync: Sending HUP signal to 46083
Dec 8 23:27:58 php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).
Dec 8 23:27:55 kernel: Bump sched buckets to 256 (was 0)
Dec 8 23:27:54 php: rc.start_packages: Restarting/Starting all packages.
Dec 8 23:27:51 check_reload_status: Reloading filter
Dec 8 23:27:51 check_reload_status: Starting packages
Dec 8 23:27:51 php: rc.newwanip: pfSense package system has detected an ip change 0.0.0.0 -> … Restarting packages.
Dec 8 23:27:49 php: rc.newwanip: Creating rrd update script
Dec 8 23:27:49 php: rc.newwanip: Resyncing OpenVPN instances for interface TRUST_HOMEPC.
Dec 8 23:27:49 php: rc.newwanip: Ignoring IPsec racoon daemon reload since there are no tunnels on interface opt2
Dec 8 23:27:49 php: rc.newwanip: rc.newwanip: on (IP address: ) (interface: TRUST_HOMEPC[opt2]) (real interface: em2).
Dec 8 23:27:49 php: rc.newwanip: rc.newwanip: Informational is starting em2.
Dec 8 23:27:46 check_reload_status: rc.newwanip starting em2
Dec 8 23:27:46 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:27:44 kernel: em2: link state changed to UP
Dec 8 23:27:44 check_reload_status: Linkup starting em2
Dec 8 23:27:20 php: rc.linkup: Hotplug event detected for TRUST_HOMEPC(opt2) but ignoring since interface is configured with static IP ( )
Dec 8 23:27:17 kernel: em2: link state changed to DOWN
Dec 8 23:27:17 check_reload_status: Linkup starting em2

is it normal the entry log "pfSense package system has detected an ip change 0.0.0.0 -> … Restarting packages." ?

stephenw10 · Dec 8, 2014, 11:50 PM

Hmm, just what I was thinking. You definitely have the bridge members set as 'none'? Both IPv4 and v6?
Seems an unlikely cause though since it would happen every time.

Steve

Ryu · Dec 9, 2014, 12:53 PM

@stephenw10:

You definitely have the bridge members set as 'none'? Both IPv4 and v6?

Steve

Yes it is set to none for both.

Ryu · Jan 14, 2015, 3:10 PM

Hi guys good news
since i have upgraded to 2.2-RC release (more than a week) the issue is not present anymore!
i didn't understand which was the problem but now i'm happy :)