From owner-freebsd-net@freebsd.org Mon Nov 25 18:46:59 2019 Return-Path: Delivered-To: freebsd-net@mailman.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.nyi.freebsd.org (Postfix) with ESMTP id 62B941B608A for ; Mon, 25 Nov 2019 18:46:59 +0000 (UTC) (envelope-from guido@gvr.org) Received: from gvr.gvr.org (gvr.gvr.org [62.251.117.91]) (using TLSv1.2 with cipher DHE-RSA-CAMELLIA256-SHA256 (256/256 bits)) (Client CN "gvr.gvr.org", Issuer "Gandi Standard SSL CA 2" (not verified)) by mx1.freebsd.org (Postfix) with ESMTPS id 47MGGp1HNfz3J64 for ; Mon, 25 Nov 2019 18:46:57 +0000 (UTC) (envelope-from guido@gvr.org) Received: from gvr.gvr.org (localhost [127.0.0.1]) by gvr.gvr.org (Postfix) with ESMTP id 9505F3928F for ; Mon, 25 Nov 2019 19:46:50 +0100 (CET) X-Virus-Scanned: amavisd-new at gvr.org Received: from gvr.gvr.org ([127.0.0.1]) by gvr.gvr.org (gvr.gvr.org [127.0.0.1]) (amavisd-new, port 10026) with ESMTP id awPB3UL5mx5j for ; Mon, 25 Nov 2019 19:46:50 +0100 (CET) Received: by gvr.gvr.org (Postfix, from userid 657) id 1CF813928C; Mon, 25 Nov 2019 19:46:50 +0100 (CET) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=gvr.org; s=20190204; t=1574707610; bh=6ERrHs2Y4NWIDB/s1X31GRxjEmHaOQwk0M2WxmelEqM=; h=Date:From:To:Subject; b=rJ98Z7YbWIqkGqtyXFddmdSzGx6oaU/JqqDTR/11gGosK2cf+g0bSWSQti+y2Cf6R H1N0J5STDJ6EsbQ9BZ1gwOZP4vBO+k7Sn5EAsoxfthDFOCViBDcobyVeLIh9xmENRx iAr0uIcPYGtlR+sO7jhv/hIId45RVqsv7Ezxp6/U7GISk5/sO1p8s4wspzwd1syY2j CpuxNlakqJMOyVx2bg99FjnehvOSn3/hQN5z45UN+a/So2wXcIAobLd4/2tDwQDZrL 4IAjHkgLkpC6ejP4qhh+5K/quhMFi/ZOPbafSkpgmAezvdVe2S96eY4dGk87/fXJdA tlNvR0s/PkY+Q== Date: Mon, 25 Nov 2019 19:46:50 +0100 From: Guido van Rooij To: freebsd-net@freebsd.org Subject: Problems with Multicast (IGMP) since upgrade from 11.3 to 12.1 Message-ID: <20191125184650.GA79855@gvr.gvr.org> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline X-Rspamd-Queue-Id: 47MGGp1HNfz3J64 X-Spamd-Bar: ----- Authentication-Results: mx1.freebsd.org; dkim=pass header.d=gvr.org header.s=20190204 header.b=rJ98Z7Yb; dmarc=none; spf=pass (mx1.freebsd.org: domain of guido@gvr.org designates 62.251.117.91 as permitted sender) smtp.mailfrom=guido@gvr.org X-Spamd-Result: default: False [-5.35 / 15.00]; ARC_NA(0.00)[]; NEURAL_HAM_MEDIUM(-1.00)[-1.000,0]; R_DKIM_ALLOW(-0.20)[gvr.org:s=20190204]; FROM_HAS_DN(0.00)[]; R_SPF_ALLOW(-0.20)[+a]; TO_MATCH_ENVRCPT_ALL(0.00)[]; MIME_GOOD(-0.10)[text/plain]; PREVIOUSLY_DELIVERED(0.00)[freebsd-net@freebsd.org]; TO_DN_NONE(0.00)[]; RCPT_COUNT_ONE(0.00)[1]; NEURAL_HAM_LONG(-1.00)[-1.000,0]; RCVD_COUNT_THREE(0.00)[3]; DMARC_NA(0.00)[gvr.org]; DKIM_TRACE(0.00)[gvr.org:+]; FROM_EQ_ENVFROM(0.00)[]; MIME_TRACE(0.00)[0:+]; RCVD_TLS_LAST(0.00)[]; ASN(0.00)[asn:3265, ipnet:62.251.0.0/17, country:NL]; IP_SCORE(-2.85)[ip: (-9.58), ipnet: 62.251.0.0/17(-4.71), asn: 3265(-0.00), country: NL(0.02)] X-BeenThere: freebsd-net@freebsd.org X-Mailman-Version: 2.1.29 Precedence: list List-Id: Networking and TCP/IP with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 25 Nov 2019 18:46:59 -0000 I am using a STB with muticast based TV. When I use my firewall with igmpproxy in 11.3, I see the following when I am not watching TV: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 2251 0 1 1 192.168.100.1 0 2251 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 213.75.167.58 224.3.2.6 1435 0 1:1 When I switch on the STB, I see: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 22450 0 1 1 192.168.100.1 0 22450 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 217.166.226.126 224.0.252.126 18986 0 1:1 213.75.167.58 224.3.2.6 1918 0 1:1 When I swicth it off, we see, again: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 50108 0 1 1 192.168.100.1 0 50108 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 213.75.167.58 224.3.2.6 2215 0 1:1 Here is the IGMP trace: # No TV 18:55:47.172531 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_ex { }] # Switch on STB 18:55:49.773534 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_ex { }] 18:56:47.094853 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [none], proto IGMP (2), length 36, options (RA)) 10.60.140.9 > 224.0.0.1: igmp query v3 18:56:53.287526 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 48, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 2 group record(s) [gaddr 224.0.252.126 is_ex { }] [gaddr 224.3.2.6 is_ex { }] # Switch off STB 18:56:57.887515 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_in { }] 18:56:58.717053 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_in { }] Now in 12.1 we see: STB off: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 158 0 1 1 192.168.100.1 0 158 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 213.75.167.58 224.3.2.6 158 0 1:1 STB on: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 10674 0 1 1 192.168.100.1 0 10674 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 217.166.226.126 224.0.252.126 9584 0 1:1 213.75.167.58 224.3.2.6 1091 0 1:1 STB back off again: # netstat -g IPv4 Virtual Interface Table Vif Thresh Local-Address Remote-Address Pkts-In Pkts-Out 0 1 10.59.161.237 41404 0 1 1 192.168.100.1 0 41404 IPv4 Multicast Forwarding Table Origin Group Packets In-Vif Out-Vifs:Ttls 217.166.226.126 224.0.252.126 0 65535 213.75.167.58 224.3.2.6 1342 0 1:1 IGMP trace: # No TV 19:05:07.103731 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [none], proto IGMP (2), length 36, options (RA)) 10.60.140.9 > 224.0.0.1: igmp query v3 19:05:12.203438 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.3.2.6 is_ex { }] # Switch on STB 19:06:30.522147 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_ex { }] 19:06:31.740782 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 40, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 1 group record(s) [gaddr 224.0.252.126 to_ex { }] # Switch off STB 19:07:12.107851 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [none], proto IGMP (2), length 36, options (RA)) 10.60.140.9 > 224.0.0.1: igmp query v3 19:07:21.605196 IP (tos 0xc0, ttl 1, id 0, offset 0, flags [DF], proto IGMP (2), length 48, options (RA)) 10.59.161.237 > 224.0.0.22: igmp v3 report, 2 group record(s) [gaddr 224.0.252.126 is_ex { }] [gaddr 224.3.2.6 is_ex { }] So we never see the IGMP_CHANGE_TO_INCLUDE_MODE packet. I did a ktr trace and in it I only saw the following entries that I think are interesting (first number is a timestamp) Nov 20 19:14:25 in_joingroup_locked: join 0xe000fc7e on 0xfffff8000f4d6000(vlan4)) Nov 20 19:14:25 igmp_initial_join: initial join 0xe000fc7e on ifp 0xfffff8000f4d6000(vlan4) Nov 20 19:14:25 igmp_v3_enqueue_group_record: queueing TO_EX for 0xe000fc7e/vlan4 Nov 20 19:15:30 in_leavegroup_locked: leave inm 0xfffff8003a448c00, 0xe000fc7e/vlan4, imf 0xfffff8003a609d40 Nov 20 19:15:30 igmp_handle_state_change: state change for 0xe000fc7e on ifp 0xfffff8000f4d6000(vlan4) Nov 20 19:15:30 igmp_v3_enqueue_group_record: nothing to do for 0xe000fc7e/vlan4 Nov 20 19:15:30 igmp_v3_enqueue_group_record: queueing MODE_EX for 0xe000fc7e/vlan4 I am somewhat lost on how to further debug this. Any hint would be appreciated. Thanks, Guido van Rooij