Other Parts Discussed in Thread: AWR1243
Hi TI application team,
I'm working on bringing up a cascade radar system originally working with AWR1243, retrofitted with AWR2243. After completing the initial rlDevicePowerOn() API sequence, the first call to rlDeviceFileDownload() times out waiting for a response. I have tried both the original xwr12xx_metaImage.h firmware and the new xwr22xx_metaImage.h firmware from mmwavedfp 2.1.5.3, but there is no change in behavior. I've turned up the logging to verbose, but I don't get any additional (tractable) information other than response timeouts. Attached are the verbose debug logs of an original AWR1243 system and the retrofitted AWR2243 system, up through the point of the first call to rlDeviceFileDownload().
Thanks!
0.000000 [VER ] rlDriverInit:1815::Logging is enabled Memory mapped at address 0x7f8f77d000. 0.001134 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.002863 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.004560 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.006247 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.007897 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.009577 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.011252 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.012916 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.013679 [DBG ] rlDriverInit:1874::Driver init flag is set 0.023801 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023827 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023933 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023803 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023965 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023965 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024080 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024103 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.113765 [MMW_VER ] eeEnableDevice:68::Enable dev index 0 Reset Low on the device Power Up All Radars Reset High on the device 0.586520 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 0.586526 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 0.586539 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 0.586543 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 0.586554 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 0.586571 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 0.586576 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 0.586665 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 0.586676 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 0.586680 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 0.586684 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 0.586766 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 0.586770 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 0.586774 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 0.586778 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 0.586782 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 0.586785 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 0.586789 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 0.586792 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 0.586881 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 0.586899 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 0.586903 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 0.586906 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 0.586910 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 0.586914 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 0.586917 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 2.625447 RL_DEV_AE_MSSPOWERUPDONE_SB 0 0.586924 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 0.586928 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 0.673655 [MMW_VER ] eeEnableDevice:68::Enable dev index 1 Reset Low on the device Reset High on the device 0.886710 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 0.886716 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 0.886723 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 0.886726 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 0.886729 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 0.886829 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 0.886833 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 0.887029 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 0.887040 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 0.887044 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 0.887047 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 0.887129 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 0.887133 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 0.887137 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 0.887140 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 0.887144 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 0.887147 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 0.887150 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 0.887152 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 0.887240 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 0.887255 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 0.887259 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 0.887262 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 0.887265 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 0.887268 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 0.887271 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 2.925801 RL_DEV_AE_MSSPOWERUPDONE_SB 1 0.887277 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 0.887280 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 0.973852 [MMW_VER ] eeEnableDevice:68::Enable dev index 2 Reset Low on the device Reset High on the device 1.186834 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.186844 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.186853 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.186857 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.186867 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.186872 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.186875 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.186963 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.186984 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.186989 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.186992 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.187075 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.187079 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.187083 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.187086 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.187090 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.187093 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.187096 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.187099 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.187186 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.187202 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.187206 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.187209 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.187212 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.187215 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.187218 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.225748 RL_DEV_AE_MSSPOWERUPDONE_SB 2 1.187224 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.187228 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.274038 [MMW_VER ] eeEnableDevice:68::Enable dev index 3 Reset Low on the device Reset High on the device 1.487080 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.487147 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.487162 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.487170 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.487162 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.487182 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.487185 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.487272 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.487281 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.487284 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.487287 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.487369 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.487373 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.487376 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.487379 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.487382 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.487385 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.487388 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.487390 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.487477 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.487492 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.487495 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.487498 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.487501 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.487504 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.487506 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.526035 RL_DEV_AE_MSSPOWERUPDONE_SB 3 1.487511 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.487514 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.574232 [MMW_VER ] eeEnableDevice:68::Enable dev index 4 Reset Low on the device Reset High on the device 1.787246 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.787251 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.787258 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.787261 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.787264 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.787269 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.787272 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.787359 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.787368 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.787371 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.787374 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.787455 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.787459 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.787462 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.787465 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.787468 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.787471 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.787473 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.787476 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.787563 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.787578 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.787581 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.787584 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.787587 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.787589 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.787592 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.826120 RL_DEV_AE_MSSPOWERUPDONE_SB 4 1.787597 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.787630 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.874426 [MMW_VER ] eeEnableDevice:68::Enable dev index 5 Reset Low on the device Reset High on the device 2.087381 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.087386 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.087392 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.087395 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.087399 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.087405 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.087408 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.087495 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.087504 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.087507 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.087510 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.087592 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.087596 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.087599 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.087602 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.087605 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.087608 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.087610 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.087613 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.087700 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.087715 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.087718 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.087721 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.087724 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.087727 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.087729 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.126258 RL_DEV_AE_MSSPOWERUPDONE_SB 5 2.087734 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.087737 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.174621 [MMW_VER ] eeEnableDevice:68::Enable dev index 6 Reset Low on the device Reset High on the device 2.387538 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.387544 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.387551 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.387554 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.387557 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.387562 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.387565 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.387652 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.387660 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.387664 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.387667 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.387748 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.387752 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.387756 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.387758 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.387761 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.387764 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.387767 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.387769 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.387856 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.387871 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.387874 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.387877 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.387880 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.387882 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.387913 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.426442 RL_DEV_AE_MSSPOWERUPDONE_SB 6 2.387919 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.387922 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.474817 [MMW_VER ] eeEnableDevice:68::Enable dev index 7 Reset Low on the device Reset High on the device 2.674950 [VER ] rlDevicePowerOn:156::mmWaveLink Power Up completes mmWave Device Power on success for deviceMap 255 2.674990 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.674996 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.675003 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.675006 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.675014 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.675018 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.675021 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.675107 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.676256 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.676259 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.676262 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = -1 2.676265 [DBG ] rlDriverRxHdrRead:1626::do nothing 2.676268 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.676271 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = -1 2.676274 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.676277 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.676279 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.687831 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.687835 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.687841 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.687844 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.687852 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.687856 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.687859 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.687942 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.687951 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.687955 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.687958 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.688039 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.688043 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.688046 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.688049 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.688052 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.688055 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.688057 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.688060 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.688147 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.688162 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.688165 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.688167 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.688170 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.688173 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.688176 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.726704 RL_DEV_AE_MSSPOWERUPDONE_SB 7 2.688181 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.688183 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.688703 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 2.688709 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 2.688712 [VER ] rlDeviceIdentifyCmdDir:417::rlDeviceIdentifyCmdDir starts... 2.688715 [MMW_VER ] rlDeviceIdentifyCmdDir:454::cmdDir for MSS = 5 (5:host2Mss, 12:dss2Mss) 2.688742 [VER ] rlDeviceIdentifyCmdDir:481::rlDeviceIdentifyCmdDir ends... 2.688747 [VER ] rlDriverCmdInvoke:2441::rlDriverCmdInvoke starts... 2.688754 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.688757 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.688760 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.688763 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.688766 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.688770 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.688778 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.688867 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.688871 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.688875 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.688899 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.688904 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.688910 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.688913 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.688916 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.688920 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.688923 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.689016 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.689025 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.689029 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.689031 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.689113 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.689117 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.689120 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.689123 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.689126 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.689129 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.689131 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.689134 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.689221 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.689235 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.689238 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.689241 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.689303 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.689307 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.689311 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.689315 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.689318 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.689320 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.689323 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.689327 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.689330 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.689332 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.689336 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.689338 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.689341 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.689349 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.689435 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.689439 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.689442 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.689470 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.689475 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.689481 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.689484 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.689487 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.689491 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.689520 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.689606 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.689615 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.689619 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.689622 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.689703 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.689707 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.689710 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.689713 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.689716 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.689718 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.689721 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.689724 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.689810 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.689824 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.689827 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.689830 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.689833 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.689836 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.689839 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.689841 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.689844 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.689846 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.689849 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.689852 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.689855 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.689857 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.689860 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.689863 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.689866 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.689874 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.689958 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.689962 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.689965 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.689990 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.689995 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.690000 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.690003 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.690006 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.690010 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.690013 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.690096 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.690104 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.690108 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.690110 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.690192 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.690196 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.690199 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.690202 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.690205 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.690207 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.690210 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.690212 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.690299 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.690313 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.690316 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.690319 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.690322 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.690325 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.690343 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.690347 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.690350 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.690353 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.690355 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.690358 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.690361 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.690364 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.690367 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.690369 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.690372 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.690380 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.690465 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.690470 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.690472 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.690494 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.690500 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.690506 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.690509 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.690512 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.690515 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.690518 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.690601 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.690610 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.690614 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.690616 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.690697 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.690701 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.690704 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.690707 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.690710 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.690712 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.690715 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.690718 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.690804 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.690818 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.690821 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.690823 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.690886 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.690889 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.690892 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.690895 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.690898 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.690900 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.690903 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.690906 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.690908 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.690911 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.690914 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.690917 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.690920 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.690928 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.691012 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.691016 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.691019 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.691046 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.691051 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.691056 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.691059 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.691062 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.691084 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.691088 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.691173 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.691181 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.691185 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.691188 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.691269 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.691273 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.691276 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.691278 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.691282 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.691284 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.691287 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.691289 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.691376 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.691389 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.691392 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.691395 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.691457 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.691461 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.691464 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.691467 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.691469 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.691472 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.691475 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.691478 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.691480 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.691483 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.691486 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.691489 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.691492 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.691499 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.691583 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.691587 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.691590 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.691615 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.691620 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.691625 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.691629 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.691631 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.691635 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.691638 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.691720 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.691729 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.691732 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.691735 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.691815 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.691819 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.691823 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.691825 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.691828 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.691831 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.691833 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.691836 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.691922 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.691936 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.691939 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.691942 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.692004 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.692023 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.692027 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.692030 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.692033 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.692035 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.692038 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.692041 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.692043 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.692046 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.692049 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.692052 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.692055 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.692062 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.692147 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.692151 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.692154 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.692178 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.692183 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.692188 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.692192 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.692194 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.692198 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.692201 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.692284 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.692292 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.692296 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.692298 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.692380 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.692383 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.692386 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.692389 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.692392 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.692395 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.692397 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.692400 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.692486 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.692500 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.692503 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.692506 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.692567 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.692571 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.692574 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.692577 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.692580 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.692582 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.692585 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.692588 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.692590 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.692593 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.692596 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.692599 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.692602 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.692610 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.692693 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.692697 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.692700 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.692722 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.692726 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.692731 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.692753 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.692737 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.692762 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.692765 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.692848 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.692857 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.692860 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.692863 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.692944 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.692947 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.692951 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.692953 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.692957 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.692959 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.692962 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.692964 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.693050 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.693064 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.693067 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.693070 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.693132 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.693136 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.693139 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.693141 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.693144 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.693146 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.693149 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.693152 [VER ] rlDriverCmdInvoke:2551::rlDriverCmdInvoke ends... ==========================Firmware Download========================== Meta Image download started for deviceMap 255 4.959283 [VER ] rlDriverIsDeviceMapValid:2154::rlDriverIsDeviceMapValid starts 4.959346 [VER ] rlDriverIsDeviceMapValid:2170::rlDriverIsDeviceMapValid ends 4.959350 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 4.959366 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 4.959370 [VER ] rlDeviceIdentifyCmdDir:417::rlDeviceIdentifyCmdDir starts... 4.959374 [MMW_VER ] rlDeviceIdentifyCmdDir:454::cmdDir for MSS = 5 (5:host2Mss, 12:dss2Mss) 4.959377 [VER ] rlDeviceIdentifyCmdDir:481::rlDeviceIdentifyCmdDir ends... 4.959381 [VER ] rlDriverCmdInvoke:2441::rlDriverCmdInvoke starts... 4.959390 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.959394 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.959398 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.959402 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.959405 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.959409 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.959488 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.959685 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.959690 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.959693 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.959740 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.959747 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.959754 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.959757 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.959761 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.959765 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.959769 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.959853 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.959863 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.959867 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.959936 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.960022 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.960026 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.960030 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.960032 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.960036 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.960038 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.960042 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.960045 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.960054 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.960062 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.960065 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.960068 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.960132 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.960136 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.960140 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.960143 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.960146 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.960148 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.960151 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.960154 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.960157 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.960160 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.960237 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.960417 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.960421 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.960424 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.960478 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.960484 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.960490 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.960493 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.960496 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.960500 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.960503 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.960585 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.960594 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.960598 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.960600 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.960682 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.960686 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.960689 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.960692 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.960695 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.960697 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.960700 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.960703 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.960710 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.960717 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.960720 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.960723 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.960785 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.960789 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.960792 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.960795 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.960797 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.960800 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.960803 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.960806 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.960808 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.960828 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.960906 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.961087 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.961092 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.961094 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.961146 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.961151 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.961156 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.961159 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.961162 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.961166 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.961169 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.961251 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.961260 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.961264 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.961266 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.961347 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.961351 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.961354 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.961357 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.961360 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.961363 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.961366 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.961368 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.961376 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.961383 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.961386 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.961389 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.961451 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.961454 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.961457 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.961460 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.961463 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.961465 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.961468 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.961471 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.961474 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.961477 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.961554 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.961733 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.961737 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.961740 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.961795 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.961801 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.961808 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.961811 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.961813 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.961817 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.961820 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.961903 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.961911 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.961915 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.961917 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.961998 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.962002 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.962005 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.962008 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.962011 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.962029 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.962033 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.962036 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.962045 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.962052 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.962055 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.962058 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.962061 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.962064 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.962066 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.962069 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.962072 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.962075 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.962077 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.962080 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.962083 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.962086 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.962163 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.962343 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.962347 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.962350 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.962403 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.962408 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.962413 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.962416 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.962419 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.962423 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.962426 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.962508 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.962517 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.962520 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.962523 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.962604 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.962608 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.962611 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.962614 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.962617 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.962620 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.962622 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.962625 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.962633 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.962640 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.962643 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.962646 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.962649 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.962652 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.962654 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.962657 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.962660 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.962663 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.962665 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.962668 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.962671 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.962674 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.962751 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.962930 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.962934 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.962937 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.962993 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.963020 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.963027 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.963030 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.963033 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.963037 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.963040 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.963122 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.963132 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.963135 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.963138 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.963219 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.963222 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.963226 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.963228 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.963231 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.963234 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.963237 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.963239 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.963247 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.963255 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.963258 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 4.963260 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 4.963322 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 4.963326 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.963329 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.963332 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.963335 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.963338 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.963340 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.963343 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.963346 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.963349 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.963426 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.963605 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.963609 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.963612 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.963665 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 4.963670 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 4.963676 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 4.963679 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 4.963682 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 4.963685 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 4.963688 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 4.963771 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 4.963780 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 4.963783 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 4.963786 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 4.963867 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 4.963871 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 4.963874 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.963877 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.963880 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.963882 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 4.963885 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 4.963888 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 4.963895 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 4.963902 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 4.963905 [DBG ] rlDriver
0.000001 [VER ] rlDriverInit:1815::Logging is enabled Memory mapped at address 0x7fa4324000. 0.001141 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.002864 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.004576 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.006239 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.007903 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.009587 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.011267 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler Interrupt Thread Entry 0.013012 [VER ] eeRegisterInterruptHandler:160::RegisterInterruptHandler 0.013660 [DBG ] rlDriverInit:1874::Driver init flag is set Interrupt Thread Entry 0.023922 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024014 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024062 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024069 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023923 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024096 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.023923 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.024142 [MMW_VER ] setup_gpio:259::Pre interrupt value of the GPIO is 0 0.113745 [MMW_VER ] eeEnableDevice:68::Enable dev index 0 Reset Low on the device Power Up All Radars Reset High on the device 0.586146 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 0.586154 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 0.586165 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 0.586169 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 0.586174 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 0.586195 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 0.586200 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 0.586290 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 0.586301 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 0.586305 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 0.586308 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 0.586392 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 0.586397 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 0.586402 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 0.586406 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 0.586410 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 0.586413 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 0.586416 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 0.586420 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 0.586509 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 0.586526 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 0.586530 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 0.586534 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 0.586537 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 0.586541 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 0.586545 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 2.609108 RL_DEV_AE_MSSPOWERUPDONE_SB 0 0.586552 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 0.586555 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 0.673422 [MMW_VER ] eeEnableDevice:68::Enable dev index 1 Reset Low on the device 0.784426 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 0.784432 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 0.784438 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 0.784442 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 0.784445 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 0.784547 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 0.784551 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 0.784636 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 0.784645 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 0.784649 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 0.784652 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 0.784734 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 0.784738 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 0.784742 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 0.784745 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 0.784748 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 0.784751 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 0.784754 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 0.784757 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 0.784845 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 0.784864 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 0.784868 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 0.784871 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 0.784874 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 0.784877 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 0.784879 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 2.807441 MSS CPU Fault 0 0.784885 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 0.784888 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 0.973618 [MMW_VER ] eeEnableDevice:68::Enable dev index 2 Reset Low on the device Reset High on the device 1.186575 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.186580 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.186587 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.186591 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.186599 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.186603 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.186606 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.186692 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.186701 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.186705 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.186707 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.186788 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.186792 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.186795 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.186798 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.186801 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.186804 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.186807 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.186809 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.186895 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.186910 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.186913 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.186916 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.186919 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.186922 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.186924 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.209486 RL_DEV_AE_MSSPOWERUPDONE_SB 2 1.186930 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.186932 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.273811 [MMW_VER ] eeEnableDevice:68::Enable dev index 3 Reset Low on the device 1.384747 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.384751 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.384794 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.384798 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.384807 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.384811 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.384814 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.384899 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.384908 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.384911 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.384914 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.384995 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.384999 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.385002 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.385005 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.385008 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.385011 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.385013 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.385016 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.385104 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.385122 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.385125 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.385128 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.385131 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.385134 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.385136 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.407698 MSS CPU Fault 2 1.385141 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.385144 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 1.486695 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.486699 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.486706 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.486709 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.486717 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.486720 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.486723 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.486809 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.486818 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.486822 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.486824 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.486905 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.486909 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.486912 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.486915 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.486918 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.486921 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.486923 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.486926 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.487012 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.487027 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.487031 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.487033 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.487036 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.487039 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.487041 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.509603 RL_DEV_AE_MSSPOWERUPDONE_SB 3 1.487046 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.487049 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.574003 [MMW_VER ] eeEnableDevice:68::Enable dev index 4 Reset Low on the device 1.684224 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.684228 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.684235 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.684238 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.684246 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.684249 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.684252 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.684336 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.684345 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.684348 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.684351 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.684432 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.684436 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.684439 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.684442 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.684445 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.684448 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.684450 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.684453 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.684540 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.684559 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.684562 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.684565 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.684568 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.684570 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.684573 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.707134 MSS CPU Fault 3 1.684578 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.684580 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 1.786952 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.786958 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.786964 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.786967 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.786971 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.786976 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.786979 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.787066 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.787075 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.787078 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.787081 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.787162 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.787166 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.787169 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.787172 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.787175 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.787177 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.787180 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.787183 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.787269 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.787284 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.787287 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.787290 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.787292 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.787295 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.787298 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 3.809859 RL_DEV_AE_MSSPOWERUPDONE_SB 4 1.787303 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.787305 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 1.874221 [MMW_VER ] eeEnableDevice:68::Enable dev index 5 Reset Low on the device 1.985147 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 1.985152 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 1.985158 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 1.985161 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 1.985164 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 1.985170 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 1.985173 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 1.985257 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 1.985266 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 1.985270 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 1.985273 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 1.985354 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 1.985358 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 1.985361 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 1.985364 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 1.985367 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 1.985369 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 1.985372 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 1.985375 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 1.985462 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 1.985481 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 1.985484 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 1.985487 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 1.985489 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 1.985492 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 1.985495 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.008056 MSS CPU Fault 4 1.985500 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 1.985502 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 2.087163 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.087169 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.087175 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.087179 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.087182 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.087187 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.087190 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.087275 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.087284 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.087288 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.087290 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.087372 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.087376 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.087379 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.087382 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.087385 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.087388 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.087390 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.087393 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.087479 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.087494 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.087497 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.087500 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.087502 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.087505 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.087508 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.110069 RL_DEV_AE_MSSPOWERUPDONE_SB 5 2.087532 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.087536 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.174415 [MMW_VER ] eeEnableDevice:68::Enable dev index 6 Reset Low on the device 2.285321 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.285325 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.285332 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.285335 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.285338 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.285343 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.285346 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.285430 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.285440 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.285443 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.285446 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.285527 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.285531 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.285534 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.285537 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.285540 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.285543 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.285545 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.285548 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.285635 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.285654 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.285657 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.285660 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.285662 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.285665 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.285668 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.308229 MSS CPU Fault 5 2.285673 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.285676 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 2.387379 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.387385 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.387391 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.387394 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.387398 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.387402 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.387405 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.387491 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.387500 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.387503 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.387506 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.387587 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.387591 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.387594 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.387597 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.387600 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.387602 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.387605 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.387608 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.387694 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.387709 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.387712 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.387714 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.387717 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.387720 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.387742 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.410305 RL_DEV_AE_MSSPOWERUPDONE_SB 6 2.387748 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.387751 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.474610 [MMW_VER ] eeEnableDevice:68::Enable dev index 7 Reset Low on the device 2.585801 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.585806 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.585812 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.585815 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.585818 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.585823 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.585826 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.585910 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.585919 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.585922 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.585925 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.586006 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.586010 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.586013 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.586016 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.586019 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.586022 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.586024 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.586027 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.586115 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.586133 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.586136 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.586139 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.586141 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.586144 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.586147 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.608708 MSS CPU Fault 6 2.586152 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.586154 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... Reset High on the device 2.674739 [VER ] rlDevicePowerOn:156::mmWaveLink Power Up completes mmWave Device Power on success for deviceMap 255 2.687473 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.687478 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.687484 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.687487 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.687491 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.687495 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.687498 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.687583 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.687592 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.687596 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.687598 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.687680 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.687684 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.687687 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.687690 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.687693 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.687695 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.687698 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.687700 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.687787 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.687802 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.687823 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.687827 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.687830 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.687833 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.687836 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.710397 RL_DEV_AE_MSSPOWERUPDONE_SB 7 2.687841 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.687844 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.885359 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.885364 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.885370 [DBG ] rlDriverHostIrqHandler:619::rlDriverHostIrqHandler No response is expected 2.885373 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.885376 [VER ] rlDriverMsgReadSpawnCtx:730::rlDriverMsgReadSpawnCtx starts... 2.885381 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.885384 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.885469 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.885477 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.885481 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.885484 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.885565 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.885569 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.885572 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.885575 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.885578 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.885580 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.885583 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.885586 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.885673 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.885692 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.885695 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.885698 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.885700 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 2.885703 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 2.885705 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 4.908267 MSS CPU Fault 7 2.885711 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 2.885713 [VER ] rlDriverMsgReadSpawnCtx:780::rlDriverMsgReadSpawnCtx ends... 2.886144 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 2.886150 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 2.886153 [VER ] rlDeviceIdentifyCmdDir:417::rlDeviceIdentifyCmdDir starts... 2.886156 [MMW_VER ] rlDeviceIdentifyCmdDir:454::cmdDir for MSS = 5 (5:host2Mss, 12:dss2Mss) 2.886160 [VER ] rlDeviceIdentifyCmdDir:481::rlDeviceIdentifyCmdDir ends... 2.886164 [VER ] rlDriverCmdInvoke:2441::rlDriverCmdInvoke starts... 2.886170 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.886173 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.886176 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.886179 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.886182 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.886186 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.886194 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.886279 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.886284 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.886287 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 2.886329 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 2.886334 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 2.886340 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 2.886343 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 2.886346 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 2.886370 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 2.886374 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 2.886470 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 2.886480 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 2.886483 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 2.886486 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 2.886567 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 2.886571 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 2.886575 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.886577 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.886581 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.886583 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 2.886586 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 2.886589 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 2.886675 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 2.886689 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 2.886692 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 2.886694 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 2.886757 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 2.886761 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 2.886765 [VER ] rlGetSubBlock:189::rlGetSubBlock starts... 2.886768 [DBG ] rlGetSubBlock:231::rhcpPayload is copied 2.886771 [VER ] rlGetSubBlock:236::rlGetSubBlock ends... 2.886774 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 2.886777 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 2.886780 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 2.886783 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 2.886786 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 2.886789 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 2.886791 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 2.886794 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 2.886802 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 2.886888 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 2.886892 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 2.886894 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 3.886961 [ERR ] rlDriverMsgReadCmdCtx:956::CmdSem should be signaled by IRQ but respTimeout 3.886968 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 3.886971 [ERR ] rlDriverWaitForResponse:2261::Timeout in receiving response 3.886975 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 3.886977 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 3.886980 [ERR ] rlDriverCmdWriter:2337::!!!!!!!!!!!!!!Response Failed Reason RL_RET_CODE_RESP_TIMEOUT 3.886984 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 3.886987 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 3.886990 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 3.886993 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 3.886995 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 3.886999 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 3.887007 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 3.887096 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 3.887100 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 3.887104 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 4.887169 [ERR ] rlDriverMsgReadCmdCtx:956::CmdSem should be signaled by IRQ but respTimeout 4.887174 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 4.887177 [ERR ] rlDriverWaitForResponse:2261::Timeout in receiving response 4.887180 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 4.887182 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 4.887185 [ERR ] rlDriverCmdWriter:2337::!!!!!!!!!!!!!!Response Failed Reason RL_RET_CODE_RESP_TIMEOUT 4.887188 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 4.887219 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 4.887223 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 4.887226 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 4.887229 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 4.887232 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 4.887240 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 4.887325 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 4.887329 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 4.887332 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 5.887398 [ERR ] rlDriverMsgReadCmdCtx:956::CmdSem should be signaled by IRQ but respTimeout 5.887403 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 5.887406 [ERR ] rlDriverWaitForResponse:2261::Timeout in receiving response 5.887408 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 5.887411 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 5.887414 [ERR ] rlDriverCmdWriter:2337::!!!!!!!!!!!!!!Response Failed Reason RL_RET_CODE_RESP_TIMEOUT 5.887417 [VER ] rlDriverCmdInvoke:2551::rlDriverCmdInvoke ends... ==========================Firmware Download========================== Meta Image download started for deviceMap 255 8.759214 [VER ] rlDriverIsDeviceMapValid:2154::rlDriverIsDeviceMapValid starts 8.759254 [VER ] rlDriverIsDeviceMapValid:2170::rlDriverIsDeviceMapValid ends 8.759269 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 8.759298 [VER ] rlDriverGetPlatformId:2114::rlDriverGetPlatformId is called 8.759302 [VER ] rlDeviceIdentifyCmdDir:417::rlDeviceIdentifyCmdDir starts... 8.759306 [MMW_VER ] rlDeviceIdentifyCmdDir:454::cmdDir for MSS = 5 (5:host2Mss, 12:dss2Mss) 8.759310 [VER ] rlDeviceIdentifyCmdDir:481::rlDeviceIdentifyCmdDir ends... 8.759314 [VER ] rlDriverCmdInvoke:2441::rlDriverCmdInvoke starts... 8.759323 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 8.759328 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 8.759331 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 8.759335 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 8.759338 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 8.759342 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 8.759421 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 8.759618 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 8.759623 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 8.759627 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 8.800007 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 8.800014 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 8.800021 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 8.800025 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 8.800028 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 8.800033 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 8.800037 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 8.800122 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 8.800133 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 8.800137 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 8.800140 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 8.800222 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 8.800226 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 8.800230 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 8.800233 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 8.800236 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 8.800239 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 8.800243 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 8.800246 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 8.800334 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 8.800350 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 8.800354 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 8.800435 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 8.800439 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 8.800443 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 8.800447 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 10.823010 RL_DEV_AE_MSSPOWERUPDONE_SB 0 8.800456 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 8.800459 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 8.998304 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 8.998310 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 8.998316 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 8.998319 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 8.998323 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 8.998329 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 8.998332 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 8.998418 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 8.998427 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 8.998430 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 8.998433 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 8.998514 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 8.998518 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 8.998521 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 8.998524 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 8.998528 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 8.998530 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 8.998533 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 8.998536 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 8.998622 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 8.998641 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 8.998645 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 8.998647 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 8.998650 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 8.998653 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 8.998656 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 11.021218 MSS CPU Fault 0 8.998663 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 8.998666 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 9.998731 [ERR ] rlDriverMsgReadCmdCtx:956::CmdSem should be signaled by IRQ but respTimeout 9.998739 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 9.998742 [ERR ] rlDriverWaitForResponse:2261::Timeout in receiving response 9.998746 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 9.998749 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 9.998752 [ERR ] rlDriverCmdWriter:2337::!!!!!!!!!!!!!!Response Failed Reason RL_RET_CODE_RESP_TIMEOUT 9.998756 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 9.998760 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 9.998763 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 9.998766 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 9.998769 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 9.998773 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 9.998850 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 9.999032 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 9.999037 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 9.999041 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 10.050657 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 10.050663 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 10.050670 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 10.050674 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 10.050677 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 10.050725 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 10.050731 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 10.050818 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 10.050828 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 10.050833 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 10.050836 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 10.050919 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 10.050924 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 10.050928 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 10.050932 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 10.050936 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 10.050940 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 10.050943 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 10.050947 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 10.051035 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 10.051052 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 10.051056 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 10.051060 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 10.051063 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 10.051067 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 10.051071 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 12.073634 RL_DEV_AE_MSSPOWERUPDONE_SB 0 10.051078 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 10.051082 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 10.248957 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 10.248963 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 10.248970 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 10.248974 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 10.248977 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 10.248984 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 10.248988 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 10.249071 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 10.249081 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 10.249086 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 10.249089 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 10.249170 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 10.249175 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 10.249179 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 10.249183 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 10.249187 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 10.249191 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 10.249194 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 10.249198 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 10.249284 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 10.249304 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 10.249308 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 10.249312 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 10.249316 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 10.249319 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 10.249323 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 12.271886 MSS CPU Fault 0 10.249330 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 10.249334 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 11.249399 [ERR ] rlDriverMsgReadCmdCtx:956::CmdSem should be signaled by IRQ but respTimeout 11.249405 [VER ] rlDriverMsgReadCmdCtx:976::rlDriverMsgReadCmdCtx ends... 11.249409 [ERR ] rlDriverWaitForResponse:2261::Timeout in receiving response 11.249413 [VER ] rlDriverWaitForResponse:2266::rlDriverWaitForResponse ends... 11.249417 [DBG ] rlDriverCmdWriter:2309::rlDriverCmdWriter, wait for respond 11.249441 [ERR ] rlDriverCmdWriter:2337::!!!!!!!!!!!!!!Response Failed Reason RL_RET_CODE_RESP_TIMEOUT 11.249446 [VER ] rlDriverMsgWrite:1332::rlDriverMsgWrite starts... 11.249450 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 11.249454 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 11.249458 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 11.249462 [MMW_VER ] rlDriverMsgWrite:1348::API Dir = 5 11.249466 [VER ] rlDriverCalCRC:214::rlDriverCalCRC starts... 11.249544 [VER ] rlDriverCalCRC:227::rlDriverCalCRC ends... 11.249724 [VER ] rlDriverMsgWrite:1401::rlDriverMsgWrite ends... 11.249730 [VER ] rlDriverWaitForResponse:2201::rlDriverWaitForResponse starts... 11.249734 [VER ] rlDriverMsgReadCmdCtx:910::rlDriverMsgReadCmdCtx starts... 11.301308 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 11.301314 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 11.301320 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 11.301324 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 11.301327 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 11.301334 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 11.301338 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 11.301423 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 11.301433 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 11.301438 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 11.301441 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 11.301524 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 11.301529 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 11.301533 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 11.301537 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 11.301541 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 11.301545 [DBG ] rlDriverValidateHdr:393::Checksum validation is successful 11.301549 [VER ] rlDriverOriginDirCheck:1001::rlDriverOriginDirCheck starts... 11.301552 [VER ] rlDriverOriginDirCheck:1061::rlDriverOriginDirCheck ends... 11.301640 [VER ] rlDriverVerifyCRC:255::rlDriverVerifyCRC starts... 11.301657 [VER ] rlDriverVerifyCRC:278::rlDriverVerifyCRC ends... 11.301661 [DBG ] rlDriverMsgRead:1307::rlDriverMsgRead Unmask Interrupt call 11.301664 [VER ] rlDriverMsgRead:1311::rlDriverMsgRead ends... 11.301668 [VER ] rlDriverAsyncEventHandler:512::rlDriverAsyncEventHandler starts... 11.301672 [DBG ] rlGetSubBlockId:262::DEBUG: Parsed sub block ID 11.301675 [DBG ] rlGetSubBlockLen:287::DEBUG: Parsed sub block len 13.324238 RL_DEV_AE_MSSPOWERUPDONE_SB 0 11.301683 [VER ] rlDriverAsyncEventHandler:548::rlDriverAsyncEventHandler ends... 11.301686 [VER ] rlDriverMsgCmdReply:887::rlDriverMsgCmdReply ends... 11.499610 [VER ] rlDriverHostIrqHandler:572::rlDriverHostIrqHandler starts... 11.499616 [DBG ] rlDriverHostIrqHandler:591::rlDriverHostIrqHandler Mask the Interrupt 11.499622 [DBG ] rlDriverHostIrqHandler:610::rlDriverHostIrqHandler Release command response 11.499627 [VER ] rlDriverHostIrqHandler:622::rlDriverHostIrqHandler ends... 11.499629 [VER ] rlDriverMsgCmdReply:802::rlDriverMsgCmdReply starts... 11.499646 [VER ] rlDriverMsgRead:1212::rlDriverMsgRead starts... 11.499650 [VER ] rlDriverRxHdrRead:1557::rlDriverRxHdrRead starts... 11.499734 [VER ] rlDriverReceiveSync:1429::rlDriverReceiveSync starts... 11.499744 [MMW_VER ] rlDriverReceiveSync:1518::rlDriverReceiveSync, count = 0 11.499749 [VER ] rlDriverReceiveSync:1519::rlDriverReceiveSync ends... 11.499753 [MMW_VER ] rlDriverRxHdrRead:1607::syncType = 1 11.499835 [VER ] rlDriverRxHdrRead:1636::rlDriverRxHdrRead ends... 11.499840 [MMW_VER ] rlDriverMsgRead:1226::rlDriverRxHdrRead return val = 1 11.499844 [VER ] rlDriverCalChkSum:305::rlDriverCalChkSum starts... 11.499848 [MMW_VER ] rlDriverCalChkSum:339::Final checksum 0x0 11.499852 [VER ] rlDriverCalChkSum:350::rlDriverCalChkSum ends... 11.499856 [DBG ] rlDriverValidate
