pmon#xcvrd[5995]: CMIS: Ethernet16: datapath init not pending

[fnsrcohr]

Switch port is configured to 2X400G mode.
After transceiver is inserted to the port, it shows link down, below is the log.
It seems that DPSM stays in the DataPathDeactivated state,
Why the switch keep the register DPDeinit(10h:128)=FF and TxDisable(10h:130)=FF ?

---

• 2025 Feb 13 22:50:00.483625 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=INSERTED, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=3
• 2025 Feb 13 22:50:00.511008 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: Setting appl=1
• 2025 Feb 13 22:50:00.540464 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: Setting host_lanemask=0xf
• 2025 Feb 13 22:50:00.604095 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: Setting media_lanemask=0xf
• 2025 Feb 13 22:50:00.679589 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: force Datapath reinit
• 2025 Feb 13 22:50:01.639007 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=DP_DEINIT, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=3
• 2025 Feb 13 22:50:01.681194 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: DpDeinit duration 60.0 secs, modulePwrUp duration 10.0 secs
• 2025 Feb 13 22:50:02.833142 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=AP_CONFIGURED, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=3
• 2025 Feb 13 22:50:03.119073 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=DP_INIT, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=3
• 2025 Feb 13 22:50:03.152015 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: datapath init not pending
• 2025 Feb 13 22:50:04.184341 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=INSERTED, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=4
• 2025 Feb 13 22:50:04.184341 sonic ERR pmon#xcvrd[5995]: CMIS: Ethernet16: FAILED
• 2025 Feb 13 22:50:28.793093 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: 400G, lanemask=0x0, CMIS state=INSERTED, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 0 host_lane_count 4 retries=0
• 2025 Feb 13 22:50:28.829442 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: Setting appl=1
• 2025 Feb 13 22:50:28.887713 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: Setting host_lanemask=0xf
• 2025 Feb 13 22:50:28.964295 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: Setting media_lanemask=0xf
• 2025 Feb 13 22:50:29.143540 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: force Datapath reinit
• 2025 Feb 13 22:50:31.731033 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=DP_DEINIT, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=0
• 2025 Feb 13 22:50:31.774305 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: DpDeinit duration 60.0 secs, modulePwrUp duration 10.0 secs
• 2025 Feb 13 22:50:32.111033 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=AP_CONFIGURED, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=0
• 2025 Feb 13 22:50:32.182976 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: 400G, lanemask=0xf, CMIS state=DP_INIT, Module state=ModuleReady, DP state={'DP1State': 'DataPathDeactivated', 'DP2State': 'DataPathDeactivated', 'DP3State': 'DataPathDeactivated', 'DP4State': 'DataPathDeactivated', 'DP5State': 'DataPathDeactivated', 'DP6State': 'DataPathDeactivated', 'DP7State': 'DataPathDeactivated', 'DP8State': 'DataPathDeactivated'}, appl 1 host_lane_count 4 retries=0
• 2025 Feb 13 22:50:32.194398 sonic NOTICE pmon#xcvrd[6009]: CMIS: Ethernet16: datapath init not pending
• .......

[ishidawataru]

2025 Feb 13 22:50:03.152015 sonic NOTICE pmon#xcvrd[5995]: CMIS: Ethernet16: datapath init not pending

https://github.com/sonic-net/sonic-platform-daemons/blob/master/sonic-xcvrd/xcvrd/xcvrd.py#L1475-L1476

It appears that the DPInitPendingLane register of your module is not in the PENDING state after xcvrd completes the datapath configuration through scs_apply_datapath_init.
(https://github.com/sonic-net/sonic-platform-daemons/blob/master/sonic-xcvrd/xcvrd/xcvrd.py#L1459)

Why the switch keep the register DPDeinit(10h:128)=FF and TxDisable(10h:130)=FF ?

xcvrd maintains DPDeinit and TxDisable at 0xFF until all required conditions are satisfied.

One of these conditions is that the DPInitPendingLane register must be in the PENDING state.
(Reference: https://github.com/sonic-net/sonic-platform-daemons/blob/master/sonic-xcvrd/xcvrd/xcvrd.py#L1466-L1487)

Once all conditions are met, xcvrd proceeds with datapath initialization and then enables Tx.

[ishidawataru]

@fnsrcohr

I think the issue is related to the following issue. I'll communicate with maintainers to see if we can get it addressed.

sonic-net/sonic-platform-daemons#568