[j-nsp] PIM-SM and static mpls

Simon Paillard simon.paillard at resel.enst-bretagne.fr
Tue Jan 3 11:07:52 EST 2012


Hi,

I encounter some issues running PIM-SM on JunOS while mpls static-path are
defined.

Little ascii, there are many XORP behind the same and single EqptA, that
receive info from J2320.
                                         need mpls static-path
                                         ^
                                         |
(eth0)[XORP](air0)----[ EqptA ]----(ge-0/0/0.1)[J2320](ge-0/0/2)-
                          |                       |
                          |_______________________|(ge-0/0/1)


J2320 is Rendez-vous Point for 224.0.0.0/4, and declared as such in XORP.

1/ 224.0.0.13 PIMv2 vs mpls static-path:
========================================
Traffic to be sent to XORP need a MPLS tag in order to go through EqptA.

----------8<--------------8<--------------8<--------------8<--------
set protocols mpls static-path inet 224.0.0.13/32 next-hop 10.160.10.2
set protocols mpls static-path inet 224.0.0.13/32 push 1000039
----------8<--------------8<--------------8<--------------8<--------

But with ge-0/0/0.1 declared as sparse, I see Junos adds no tag.
(And as expected, EqptA refuses the packets because of missing tag).

-> How to make sure the tag is added ? Is there a way to defined a virtual
interface that would map to "ge-0/0/0.1 / mpls push XXX" ?


2/ Reverse path forwarding issue and PIM ?
==========================================
As an ugly and temporally workaround of 1/, I've added an interface ge-0/0/1
to send PIM to XORPs.

* I correctly see incoming PIM hello from J2320.
* PIMv2 registration from XORP arrives on ge-0/0/0.1, with source 192.168.25.33
* route to source 192.168.25.33 is ge-0/0/0.1
* Registration stop are correctly received by XORP from J2320

But:
* on IGMP Join on ge-0/0/2 (pim and igmp enabled), no PIM join is sent to XORP
(or any other interface, even ge-0/0/0.1 without mpls tag).

pim-igmp-trace.txt attached continas IGMP and PIM debug: and (225.0.0.42 ,
192.168.25.33) as the example flow.

It seems JunOS need to be told that PIM regarding source 192.168.25.33 should
be sent to ge-0/0/1 ?

I've tried "ip multicast-routing disable-rpf-check" with a 224.0.0.0/4 mask,
but without improvement.

A solution to 1/ would make 2/ invalid :-)

Thanks for your help.

If you need the full Juniper conf, I need to anonymize it first.


PS: JUNOS 9.3R2.8

-- 
Simon Paillard
-------------- next part --------------
tail -F /var/log/pim-trace /var/log/igmp-trace

==> /var/log/pim-trace <==
Dec 14 12:38:28.725783 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.001
Dec 14 12:38:28.725844 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:38:28.725873 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0
Dec 14 12:38:35.679440 PIM ge-0/0/0.1 RECV 192.168.25.1 -> 10.128.255.253 V2 Register Flags: 0x40000000 Border: 0 Null: 1 Source 192.168.25.33 Group 225.0.0.42 sum 0x9eff len 28
Dec 14 12:38:35.679687 PIM Sending Wildcard Register Stop to 192.168.25.1 for Group 225.0.0.42
Dec 14 12:38:35.680156 PIM SENT 10.128.255.253 -> 192.168.25.1 V2 RegisterStop Source 0.0.0.0 Group 225.0.0.42 sum 0xfab4 len 18
Dec 14 12:38:35.680229 task_timer_uset: timer PIM.master_RegisterStop <Touched> set to offset 1 at 12:38:36
Dec 14 12:38:36.680862 task_timer_dispatch: calling PIM.master_RegisterStop, late by 0.001
Dec 14 12:38:36.680977 task_timer_uset: timer PIM.master_RegisterStop <Touched Processing> set to offset 5:00 at 12:43:36
Dec 14 12:38:36.681077 task_timer_dispatch: returned from PIM.master_RegisterStop, rescheduled in 5:00

==> /var/log/igmp-trace <==
Dec 14 12:38:37.630206 task_process_events: recv ready for IGMP
Dec 14 12:38:37.630301 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:37.630457 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:38:37.630488   Group 224.0.0.13
Dec 14 12:38:37.630525 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:37.630552 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:37.630902 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:37.630953 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:37.631017 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:37.631050 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18

==> /var/log/pim-trace <==
Dec 14 12:38:39.314461 task_timer_dispatch: calling PIM.master_Hello, late by 0.001
Dec 14 12:38:39.314961 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:38:39
Dec 14 12:38:39.315564 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 29.841
Dec 14 12:38:39.344427 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.002
Dec 14 12:38:39.344969 PIM ge-0/0/1.0 SENT 10.128.255.253 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 660e9365 sum 0x5c25 len 34
Dec 14 12:38:39.345480 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.027
Dec 14 12:38:39.373333 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.001
Dec 14 12:38:39.373716 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:38:39.374352 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0

==> /var/log/igmp-trace <==
Dec 14 12:38:42.612366 task_process_events: recv ready for IGMP
Dec 14 12:38:42.612934 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:42.613738 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:38:42.614252   Group 239.1.0.1
Dec 14 12:38:42.614921 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:42.615539 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:42.616209 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:42.617002 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:42.618068 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:42.619060 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:42.619939 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:42.620646 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:38:42.621744   Group 239.2.0.1
Dec 14 12:38:42.623252 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:42.624202 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:42.624992 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:42.626011 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:42.626630 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:42.627365 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:42.628141 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:42.629213 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:38:42.629856   Group 228.0.0.45
Dec 14 12:38:42.630926 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:42.631731 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:42.632487 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:42.632552 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:42.632588 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:42.632695 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:42.632792 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:42.633606 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:38:42.634373   Group 227.0.0.44
Dec 14 12:38:42.634507 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:42.634548 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:42.634608 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:42.634646 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:42.634754 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:42.634790 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:42.712509 task_process_events: recv ready for IGMP
Dec 14 12:38:42.713183 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:42.713834 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:38:42.714781   Group 224.0.0.13
Dec 14 12:38:42.715307 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:42.715371 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:42.715492 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:42.715570 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:42.715607 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:42.715636 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:47.685654 task_process_events: recv ready for IGMP
Dec 14 12:38:47.686228 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:47.687093 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:38:47.687515   Group 239.1.0.1
Dec 14 12:38:47.687636 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:47.687675 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:47.687716 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:47.687752 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:47.687907 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:47.687948 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:47.688048 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:47.688174 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:38:47.688209   Group 239.2.0.1
Dec 14 12:38:47.688240 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:47.688270 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:47.688306 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:47.689045 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:47.689948 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:47.691863 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:47.692788 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:47.692884 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:38:47.692921   Group 228.0.0.45
Dec 14 12:38:47.692955 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:47.693048 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:47.693093 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:47.693127 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:47.693159 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:47.693187 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:47.693968 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:47.694753 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:38:47.694904   Group 227.0.0.44
Dec 14 12:38:47.694952 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:47.694988 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:47.695084 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:47.695128 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:47.695161 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:47.695188 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:47.785884 task_process_events: recv ready for IGMP
Dec 14 12:38:47.786373 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:47.787419 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:38:47.788487   Group 224.0.0.13
Dec 14 12:38:47.789478 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:47.790224 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:47.791458 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:47.791821 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:47.792276 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:47.793031 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:50.494377 task_process_events: recv ready for IGMP
Dec 14 12:38:50.495134 task_receive_packet: task IGMP from 192.168.250.33 to 224.0.0.22 if ge-0/0/2.0 (ix 67) msgix 67 socket 22 length 40
Dec 14 12:38:50.495503 RCV IGMP V3 Report len 16 from 192.168.250.33 intf ge-0/0/2.0
Dec 14 12:38:50.496160   Records 1
Dec 14 12:38:50.496220     Group 225.0.0.42, type TO_EX, aux_len 0, sources 0
Dec 14 12:38:50.496347 task_timer_ucreate: created timer IGMP_GMP router group timer  flags <>
Dec 14 12:38:50.496398 task_timer_ucreate: created timer IGMP_GMP router group query  flags <>
Dec 14 12:38:50.496446 task_timer_ucreate: created timer IGMP_GMP router GSS query  flags <>
Dec 14 12:38:50.496480 task_timer_ucreate: created timer IGMP_GMP router group basic host  flags <>
Dec 14 12:38:50.496508 task_timer_ucreate: created timer IGMP_GMP router group leave host  flags <>
Dec 14 12:38:50.496634 Created group 225.0.0.42, intf ge-0/0/2.0
Dec 14 12:38:50.496693 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:50.496733 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:50.496788 task_timer_ucreate: created timer IGMP_GMP router host group  flags <>
Dec 14 12:38:50.497028 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:50.497685 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:50.498008 task_job_create_background: create prio 4 job IGMP notification job for task IGMP
Dec 14 12:38:50.498060 task_job_create_background: create prio 4 job IGMP host_notification job for task IGMP
Dec 14 12:38:50.498284 background dispatch running job IGMP notification job for task IGMP
Dec 14 12:38:50.498418 Client 0 notif ge-0/0/2.0 225.0.0.42 Add Group Excl 0.0.0.0
Dec 14 12:38:50.498588 task_job_delete: delete background job IGMP notification job for task IGMP
Dec 14 12:38:50.498703 background dispatch completed job IGMP notification job for task IGMP
Dec 14 12:38:50.498763 background dispatch running job IGMP host_notification job for task IGMP
Dec 14 12:38:50.498810 task_job_delete: delete background job IGMP host_notification job for task IGMP
Dec 14 12:38:50.498840 background dispatch completed job IGMP host_notification job for task IGMP

==> /var/log/pim-trace <==
Dec 14 12:38:50.498510 task_job_create_background: create prio 5 job PIM Nbr JP for task PIM.master
Dec 14 12:38:50.499347 background dispatch running job PIM Nbr JP for task PIM.master

Dec 14 12:38:50.500491 EVENT:DS_JOIN
sgnode: 225.0.0.42.0.0.0.0 Flags-sm:wc:rpt:
        DSM: All macros empty
        US-FSM: US State:NOT_JOINED
        RPF: us interface - ge-0/0/1.0(p) nbr unknown
J/P Attr: Flags:sm:wc:rpt: If:ge-0/0/2.0(p) Nbr:192.168.250.140 O:LOCAL-RCVR Ht:0 AS: 0
DS-FSM: IF/NBR ge-0/0/2.0/192.168.250.140((L)) State NoInfo (NI) to Join (J)
Dec 14 12:38:50.502169 task_timer_ucreate: created timer PIM.master_RxJoin  flags <>

EVENT:DS_IMMEDIATE_OLIST_BECOMES_NON_NULL
sg state: 225.0.0.42.0.0.0.0 Flags-sm:wc:rpt:
        DSM: IMM_OLIST(*,g):NE
        US-FSM: US State:NOT_JOINED
        RPF: us interface - ge-0/0/1.0(p) nbr unknown
US-FSM: Prerequisite fails us-ev JOIN_DESIRED_TRUE [we_are_rp]
Dec 14 12:38:50.502479 task_job_create_background: create prio 5 job PIM SRC Q for task PIM.master
Dec 14 12:38:50.502605 task_job_delete: delete background job PIM Nbr JP for task PIM.master
Dec 14 12:38:50.502649 background dispatch completed job PIM Nbr JP for task PIM.master
Dec 14 12:38:50.502705 background dispatch running job PIM SRC Q for task PIM.master
Dec 14 12:38:50.503235 PIM resolving request for Group 225.0.0.42 Source 192.168.25.33 Interface ge-0/0/0.1

Dec 14 12:38:50.504523 EVENT:SOURCE_INFO_FROM_RSLV
Dec 14 12:38:50.504937  Addr: 225.0.0.42.192.168.25.33 On interface - ge-0/0/0.1(p) due_to_gr 0
Assoc <*,g>: 225.0.0.42.0.0.0.0 Flags-sm:wc:rpt:
        DSM: IMM_OLIST(*,g):NE
        US-FSM: US State:NOT_JOINED
        RPF: us interface - ge-0/0/1.0(p) nbr unknown
Dec 14 12:38:50.507771 task_timer_ucreate: created timer PIM.master_KeepAlive  flags <>
Dec 14 12:38:50.508063 task_timer_uset: timer PIM.master_KeepAlive <Touched> set to interval 3:30 at 12:42:20
Dec 14 12:38:50.508124 task_timer_uset: timer PIM.master_KeepAlive <Touched> set to offset 3:30 at 12:42:20

EVENT:KAT_TIMER_STARTED
sg state: 225.0.0.42.192.168.25.33 Flags-sm:
        DSM: All macros empty
        US-FSM: US State:NOT_JOINED(RPT_NOT_JOINED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown

EVENT:DS_INHERITED_SGRPT_OLIST_BECOMES_NON_NULL
sg state: 225.0.0.42.192.168.25.33 Flags-sm:kat:
        DSM: INH_olist(s,g,rpt):NE INH_OLIST(s,g):NE
        US-FSM: US State:NOT_JOINED(RPT_NOT_JOINED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown
<s,g,rpt>US-FSM: US Event - RPT_INH_OLIST_FOR_SGRPT_NON_NULL Current State - (RPT_NOT_JOINED) New State - (RPT_NOT_PRUNED)
<s,g,rpt> US-FSM: rpf nbr towrads RP not availble

EVENT:DS_INHERITED_SG_OLIST_BECOMES_NON_NULL
sg state: 225.0.0.42.192.168.25.33 Flags-sm:kat:
        DSM: INH_olist(s,g,rpt):NE INH_OLIST(s,g):NE
        US-FSM: US State:NOT_JOINED(RPT_NOT_PRUNED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown
US-FSM: Prerequisite fails us-ev JOIN_DESIRED_TRUE [rpf_nbr_null]
US-FSM: Prerequisite fails us-ev JOIN_DESIRED_TRUE [rpf_nbr_null]
Dec 14 12:38:50.509337 task_job_delete: delete background job PIM SRC Q for task PIM.master
Dec 14 12:38:50.509923 background dispatch completed job PIM SRC Q for task PIM.master

==> /var/log/igmp-trace <==
Dec 14 12:38:52.759935 task_process_events: recv ready for IGMP
Dec 14 12:38:52.760424 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:52.760985 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:38:52.761043   Group 239.1.0.1
Dec 14 12:38:52.761159 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:52.761201 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:52.761242 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:52.761275 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:52.761305 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:52.761397 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:52.762227 task_process_events: recv ready for IGMP
Dec 14 12:38:52.762980 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:52.763351 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:38:52.763754   Group 239.2.0.1
Dec 14 12:38:52.764322 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:52.764593 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:52.764745 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:52.764790 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:52.764823 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:52.764851 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:52.765667 task_process_events: recv ready for IGMP
Dec 14 12:38:52.766104 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:52.766639 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:38:52.767321   Group 227.0.0.44
Dec 14 12:38:52.767672 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:52.767728 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:52.767773 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:52.767909 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:52.767951 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:52.767978 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:52.768714 task_process_events: recv ready for IGMP
Dec 14 12:38:52.769160 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:52.769757 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:38:52.770330   Group 228.0.0.45
Dec 14 12:38:52.770973 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:52.771337 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:52.771792 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:52.772536 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:52.772901 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:52.773561 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:52.860216 task_process_events: recv ready for IGMP
Dec 14 12:38:52.860969 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:52.861344 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:38:52.861964   Group 224.0.0.13
Dec 14 12:38:52.862041 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:52.862078 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:52.862119 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:52.862217 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:52.862252 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:52.862280 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18

==> /var/log/pim-trace <==
Dec 14 12:38:54.272460 task_timer_dispatch: calling PIM.master_Hello, late by 0.001
Dec 14 12:38:54.272946 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:38:54
Dec 14 12:38:54.273554 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 29.285
Dec 14 12:38:54.303392 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.001
Dec 14 12:38:54.304138 PIM ge-0/0/0.1 SENT 10.160.10.1 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 3a29398c sum 0xe1e3 len 34
Dec 14 12:38:54.305092 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.029
Dec 14 12:38:54.333417 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:38:54.334392 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:38:54.335440 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0
Dec 14 12:38:55.528060 PIM ge-0/0/0.1 RECV 10.128.0.1 -> 224.0.0.13 V2 Hello hold 105 LAN prune 500 ms override 2500 ms pri 1 genid 7bb541fd sum 0x15f3 len 34
Dec 14 12:38:55.528573 task_timer_uset: timer PIM.master_Nbr <Touched> set to offset 1:45 at 12:40:40
Dec 14 12:38:56.658235 task_timer_dispatch: calling PIM.master_Hello, late by 0.001
Dec 14 12:38:56.658633 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:38:56
Dec 14 12:38:56.659333 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 28.466
Dec 14 12:38:56.687115 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.001
Dec 14 12:38:56.687655 PIM ge-0/0/2.0 SENT 192.168.250.140 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 2e9665cc sum 0xc136 len 34
Dec 14 12:38:56.688157 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.027
Dec 14 12:38:56.715248 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:38:56.715621 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:38:56.716036 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0

==> /var/log/igmp-trace <==
Dec 14 12:38:57.839456 task_process_events: recv ready for IGMP
Dec 14 12:38:57.839979 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:57.840651 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:38:57.840992   Group 239.1.0.1
Dec 14 12:38:57.841058 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:57.841094 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:57.841199 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:57.841234 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:57.841265 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:57.841291 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:57.842019 task_process_events: recv ready for IGMP
Dec 14 12:38:57.842474 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:57.843119 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:38:57.843764   Group 239.2.0.1
Dec 14 12:38:57.844113 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:57.844172 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:57.844269 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:57.844313 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:57.844347 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:57.844374 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:57.845111 task_process_events: recv ready for IGMP
Dec 14 12:38:57.845563 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:57.846170 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:38:57.846851   Group 228.0.0.45
Dec 14 12:38:57.847243 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:57.847798 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:57.848253 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:57.848839 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:57.849292 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:57.849854 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:57.850774 task_process_events: recv ready for IGMP
Dec 14 12:38:57.851065 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:57.851149 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:38:57.851264   Group 227.0.0.44
Dec 14 12:38:57.851311 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:57.851363 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:57.851409 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:57.851501 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:57.851542 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:57.851570 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:57.939655 task_process_events: recv ready for IGMP
Dec 14 12:38:57.940119 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:38:57.940207 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:38:57.940328   Group 224.0.0.13
Dec 14 12:38:57.940370 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:38:57.940404 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:38:57.940466 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:57.940562 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:57.940598 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:57.940627 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:38:59.931467 task_process_events: recv ready for IGMP
Dec 14 12:38:59.931965 task_receive_packet: task IGMP from 192.168.250.33 to 224.0.0.22 if ge-0/0/2.0 (ix 67) msgix 67 socket 22 length 40
Dec 14 12:38:59.932655 RCV IGMP V3 Report len 16 from 192.168.250.33 intf ge-0/0/2.0
Dec 14 12:38:59.933015   Records 1
Dec 14 12:38:59.933579     Group 225.0.0.42, type TO_EX, aux_len 0, sources 0
Dec 14 12:38:59.934026 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:38:59.934589 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:38:59.934793 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:38:59.969556 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:02.907661 task_process_events: recv ready for IGMP
Dec 14 12:39:02.908153 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:02.908719 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:02.908839   Group 239.1.0.1
Dec 14 12:39:02.908895 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:02.908937 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:02.908985 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:02.909028 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:02.909148 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:02.909193 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:02.910005 task_process_events: recv ready for IGMP
Dec 14 12:39:02.910473 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:02.911070 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:02.911511   Group 239.2.0.1
Dec 14 12:39:02.912087 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:02.912524 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:02.913098 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:02.913294 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:02.913357 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:02.913400 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:02.914189 task_process_events: recv ready for IGMP
Dec 14 12:39:02.914664 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:02.915387 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:02.915726   Group 228.0.0.45
Dec 14 12:39:02.916388 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:02.916731 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:02.917299 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:02.917750 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:02.918310 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:02.918749 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:02.919604 task_process_events: recv ready for IGMP
Dec 14 12:39:02.920054 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:02.920580 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:02.920645   Group 227.0.0.44
Dec 14 12:39:02.920749 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:02.920792 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:02.920838 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:02.920880 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:02.920978 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:02.921032 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:03.007908 task_process_events: recv ready for IGMP
Dec 14 12:39:03.008409 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:03.008503 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:03.008623   Group 224.0.0.13
Dec 14 12:39:03.008671 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:03.008717 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:03.008766 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:03.008864 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:03.008909 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:03.008946 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:07.995012 task_process_events: recv ready for IGMP
Dec 14 12:39:07.995504 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:07.996074 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:07.996195   Group 239.1.0.1
Dec 14 12:39:07.996253 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:07.996295 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:07.996345 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:07.996457 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:07.996517 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:07.996555 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:07.997376 task_process_events: recv ready for IGMP
Dec 14 12:39:07.997841 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:07.997932 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:07.998052   Group 227.0.0.44
Dec 14 12:39:07.998099 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:07.998140 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:07.998190 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:07.998287 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:07.998331 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:07.998368 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:07.999197 task_process_events: recv ready for IGMP
Dec 14 12:39:07.999675 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:08.000330 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:08.000707   Group 239.2.0.1
Dec 14 12:39:08.000780 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:08.000883 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:08.000933 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:08.000977 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:08.001020 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:08.001125 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:08.001821 task_process_events: recv ready for IGMP
Dec 14 12:39:08.002274 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:08.002747 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:08.003493   Group 228.0.0.45
Dec 14 12:39:08.003790 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:08.003855 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:08.003962 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:08.004014 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:08.004055 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:08.004094 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:08.094258 task_process_events: recv ready for IGMP
Dec 14 12:39:08.095021 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:08.095631 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:08.096312   Group 224.0.0.13
Dec 14 12:39:08.096673 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:08.097390 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:08.097815 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:08.097889 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:08.097934 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:08.098030 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18

==> /var/log/pim-trace <==
Dec 14 12:39:09.157565 task_timer_dispatch: calling PIM.master_Hello, late by 0.001
Dec 14 12:39:09.158169 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:39:09
Dec 14 12:39:09.158299 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 29.942
Dec 14 12:39:09.187543 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:39:09.188068 PIM ge-0/0/1.0 SENT 10.128.255.253 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 660e9365 sum 0x5c25 len 34
Dec 14 12:39:09.188594 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.029
Dec 14 12:39:09.218491 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:39:09.218868 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:39:09.219418 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0

==> /var/log/igmp-trace <==
Dec 14 12:39:13.061474 task_process_events: recv ready for IGMP
Dec 14 12:39:13.061986 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:13.062082 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:13.062201   Group 239.1.0.1
Dec 14 12:39:13.062251 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:13.062294 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:13.062345 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:13.062451 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:13.062495 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:13.062533 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:13.063733 task_process_events: recv ready for IGMP
Dec 14 12:39:13.064046 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:13.064138 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:13.064276   Group 239.2.0.1
Dec 14 12:39:13.064326 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:13.064368 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:13.064475 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:13.064527 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:13.064569 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:13.064608 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:13.065431 task_process_events: recv ready for IGMP
Dec 14 12:39:13.065877 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:13.066420 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:13.066547   Group 227.0.0.44
Dec 14 12:39:13.066604 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:13.066647 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:13.066696 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:13.066801 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:13.066852 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:13.066893 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:13.067705 task_process_events: recv ready for IGMP
Dec 14 12:39:13.068432 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:13.069140 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:13.069500   Group 228.0.0.45
Dec 14 12:39:13.069962 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:13.070687 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:13.071041 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:13.071107 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:13.071214 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:13.071262 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:13.160765 task_process_events: recv ready for IGMP
Dec 14 12:39:13.161247 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:13.161936 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:13.162300   Group 224.0.0.13
Dec 14 12:39:13.162856 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:13.163289 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:13.163892 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:13.164320 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:13.164892 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:13.165321 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:18.147689 task_process_events: recv ready for IGMP
Dec 14 12:39:18.148181 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:18.148763 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:18.148892   Group 239.1.0.1
Dec 14 12:39:18.148950 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:18.148994 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:18.149044 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:18.149158 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:18.149212 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:18.149252 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:18.150086 task_process_events: recv ready for IGMP
Dec 14 12:39:18.150533 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:18.150624 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:18.150739   Group 227.0.0.44
Dec 14 12:39:18.150790 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:18.150833 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:18.150883 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:18.150991 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:18.151035 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:18.151073 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:18.152104 task_process_events: recv ready for IGMP
Dec 14 12:39:18.152347 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:18.152492 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:18.152546   Group 239.2.0.1
Dec 14 12:39:18.152593 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:18.152636 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:18.152688 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:18.152801 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:18.152846 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:18.152885 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:18.154115 task_process_events: recv ready for IGMP
Dec 14 12:39:18.154436 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:18.154584 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:18.154635   Group 228.0.0.45
Dec 14 12:39:18.154728 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:18.154846 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:18.154904 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:18.154949 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:18.154990 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:18.155083 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:18.246920 task_process_events: recv ready for IGMP
Dec 14 12:39:18.247431 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:18.248459 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:18.248811   Group 224.0.0.13
Dec 14 12:39:18.249470 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:18.250611 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:18.252071 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:18.252660 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:18.252811 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:18.252859 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:23.219108 task_process_events: recv ready for IGMP
Dec 14 12:39:23.219596 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:23.220145 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:23.220287   Group 239.2.0.1
Dec 14 12:39:23.220341 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:23.220384 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:23.220433 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:23.220531 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:23.220576 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:23.220612 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:23.221448 task_process_events: recv ready for IGMP
Dec 14 12:39:23.221907 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:23.221998 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:23.222101   Group 239.1.0.1
Dec 14 12:39:23.222145 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:23.222187 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:23.222236 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:23.222349 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:23.222396 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:23.222434 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:23.223643 task_process_events: recv ready for IGMP
Dec 14 12:39:23.224058 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:23.224718 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:23.225306   Group 227.0.0.44
Dec 14 12:39:23.225733 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:23.226310 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:23.227025 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:23.227373 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:23.227788 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:23.228362 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:23.229220 task_process_events: recv ready for IGMP
Dec 14 12:39:23.229664 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:23.229806 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:23.229855   Group 228.0.0.45
Dec 14 12:39:23.229898 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:23.229940 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:23.229989 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:23.230112 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:23.230158 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:23.230195 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:23.318324 task_process_events: recv ready for IGMP
Dec 14 12:39:23.318825 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:23.319867 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:23.320356   Group 224.0.0.13
Dec 14 12:39:23.320892 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:23.320964 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:23.321084 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:23.321138 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:23.321177 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:23.321213 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18

==> /var/log/pim-trace <==
Dec 14 12:39:23.558698 task_timer_dispatch: calling PIM.master_Hello, late by 0.001
Dec 14 12:39:23.559102 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:39:23
Dec 14 12:39:23.559767 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 29.987
Dec 14 12:39:23.588619 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:39:23.589177 PIM ge-0/0/0.1 SENT 10.160.10.1 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 3a29398c sum 0xe1e3 len 34
Dec 14 12:39:23.589665 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.029
Dec 14 12:39:23.618776 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:39:23.619160 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:39:23.619821 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0
Dec 14 12:39:24.174238 PIM ge-0/0/0.1 RECV 192.168.25.1 -> 10.128.255.253 V2 Register Flags: 0x40000000 Border: 0 Null: 1 Source 192.168.25.33 Group 225.0.0.42 sum 0x9eff len 28
Dec 14 12:39:25.126593 task_timer_dispatch: calling PIM.master_Hello, late by 0.002
Dec 14 12:39:25.127062 task_timer_uset: timer PIM.master_IF_Xmit <Touched> set to interval 0.030000 jitter 10 at 12:39:25
Dec 14 12:39:25.127668 task_timer_dispatch: returned from PIM.master_Hello, rescheduled in 27.021
Dec 14 12:39:25.157525 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.001
Dec 14 12:39:25.158082 PIM ge-0/0/2.0 SENT 192.168.250.140 -> 224.0.0.13 V2 Hello hold 105 T-bit LAN prune 500 ms override 2000 ms pri 1 genid 2e9665cc sum 0xc136 len 34
Dec 14 12:39:25.158580 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0.029
Dec 14 12:39:25.187435 task_timer_dispatch: calling PIM.master_IF_Xmit, late by 0.000
Dec 14 12:39:25.187801 task_timer_reset: reset PIM.master_IF_Xmit
Dec 14 12:39:25.188453 task_timer_dispatch: returned from PIM.master_IF_Xmit, rescheduled in 0
Dec 14 12:39:25.552974 PIM ge-0/0/0.1 RECV 10.128.0.1 -> 224.0.0.13 V2 Hello hold 105 LAN prune 500 ms override 2500 ms pri 1 genid 7bb541fd sum 0x15f3 len 34
Dec 14 12:39:25.553439 task_timer_uset: timer PIM.master_Nbr <Touched> set to offset 1:45 at 12:41:10

==> /var/log/igmp-trace <==
Dec 14 12:39:28.299396 task_process_events: recv ready for IGMP
Dec 14 12:39:28.299981 task_receive_packet: task IGMP from 10.128.1.134 to 239.1.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:28.300842 RCV IGMP V2 Report len 8 from 10.128.1.134 intf ge-0/0/0.1
Dec 14 12:39:28.301267   Group 239.1.0.1
Dec 14 12:39:28.301405 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:28.301456 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:28.301508 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:28.301553 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:28.301664 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:28.301708 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:28.301814 task_receive_packet: task IGMP from 10.128.1.150 to 227.0.0.44 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:28.301953 RCV IGMP V2 Report len 8 from 10.128.1.150 intf ge-0/0/0.1
Dec 14 12:39:28.301998   Group 227.0.0.44
Dec 14 12:39:28.302042 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:28.302768 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:28.303443 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:28.303519 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:28.303567 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:28.303608 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:28.303778 task_receive_packet: task IGMP from 10.128.1.138 to 239.2.0.1 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:28.303862 RCV IGMP V2 Report len 8 from 10.128.1.138 intf ge-0/0/0.1
Dec 14 12:39:28.305089   Group 239.2.0.1
Dec 14 12:39:28.306131 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:28.307179 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:28.307944 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:28.308875 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:28.309003 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:28.310006 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:28.310775 task_receive_packet: task IGMP from 10.128.1.154 to 228.0.0.45 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:28.311244 RCV IGMP V2 Report len 8 from 10.128.1.154 intf ge-0/0/0.1
Dec 14 12:39:28.311851   Group 228.0.0.45
Dec 14 12:39:28.312776 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:28.313420 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:28.313559 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:28.313609 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:28.313652 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:28.313690 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18
Dec 14 12:39:28.398678 task_process_events: recv ready for IGMP
Dec 14 12:39:28.399186 task_receive_packet: task IGMP from 10.128.1.158 to 224.0.0.13 if ge-0/0/0.1 (ix 70) msgix 70 socket 22 length 28
Dec 14 12:39:28.400227 RCV IGMP V2 Report len 8 from 10.128.1.158 intf ge-0/0/0.1
Dec 14 12:39:28.400944   Group 224.0.0.13
Dec 14 12:39:28.402325 task_timer_reset: reset IGMP_GMP router group leave host
Dec 14 12:39:28.402806 task_timer_set_oneshot_latest: timer IGMP_GMP router group leave host interval set to 34:18
Dec 14 12:39:28.403414 task_timer_reset: reset IGMP_GMP router group timer
Dec 14 12:39:28.403540 task_timer_set_oneshot_latest: timer IGMP_GMP router group timer interval set to 34:18
Dec 14 12:39:28.403588 task_timer_reset: reset IGMP_GMP router host group
Dec 14 12:39:28.403629 task_timer_set_oneshot_latest: timer IGMP_GMP router host group interval set to 34:18

==> /var/log/pim-trace <==
Dec 14 12:39:30.050582 PIM ge-0/0/1.0 RECV 10.128.0.1 -> 10.128.255.253 V2 Register Flags: 0x00000000 Border: 0 Null: 0 Source 192.168.25.33 Group 225.0.0.42 sum 0xdeff len 1036
Dec 14 12:39:30.051328 task_timer_delete: PIM.master_RegisterStop <Touched>

==> /var/log/igmp-trace <==
Dec 14 12:39:30.052427 task_send_msg: task IGMP socket 22 length 1028 to 225.0.0.42 in interface pd-0/0/0.32769

==> /var/log/pim-trace <==
Dec 14 12:39:30.052973 PIM resolving request for Group 225.0.0.42 Source 192.168.25.33 Interface pd-0/0/0.32769

Dec 14 12:39:30.053120 EVENT:RSLV_REQ
Dec 14 12:39:30.053141  Addr: 225.0.0.42.192.168.25.33 On interface - pd-0/0/0.32769(p) due_to_gr 0
Assoc <s,g>: 225.0.0.42.192.168.25.33 Flags-sm:kat:
        DSM: INH_olist(s,g,rpt):NE INH_OLIST(s,g):NE
        US-FSM: US State:NOT_JOINED(RPT_NOT_PRUNED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown
Assoc <*,g>: 225.0.0.42.0.0.0.0 Flags-sm:wc:rpt:
        DSM: IMM_OLIST(*,g):NE
        US-FSM: US State:NOT_JOINED
        RPF: us interface - ge-0/0/1.0(p) nbr unknown
FWD-FSM:Building fwd cache entry: <192.168.25.33,225.0.0.42> IIF - pd-0/0/0.32769 rpf_check enable
FWD-FSM:OIF List: IIF - ge-0/0/2.0 (Added)
Dec 14 12:39:30.053853 CHANGE   225.0.0.42.192.168.25.33/64  PIM      pref 105/0 metric  <Active Int>
Dec 14 12:39:30.053934 ADD      225.0.0.42.192.168.25.33/64  PIM      pref 105/0 metric  <Active Int>
Dec 14 12:39:30.054005 rt_close: 1 route proto PIM.master
Dec 14 12:39:30.054005

Dec 14 12:39:30.054211 EVENT:DS_JOIN
sgnode: 225.0.0.42.192.168.25.33 Flags-sm:kat:
        DSM: INH_olist(s,g,rpt):NE INH_OLIST(s,g):NE
        US-FSM: US State:NOT_JOINED(RPT_NOT_PRUNED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown
J/P Attr: Flags:sm: If:ge-0/0/2.0(p) Nbr:192.168.250.140 O: LASTHOP-SPT Ht:0 AS: 0
DS-FSM: IF/NBR ge-0/0/2.0/192.168.250.140((L)) State NoInfo (NI) to Join (J)
Dec 14 12:39:30.054437 task_timer_ucreate: created timer PIM.master_RxJoin  flags <>

EVENT:DS_IMMEDIATE_OLIST_BECOMES_NON_NULL
sg state: 225.0.0.42.192.168.25.33 Flags-sm:kat:
        DSM: IMM_OLIST(s,g):NE INH_olist(s,g,rpt):NE INH_OLIST(s,g):NE
        US-FSM: US State:NOT_JOINED(RPT_NOT_PRUNED)
        RPF: us interface - ge-0/0/0.1(p) nbr unknown
US-FSM: Prerequisite fails us-ev JOIN_DESIRED_TRUE [rpf_nbr_null]
FWD-FSM:Add if ge-0/0/2.0 in OIF-list of <192.168.25.33,225.0.0.42> mcast rt
^C
%



More information about the juniper-nsp mailing list