Flight mode cannot be turned off

[philipanda]

Component

Dasharo firmware

Device

NovaCustom NV4x 12th Gen

Dasharo version

v1.8.0-rc8

Dasharo Tools Suite version

n/a

Test case ID

ECR017.201

Brief summary

1. Flight mode starts with a huge delay
2. Wifi&BT radios never come back

How reproducible

100%

How to reproduce

Press the airplane mode shortcut (fn+F11) or enable airplane mode from OS GUI.
Check if WiFi & BT are detected by the OS
Turn flight mode off.
Check again

Expected behavior

Flight mode is being enabled/disabled without noticable delays.
WiFi& BT radios are turned off when flight mode is on.
They come back once the flight mode is disabled.

Actual behavior

1. Pressing airplane mode shortcut (fn+F11) or enabling airplane mode from OS GUI (tested on Ubuntu and Windows) works with a huge delay (10-30s), and it seems like not every button press is registered. Often I needed to press the key combination multiple times for it to trigger.
2. After the first time the flight mode is triggered, turning it on/off is instantenous, as expected
3. But the WiFi and Bluetooth radios will never turn back on again after the flight mode is disabled.

dmesg logs show lots of errors when trying to disable the flight mode
dmesg.log

probable region of interest

The log below starts after first trying to disable flight mode. Then there's a second flight mode enable, and a second try to disable it.

[  426.549572] xhci_hcd 0000:00:14.0: Timeout while waiting for setup device command
[  431.669402] Bluetooth: hci0: HCI reset during shutdown failed
[  432.181541] xhci_hcd 0000:00:14.0: Timeout while waiting for setup device command
[  432.389458] usb 3-10: device not accepting address 3, error -62
[  432.389734] Bluetooth: hci0: Failed usb_autopm_get_interface: -16
[  432.389820] usb 3-10: USB disconnect, device number 3
[  432.504508] usb 3-10: new full-speed USB device number 4 using xhci_hcd
[  444.597568] iwlwifi 0000:00:14.3: WFPM_UMAC_PD_NOTIFICATION: 0x3f
[  444.597622] iwlwifi 0000:00:14.3: WFPM_LMAC2_PD_NOTIFICATION: 0x1f
[  444.597672] iwlwifi 0000:00:14.3: WFPM_AUTH_KEY_0: 0x80000400
[  444.597722] iwlwifi 0000:00:14.3: CNVI_SCU_SEQ_DATA_DW9: 0x10
[  444.597782] iwlwifi 0000:00:14.3: SecBoot CPU1 Status: 0x0, CPU2 Status: 0x3100000
[  444.597831] iwlwifi 0000:00:14.3: WFPM_LMAC1_PD_NOTIFICATION: 0x1f
[  444.597881] iwlwifi 0000:00:14.3: HPM_SECONDARY_DEVICE_STATE: 0x42
[  444.597931] iwlwifi 0000:00:14.3: WFPM_MAC_OTP_CFG7_ADDR: 0x0
[  444.597982] iwlwifi 0000:00:14.3: WFPM_MAC_OTP_CFG7_DATA: 0x0
[  444.597987] iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0xd05c18
[  444.597993] iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0xd05c1c
[  444.598000] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 13 fired (delay=0ms).
[  444.598135] iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
[  444.598140] iwlwifi 0000:00:14.3: Transport status: 0x00000242, valid: 798883821
[  444.598148] iwlwifi 0000:00:14.3: Loaded firmware version: 89.1a492d28.0 so-a0-hr-b0-89.ucode
[  444.598155] iwlwifi 0000:00:14.3: 0xFCFA3754 | ADVANCED_SYSASSERT          
[  444.598163] iwlwifi 0000:00:14.3: 0xDE2BFD25 | trm_hw_status0
[  444.598167] iwlwifi 0000:00:14.3: 0x77AFDFBE | trm_hw_status1
[  444.598172] iwlwifi 0000:00:14.3: 0xFBFDC367 | branchlink2
[  444.598176] iwlwifi 0000:00:14.3: 0xFF5B8776 | interruptlink1
[  444.598181] iwlwifi 0000:00:14.3: 0x0DE87463 | interruptlink2
[  444.598186] iwlwifi 0000:00:14.3: 0xDB52E6BF | data1
[  444.598191] iwlwifi 0000:00:14.3: 0xEEFCD27D | data2
[  444.598195] iwlwifi 0000:00:14.3: 0xC77FEC5B | data3
[  444.598200] iwlwifi 0000:00:14.3: 0x3DC83D91 | beacon time
[  444.598204] iwlwifi 0000:00:14.3: 0x5E2C9992 | tsf low
[  444.598209] iwlwifi 0000:00:14.3: 0xF8C2B65F | tsf hi
[  444.598213] iwlwifi 0000:00:14.3: 0x9D075774 | time gp1
[  444.598218] iwlwifi 0000:00:14.3: 0xD1EF33B3 | time gp2
[  444.598222] iwlwifi 0000:00:14.3: 0xBFF8FF9B | uCode revision type
[  444.598227] iwlwifi 0000:00:14.3: 0xFB4F7DF2 | uCode version major
[  444.598232] iwlwifi 0000:00:14.3: 0xA7F5E0C9 | uCode version minor
[  444.598236] iwlwifi 0000:00:14.3: 0x261A0255 | hw version
[  444.598240] iwlwifi 0000:00:14.3: 0x85A28544 | board version
[  444.598245] iwlwifi 0000:00:14.3: 0x4291405A | hcmd
[  444.598287] iwlwifi 0000:00:14.3: 0x14809310 | isr0
[  444.598295] iwlwifi 0000:00:14.3: 0x2332020B | isr1
[  444.598303] iwlwifi 0000:00:14.3: 0x1B1186EA | isr2
[  444.598311] iwlwifi 0000:00:14.3: 0x13142011 | isr3
[  444.598321] iwlwifi 0000:00:14.3: 0x38664A98 | isr4
[  444.598329] iwlwifi 0000:00:14.3: 0x2438D253 | last cmd Id
[  444.598338] iwlwifi 0000:00:14.3: 0x392C0213 | wait_event
[  444.598347] iwlwifi 0000:00:14.3: 0x80508000 | l2p_control
[  444.598355] iwlwifi 0000:00:14.3: 0x2203C0D5 | l2p_duration
[  444.598364] iwlwifi 0000:00:14.3: 0x9D048415 | l2p_mhvalid
[  444.598371] iwlwifi 0000:00:14.3: 0x08A0488C | l2p_addr_match
[  444.598380] iwlwifi 0000:00:14.3: 0x0515E202 | lmpm_pmg_sel
[  444.598388] iwlwifi 0000:00:14.3: 0xB2615B00 | timestamp
[  444.598397] iwlwifi 0000:00:14.3: 0x0DD4008D | flow_handler
[  444.598464] iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
[  444.598469] iwlwifi 0000:00:14.3: Transport status: 0x00000242, valid: -2134653567
[  444.598478] iwlwifi 0000:00:14.3: 0x45471014 | ADVANCED_SYSASSERT
[  444.598484] iwlwifi 0000:00:14.3: 0x848AE32C | umac branchlink1
[  444.598489] iwlwifi 0000:00:14.3: 0x05441783 | umac branchlink2
[  444.598495] iwlwifi 0000:00:14.3: 0x24062A40 | umac interruptlink1
[  444.598501] iwlwifi 0000:00:14.3: 0x009D0EA1 | umac interruptlink2
[  444.598508] iwlwifi 0000:00:14.3: 0x8C05070C | umac data1
[  444.598514] iwlwifi 0000:00:14.3: 0x05091A14 | umac data2
[  444.598520] iwlwifi 0000:00:14.3: 0xC4261D04 | umac data3
[  444.598526] iwlwifi 0000:00:14.3: 0x8C1F0C88 | umac major
[  444.598531] iwlwifi 0000:00:14.3: 0x0560A049 | umac minor
[  444.598537] iwlwifi 0000:00:14.3: 0x04F0129A | frame pointer
[  444.598543] iwlwifi 0000:00:14.3: 0x5103E720 | stack pointer
[  444.598549] iwlwifi 0000:00:14.3: 0xE092A308 | last host cmd
[  444.598555] iwlwifi 0000:00:14.3: 0xB017968E | isr status reg
[  444.598594] iwlwifi 0000:00:14.3: IML/ROM dump:
[  444.598599] iwlwifi 0000:00:14.3: 0x0310 | IML/ROM SYSASSERT
[  444.598604] iwlwifi 0000:00:14.3: 0x03100000 | IML/ROM error/state
[  444.598645] iwlwifi 0000:00:14.3: 0x00000000 | IML/ROM data1
[  444.598695] iwlwifi 0000:00:14.3: 0x80000400 | IML/ROM WFPM_AUTH_KEY_0
[  444.598741] iwlwifi 0000:00:14.3: Fseq Registers:
[  444.598748] iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE
[  444.598788] iwlwifi 0000:00:14.3: 0x80350002 | FSEQ_TOP_INIT_VERSION
[  444.598796] iwlwifi 0000:00:14.3: 0x00150002 | FSEQ_CNVIO_INIT_VERSION
[  444.598838] iwlwifi 0000:00:14.3: 0x0000A482 | FSEQ_OTP_VERSION
[  444.598845] iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION
[  444.598887] iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN
[  444.598894] iwlwifi 0000:00:14.3: 0x00080400 | FSEQ_CNVI_ID
[  444.598935] iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID
[  444.598943] iwlwifi 0000:00:14.3: 0x00080400 | CNVI_AUX_MISC_CHIP
[  444.599957] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_AUX_MISC_CHIP
[  444.600953] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
[  444.601956] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
[  444.601985] iwlwifi 0000:00:14.3: 0x00150002 | FSEQ_PREV_CNVIO_INIT_VERSION
[  444.601991] iwlwifi 0000:00:14.3: 0x00350002 | FSEQ_WIFI_FSEQ_VERSION
[  444.601996] iwlwifi 0000:00:14.3: 0x00350002 | FSEQ_BT_FSEQ_VERSION
[  444.602034] iwlwifi 0000:00:14.3: 0x000000EB | FSEQ_CLASS_TP_VERSION
[  444.602050] iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0xc00c06d2
[  444.602090] iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0x0
[  444.602099] iwlwifi 0000:00:14.3: Failed to start RT ucode: -110
[  444.602106] iwlwifi 0000:00:14.3: Failed to start RT ucode: -110
[  444.602111] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 13 fired (delay=0ms).
[  445.805026] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms).
[  446.330512] iwlwifi 0000:00:14.3: mac start retry 0
[  448.165543] usb 3-10: device descriptor read/64, error -110
[  464.038589] usb 3-10: device descriptor read/64, error -110
[  464.253532] usb 3-10: new full-speed USB device number 5 using xhci_hcd
[  469.749616] iwlwifi 0000:00:14.3: WFPM_UMAC_PD_NOTIFICATION: 0x3f
[  469.749670] iwlwifi 0000:00:14.3: WFPM_LMAC2_PD_NOTIFICATION: 0x1f
[  469.749767] iwlwifi 0000:00:14.3: WFPM_AUTH_KEY_0: 0x80000400
[  469.749783] iwlwifi 0000:00:14.3: CNVI_SCU_SEQ_DATA_DW9: 0x10
[  469.749842] iwlwifi 0000:00:14.3: SecBoot CPU1 Status: 0x0, CPU2 Status: 0x3100000
[  469.749892] iwlwifi 0000:00:14.3: WFPM_LMAC1_PD_NOTIFICATION: 0x1f
[  469.749941] iwlwifi 0000:00:14.3: HPM_SECONDARY_DEVICE_STATE: 0x42
[  469.749991] iwlwifi 0000:00:14.3: WFPM_MAC_OTP_CFG7_ADDR: 0x0
[  469.750042] iwlwifi 0000:00:14.3: WFPM_MAC_OTP_CFG7_DATA: 0x0
[  469.750046] iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0xd05c18
[  469.750052] iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0xd05c1c
[  469.750059] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 13 fired (delay=0ms).
[  469.750238] iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
[  469.750243] iwlwifi 0000:00:14.3: Transport status: 0x00000242, valid: 264110061
[  469.750251] iwlwifi 0000:00:14.3: Loaded firmware version: 89.1a492d28.0 so-a0-hr-b0-89.ucode
[  469.750258] iwlwifi 0000:00:14.3: 0xF4F83754 | ADVANCED_SYSASSERT          
[  469.750265] iwlwifi 0000:00:14.3: 0xDEABFD75 | trm_hw_status0
[  469.750270] iwlwifi 0000:00:14.3: 0xF7AFDF9E | trm_hw_status1
[  469.750274] iwlwifi 0000:00:14.3: 0xFBFDC367 | branchlink2
[  469.750279] iwlwifi 0000:00:14.3: 0xFE9F8FF7 | interruptlink1
[  469.750283] iwlwifi 0000:00:14.3: 0x1DE84443 | interruptlink2
[  469.750288] iwlwifi 0000:00:14.3: 0xCBD2E6BB | data1
[  469.750292] iwlwifi 0000:00:14.3: 0x6FFCBA7D | data2
[  469.750297] iwlwifi 0000:00:14.3: 0xC77F6C58 | data3
[  469.750301] iwlwifi 0000:00:14.3: 0x39C81599 | beacon time
[  469.750306] iwlwifi 0000:00:14.3: 0x4E2CB9BA | tsf low
[  469.750310] iwlwifi 0000:00:14.3: 0xF886B65F | tsf hi
[  469.750315] iwlwifi 0000:00:14.3: 0x9F0757F1 | time gp1
[  469.750319] iwlwifi 0000:00:14.3: 0xD3FF33F9 | time gp2
[  469.750324] iwlwifi 0000:00:14.3: 0xBFF9FB9F | uCode revision type
[  469.750395] iwlwifi 0000:00:14.3: 0xFB4F7DF2 | uCode version major
[  469.750405] iwlwifi 0000:00:14.3: 0xA5F5E8E9 | uCode version minor
[  469.750413] iwlwifi 0000:00:14.3: 0x27120257 | hw version
[  469.750422] iwlwifi 0000:00:14.3: 0xE5A28144 | board version
[  469.750431] iwlwifi 0000:00:14.3: 0x4291405A | hcmd
[  469.750442] iwlwifi 0000:00:14.3: 0x5182A330 | isr0
[  469.750451] iwlwifi 0000:00:14.3: 0x0312820B | isr1
[  469.750461] iwlwifi 0000:00:14.3: 0x1B1106EA | isr2
[  469.750470] iwlwifi 0000:00:14.3: 0x12142810 | isr3
[  469.750480] iwlwifi 0000:00:14.3: 0x78664A9A | isr4
[  469.750489] iwlwifi 0000:00:14.3: 0x2438D2D2 | last cmd Id
[  469.750498] iwlwifi 0000:00:14.3: 0x792E0212 | wait_event
[  469.750508] iwlwifi 0000:00:14.3: 0x80408020 | l2p_control
[  469.750516] iwlwifi 0000:00:14.3: 0x2303E0D5 | l2p_duration
[  469.750525] iwlwifi 0000:00:14.3: 0x8D858001 | l2p_mhvalid
[  469.750535] iwlwifi 0000:00:14.3: 0x0AA0488C | l2p_addr_match
[  469.750545] iwlwifi 0000:00:14.3: 0x0414C202 | lmpm_pmg_sel
[  469.750555] iwlwifi 0000:00:14.3: 0xA2604B94 | timestamp
[  469.750564] iwlwifi 0000:00:14.3: 0x0DD6208D | flow_handler
[  469.750630] iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
[  469.750636] iwlwifi 0000:00:14.3: Transport status: 0x00000242, valid: -758923123
[  469.750643] iwlwifi 0000:00:14.3: 0x84451014 | ADVANCED_SYSASSERT
[  469.750649] iwlwifi 0000:00:14.3: 0x8482E36C | umac branchlink1
[  469.750654] iwlwifi 0000:00:14.3: 0x04041683 | umac branchlink2
[  469.750659] iwlwifi 0000:00:14.3: 0x44062A40 | umac interruptlink1
[  469.750663] iwlwifi 0000:00:14.3: 0x001C0EA1 | umac interruptlink2
[  469.750668] iwlwifi 0000:00:14.3: 0xC895870C | umac data1
[  469.750673] iwlwifi 0000:00:14.3: 0x45091A70 | umac data2
[  469.750677] iwlwifi 0000:00:14.3: 0xC4261984 | umac data3
[  469.750682] iwlwifi 0000:00:14.3: 0x8D1E0C88 | umac major
[  469.750686] iwlwifi 0000:00:14.3: 0x0562A059 | umac minor
[  469.750691] iwlwifi 0000:00:14.3: 0x04B41A9B | frame pointer
[  469.750696] iwlwifi 0000:00:14.3: 0x41026D28 | stack pointer
[  469.750700] iwlwifi 0000:00:14.3: 0xC092E308 | last host cmd
[  469.750705] iwlwifi 0000:00:14.3: 0xB106B699 | isr status reg
[  469.750743] iwlwifi 0000:00:14.3: IML/ROM dump:
[  469.750747] iwlwifi 0000:00:14.3: 0x0310 | IML/ROM SYSASSERT
[  469.750751] iwlwifi 0000:00:14.3: 0x03100000 | IML/ROM error/state
[  469.750793] iwlwifi 0000:00:14.3: 0x00000000 | IML/ROM data1
[  469.750844] iwlwifi 0000:00:14.3: 0x80000400 | IML/ROM WFPM_AUTH_KEY_0
[  469.750890] iwlwifi 0000:00:14.3: Fseq Registers:
[  469.750897] iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE
[  469.750937] iwlwifi 0000:00:14.3: 0x80350002 | FSEQ_TOP_INIT_VERSION
[  469.750943] iwlwifi 0000:00:14.3: 0x00150002 | FSEQ_CNVIO_INIT_VERSION
[  469.750987] iwlwifi 0000:00:14.3: 0x0000A482 | FSEQ_OTP_VERSION
[  469.750994] iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION
[  469.751037] iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN
[  469.751044] iwlwifi 0000:00:14.3: 0x00080400 | FSEQ_CNVI_ID
[  469.751088] iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID
[  469.751094] iwlwifi 0000:00:14.3: 0x00080400 | CNVI_AUX_MISC_CHIP
[  469.752110] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_AUX_MISC_CHIP
[  469.753108] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
[  469.754109] iwlwifi 0000:00:14.3: 0x0BADCAFE | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
[  469.754142] iwlwifi 0000:00:14.3: 0x00150002 | FSEQ_PREV_CNVIO_INIT_VERSION
[  469.754151] iwlwifi 0000:00:14.3: 0x00350002 | FSEQ_WIFI_FSEQ_VERSION
[  469.754191] iwlwifi 0000:00:14.3: 0x00350002 | FSEQ_BT_FSEQ_VERSION
[  469.754196] iwlwifi 0000:00:14.3: 0x000000EB | FSEQ_CLASS_TP_VERSION
[  469.754209] iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0xc00c06d2
[  469.754248] iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0x0
[  469.754262] iwlwifi 0000:00:14.3: Failed to start RT ucode: -110
[  469.754268] iwlwifi 0000:00:14.3: Failed to start RT ucode: -110
[  469.754272] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 13 fired (delay=0ms).
[  470.877745] iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms).
[  471.342377] iwlwifi 0000:00:14.3: mac start retry 0

### Screenshots

No response

Additional context

related? #709

Solutions you've tried

Recovery possible in two ways:

1. WiFi&BT radios at Dasharo Security Options need to be disabled and re-enabled
2. Coldbooting, disconnect the system battery and CMOS battery, reconnect

[filipleple]

@philipanda I think you grabbed a laptop unit where I had flashed a WIP version of EC, trying to solve the exact issue you linked, #709. You can reflash it internally with dasharo_ectool

[philipanda]

@filipleple are you saying the automatic EC sync did not work when flashing with the v1.8.0-rc8 firmware? Is that expected for some unknown to me reason? Might try re-flashing the EC manually for a good measure

[filipleple]

@philipanda hmm, I remember I fought against the sync somehow to test this, I don't remember how I did it (setting to a higher version, or using a different board name to trigger a mismatch) but I'm pretty sure that it's confined to this one EC binary, and sync should work properly again after flashing a "normal" release EC binary

[philipanda]

Fixed by manually flashing the release EC binary
#1793