Cannot connect to nRF52840 Dongle

Hi

I having a problem programming my nRF52840 Dongle from the Programmer v3.0.6 in nRF Connect for Desktop v4.0.0 (on Windows 10 @ AMD Ryzen 7). I put the dongle in dfu mode by pressing the reset button on the dongle and it starts pulsing the led red and the programmer is also able to find it (The device I can select is called Open DFU Bootloader / D1871BF59F6B). Selecting and open this device in the programmer it just tells it is an UNKNOWN_FAMILY and zebra stripes are shown. Turning on the verbose logging gives this output:

2023-02-19T18:03:19.358Z INFO [nrfdl][info](19:03:19.359) Loaded plugin C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl, starting to resolve symbols
2023-02-19T18:03:19.358Z DEBUG [nrfdl][debug](19:03:19.359) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
2023-02-19T18:03:19.574Z ERROR [nrfdl][error](19:03:19.574) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
2023-02-19T18:03:19.884Z ERROR [nrfdl][error](19:03:19.884) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
2023-02-19T18:03:20.105Z DEBUG Client Id: 7876168b-42c0-46e1-bc90-9a9c5205873c
2023-02-19T18:03:20.214Z ERROR [nrfdl][error](19:03:20.196) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
2023-02-19T18:03:20.496Z ERROR [nrfdl][error](19:03:20.497) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
2023-02-19T18:03:20.496Z ERROR [nrfdl][error](19:03:20.497) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
2023-02-19T18:03:20.496Z DEBUG [nrfdl][debug](19:03:20.497) ~async_task id: 110249217441
2023-02-19T18:03:20.496Z INFO [nrfdl][info](19:03:20.497) close plugin dll at path: C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl
2023-02-19T18:03:20.497Z ERROR Error when fetching device versions: async task failure for id: 110249217441 msg: [sdfu] serialport could not be opened (Origin: "Error when running operation: fw-read-info")
2023-02-19T18:03:21.627Z DEBUG Google Analytics for category pc-nrfconnect-programmer has initialized

What can I do to solve this apparently access issue?

Br

Kim

  • Hello,

    Are you sure your dongle's COM port is not opened in another application? Try disconnecting the dongle and reconnecting it to make sure that nothing has already opened the COM port. 

    Do you have administrator rights on your computer? Is Serial ports' access denied if you do not have it?

    Best regards,

    Edvin

  • Yes I have admin rights. Just been doing a bit more research and in my case, when the nRF52840 Dongle is in bootloader mode (red led pulsing) it is enumerated to COM5 on my PC. When I then try to open COM5 in a terminal program it also reports "access denied" for COM5. If I then reboot my PC still having the Dongle connected, I'm able to connect to COM5 after reboot in the terminal program. If I then bring up the Programmer it also connects and do some SDFU writings to the dongle (try to program the "ble_connectivity_s140_usb_hci_pca10056.hex" file). When it reaches 100% the Programmer seems to try to reopen the COM5 but now fails again with "Access Denied" error. So I'm ending up in a loop. Attached the verbose debug log for the whole session.

    2023-02-21T22:44:13.972Z DEBUG Application data folder: C:\Users\Kim\AppData\Roaming\nrfconnect\pc-nrfconnect-programmer
    2023-02-21T22:44:13.995Z DEBUG App pc-nrfconnect-programmer v3.0.6 local
    2023-02-21T22:44:13.996Z DEBUG App path: C:\Users\Kim\.nrfconnect-apps\node_modules\pc-nrfconnect-programmer
    2023-02-21T22:44:13.996Z DEBUG nRFConnect 4.0.0, required by the app is (>=4.0.0)
    2023-02-21T22:44:13.996Z DEBUG nRFConnect path: C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar
    2023-02-21T22:44:13.996Z DEBUG HomeDir: C:\Users\Kim
    2023-02-21T22:44:13.996Z DEBUG TmpDir: C:\Users\Kim\AppData\Local\Temp
    2023-02-21T22:44:13.997Z DEBUG [nrfdl][debug](23:44:13.980) Getting config descriptor
    2023-02-21T22:44:13.997Z DEBUG [nrfdl][debug](23:44:13.981) Iterating descriptors.
    2023-02-21T22:44:13.997Z DEBUG [nrfdl][debug](23:44:13.981) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 8ms]
    2023-02-21T22:44:13.997Z DEBUG [nrfdl][debug](23:44:13.981) USBLister: Adding device with serial number: 5D864555333
    2023-02-21T22:44:13.997Z DEBUG [nrfdl][debug](23:44:13.981) USBLister: Adding device with serial number: D1871BF59F6B
    2023-02-21T22:44:13.998Z DEBUG [nrfdl][debug](23:44:13.981) USBLister: Adding device with serial number: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:13.998Z DEBUG [nrfdl][debug](23:44:13.981) USBLister: Adding device with serial number: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:13.998Z DEBUG [nrfdl][debug](23:44:13.981) USBLister: Adding device with serial number: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:13.998Z VERBOSE [nrfdl][trace](23:44:13.981) DeviceLister::enumerate::enumerate_task: After async_lister->enumerate
    2023-02-21T22:44:13.998Z VERBOSE [nrfdl][trace](23:44:13.982) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:13.998Z DEBUG [nrfdl][debug](23:44:13.982) [jlink] [->] NRFDL::UniversalPlatform::JLinkHW::enumerate
    2023-02-21T22:44:13.998Z DEBUG [nrfdl][debug](23:44:13.982) [jlink] JLinkHW::enumerate all devices
    2023-02-21T22:44:14.147Z DEBUG [nrfdl][debug](23:44:14.147) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.147Z DEBUG [nrfdl][debug](23:44:14.147) [jlink] [NRFJPROG][Client][] open
    2023-02-21T22:44:14.147Z DEBUG [nrfdl][debug](23:44:14.147) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.147Z DEBUG [nrfdl][debug](23:44:14.147) [jlink] [NRFJPROG][Client][] start
    2023-02-21T22:44:14.192Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.192Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][SeggerBackend][] Logger sink registered in Segger backend logger
    2023-02-21T22:44:14.192Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][JLink][] Logger sink registered in JLink logger
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][nRFXX][] open
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][nRFXX][] just_check_family
    2023-02-21T22:44:14.193Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.194Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][SeggerBackend][] open_dll
    2023-02-21T22:44:14.194Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.194Z DEBUG [nrfdl][debug](23:44:14.193) [jlink] [NRFJPROG][SeggerBackend][] No J-Link DLL path was provided. Attempting to auto detect.
    2023-02-21T22:44:14.196Z DEBUG [nrfdl][debug](23:44:14.197) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.196Z VERBOSE [nrfdl][trace](23:44:14.197) [jlink] [NRFJPROG][Client][] Command open executed for 43 milliseconds with result 0
    2023-02-21T22:44:14.208Z DEBUG [nrfdl][debug](23:44:14.209) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.208Z DEBUG [nrfdl][debug](23:44:14.209) [jlink] [NRFJPROG][SeggerBackend][] Set batch mode
    2023-02-21T22:44:14.208Z DEBUG [nrfdl][debug](23:44:14.209) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.208Z DEBUG [nrfdl][debug](23:44:14.209) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:14.208Z DEBUG [nrfdl][debug](23:44:14.209) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.209Z VERBOSE [nrfdl][trace](23:44:14.209) [jlink] [NRFJPROG][Worker][] Command open executed for 8 milliseconds with result 0
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] [NRFJPROG][Client][] jlink_dll_version
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] [NRFJPROG][Client][] open
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.331) [jlink] [NRFJPROG][Client][] start
    2023-02-21T22:44:14.331Z DEBUG [nrfdl][debug](23:44:14.332) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.331Z VERBOSE [nrfdl][trace](23:44:14.332) [jlink] [NRFJPROG][Client][] Command jlink_dll_version executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.337Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.337Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] [NRFJPROG][nRFXX][] dll_version
    2023-02-21T22:44:14.337Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.338Z DEBUG [nrfdl][debug](23:44:14.338) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.338Z VERBOSE [nrfdl][trace](23:44:14.338) [jlink] [NRFJPROG][Worker][] Command jlink_dll_version executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.377Z DEBUG [nrfdl][debug](23:44:14.378) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.377Z VERBOSE [nrfdl][trace](23:44:14.378) [jlink] [NRFJPROG][Client][] Command open executed for 42 milliseconds with result 0
    2023-02-21T22:44:14.384Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.384Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][SeggerBackend][] Logger sink registered in Segger backend logger
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][JLink][] Logger sink registered in JLink logger
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][nRFXX][] open
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][nRFXX][] just_check_family
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][SeggerBackend][] open_dll
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][SeggerBackend][] No J-Link DLL path was provided. Attempting to auto detect.
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][SeggerBackend][] Set batch mode
    2023-02-21T22:44:14.385Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.386Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:14.386Z DEBUG [nrfdl][debug](23:44:14.385) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.386Z VERBOSE [nrfdl][trace](23:44:14.385) [jlink] [NRFJPROG][Worker][] Command open executed for 7 milliseconds with result 0
    2023-02-21T22:44:14.496Z DEBUG [nrfdl][debug](23:44:14.496) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.496Z DEBUG [nrfdl][debug](23:44:14.496) [jlink] [NRFJPROG][Client][] open
    2023-02-21T22:44:14.496Z DEBUG [nrfdl][debug](23:44:14.496) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.496Z DEBUG [nrfdl][debug](23:44:14.496) [jlink] [NRFJPROG][Client][] start
    2023-02-21T22:44:14.502Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][nRFXX][] close
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][SeggerBackend][] close
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][SeggerBackend][] disconnect_from_emu
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][SeggerBackend][] Segger Backend closed.
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.503Z DEBUG [nrfdl][debug](23:44:14.503) [jlink] [NRFJPROG][nRFXX][] nRF family DLL closed
    2023-02-21T22:44:14.512Z DEBUG [nrfdl][debug](23:44:14.513) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.513Z VERBOSE [nrfdl][trace](23:44:14.513) [jlink] [NRFJPROG][Client][] Command close executed for 16 milliseconds with result 0
    2023-02-21T22:44:14.513Z DEBUG [nrfdl][debug](23:44:14.513) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.513Z DEBUG [nrfdl][debug](23:44:14.513) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.513Z DEBUG [nrfdl][debug](23:44:14.513) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.513Z VERBOSE [nrfdl][trace](23:44:14.513) [jlink] [NRFJPROG][Client][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.542Z DEBUG [nrfdl][debug](23:44:14.543) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.543Z VERBOSE [nrfdl][trace](23:44:14.543) [jlink] [NRFJPROG][Client][] Command open executed for 42 milliseconds with result 0
    2023-02-21T22:44:14.544Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.544Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z VERBOSE [nrfdl][trace](23:44:14.545) [jlink] [NRFJPROG][Worker][] Command close executed for 15 milliseconds with result 0
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][SeggerBackend][] Logger sink registered in Segger backend logger
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z VERBOSE [nrfdl][trace](23:44:14.545) [jlink] [NRFJPROG][Worker][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][JLink][] Logger sink registered in JLink logger
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z VERBOSE [nrfdl][trace](23:44:14.545) [jlink] [NRFJPROG][Worker][] Executed 4 commands for 23 milliseconds
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][nRFXX][] open
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][nRFXX][] just_check_family
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.545Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][SeggerBackend][] open_dll
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][SeggerBackend][] No J-Link DLL path was provided. Attempting to auto detect.
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][SeggerBackend][] Set batch mode
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:14.546Z DEBUG [nrfdl][debug](23:44:14.545) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.546Z VERBOSE [nrfdl][trace](23:44:14.545) [jlink] [NRFJPROG][Worker][] Command open executed for 7 milliseconds with result 0
    2023-02-21T22:44:14.577Z DEBUG [nrfdl][debug](23:44:14.577) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.577Z DEBUG [nrfdl][debug](23:44:14.577) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.577Z DEBUG [nrfdl][debug](23:44:14.577) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.577Z DEBUG [nrfdl][debug](23:44:14.577) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.578Z DEBUG [nrfdl][debug](23:44:14.578) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.578Z DEBUG [nrfdl][debug](23:44:14.578) [jlink] [NRFJPROG][Client][] Child process terminated with result 0
    2023-02-21T22:44:14.578Z DEBUG [nrfdl][debug](23:44:14.578) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.578Z VERBOSE [nrfdl][trace](23:44:14.578) [jlink] [NRFJPROG][Client][] Executed 4 commands for 59 milliseconds
    2023-02-21T22:44:14.578Z DEBUG [nrfdl][debug](23:44:14.578) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.578Z DEBUG [nrfdl][debug](23:44:14.578) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.591Z DEBUG [nrfdl][debug](23:44:14.592) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.591Z DEBUG [nrfdl][debug](23:44:14.592) [jlink] [NRFJPROG][Client][] enum_emu_snr
    2023-02-21T22:44:14.592Z DEBUG [nrfdl][debug](23:44:14.592) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.592Z DEBUG [nrfdl][debug](23:44:14.592) [jlink] [NRFJPROG][Client][] jlink_dll_version
    2023-02-21T22:44:14.592Z INFO Using nrf-device-lib-js version: 0.5.0
    2023-02-21T22:44:14.592Z INFO Using nrf-device-lib version: 0.13.11
    2023-02-21T22:44:14.592Z INFO Using nrfjprog DLL version: 10.18.2
    2023-02-21T22:44:14.592Z INFO Using JLink version: JLink_V7.66a
    2023-02-21T22:44:14.594Z DEBUG [nrfdl][debug](23:44:14.594) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.594Z VERBOSE [nrfdl][trace](23:44:14.594) [jlink] [NRFJPROG][Client][] Command jlink_dll_version executed for 2 milliseconds with result 0
    2023-02-21T22:44:14.595Z DEBUG [nrfdl][debug](23:44:14.596) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.596Z VERBOSE [nrfdl][trace](23:44:14.596) [jlink] [NRFJPROG][Client][] Command enum_emu_snr executed for 4 milliseconds with result 0
    2023-02-21T22:44:14.596Z DEBUG [nrfdl][debug](23:44:14.596) [jlink] Found 0 JLink devices connected
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][nRFXX][] enum_emu_snr
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][SeggerBackend][] enum_emu_snr
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][SeggerBackend][] ---just_enum_emu_snr
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][SeggerBackend][] ---just_get_num_emus
    2023-02-21T22:44:14.605Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.606Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.606Z DEBUG [nrfdl][debug](23:44:14.605) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.606Z VERBOSE [nrfdl][trace](23:44:14.605) [jlink] [NRFJPROG][Worker][] Command enum_emu_snr executed for 2 milliseconds with result 0
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][nRFXX][] dll_version
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.610Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.610Z VERBOSE [nrfdl][trace](23:44:14.610) [jlink] [NRFJPROG][Worker][] Command jlink_dll_version executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][nRFXX][] close
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] close
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] disconnect_from_emu
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.611Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][SeggerBackend][] Segger Backend closed.
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][nRFXX][] nRF family DLL closed
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.612Z VERBOSE [nrfdl][trace](23:44:14.610) [jlink] [NRFJPROG][Client][] Command close executed for 16 milliseconds with result 0
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.612Z DEBUG [nrfdl][debug](23:44:14.610) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.625Z DEBUG [nrfdl][debug](23:44:14.625) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.625Z VERBOSE [nrfdl][trace](23:44:14.625) [jlink] [NRFJPROG][Client][] Command terminate executed for 14 milliseconds with result 0
    2023-02-21T22:44:14.641Z DEBUG [nrfdl][debug](23:44:14.641) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.641Z VERBOSE [nrfdl][trace](23:44:14.641) [jlink] [NRFJPROG][Worker][] Command close executed for 14 milliseconds with result 0
    2023-02-21T22:44:14.641Z DEBUG [nrfdl][debug](23:44:14.641) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.641Z VERBOSE [nrfdl][trace](23:44:14.641) [jlink] [NRFJPROG][Worker][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.641Z DEBUG [nrfdl][debug](23:44:14.641) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.641Z VERBOSE [nrfdl][trace](23:44:14.641) [jlink] [NRFJPROG][Worker][] Executed 4 commands for 21 milliseconds
    2023-02-21T22:44:14.673Z DEBUG [nrfdl][debug](23:44:14.673) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.673Z DEBUG [nrfdl][debug](23:44:14.673) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.673Z DEBUG [nrfdl][debug](23:44:14.673) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.673Z DEBUG [nrfdl][debug](23:44:14.673) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.674Z DEBUG [nrfdl][debug](23:44:14.674) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.674Z DEBUG [nrfdl][debug](23:44:14.674) [jlink] [NRFJPROG][Client][] Child process terminated with result 0
    2023-02-21T22:44:14.674Z DEBUG [nrfdl][debug](23:44:14.674) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.674Z VERBOSE [nrfdl][trace](23:44:14.674) [jlink] [NRFJPROG][Client][] Executed 4 commands for 74 milliseconds
    2023-02-21T22:44:14.674Z DEBUG [nrfdl][debug](23:44:14.674) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.674Z DEBUG [nrfdl][debug](23:44:14.674) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.705Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.705Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][nRFXX][] close
    2023-02-21T22:44:14.705Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.705Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.705Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][SeggerBackend][] close
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][SeggerBackend][] disconnect_from_emu
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][SeggerBackend][] Segger Backend closed.
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.706Z DEBUG [nrfdl][debug](23:44:14.705) [jlink] [NRFJPROG][nRFXX][] nRF family DLL closed
    2023-02-21T22:44:14.720Z DEBUG [nrfdl][debug](23:44:14.720) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.720Z VERBOSE [nrfdl][trace](23:44:14.720) [jlink] [NRFJPROG][Client][] Command close executed for 33 milliseconds with result 0
    2023-02-21T22:44:14.720Z DEBUG [nrfdl][debug](23:44:14.720) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.720Z DEBUG [nrfdl][debug](23:44:14.720) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.735Z DEBUG [nrfdl][debug](23:44:14.735) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.735Z VERBOSE [nrfdl][trace](23:44:14.735) [jlink] [NRFJPROG][Worker][] Command close executed for 30 milliseconds with result 0
    2023-02-21T22:44:14.735Z DEBUG [nrfdl][debug](23:44:14.735) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.735Z VERBOSE [nrfdl][trace](23:44:14.735) [jlink] [NRFJPROG][Client][] Command terminate executed for 14 milliseconds with result 0
    2023-02-21T22:44:14.749Z DEBUG [nrfdl][debug](23:44:14.749) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.749Z VERBOSE [nrfdl][trace](23:44:14.749) [jlink] [NRFJPROG][Worker][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:14.749Z DEBUG [nrfdl][debug](23:44:14.749) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.749Z VERBOSE [nrfdl][trace](23:44:14.749) [jlink] [NRFJPROG][Worker][] Executed 4 commands for 39 milliseconds
    2023-02-21T22:44:14.782Z DEBUG [nrfdl][debug](23:44:14.782) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.782Z DEBUG [nrfdl][debug](23:44:14.782) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.782Z DEBUG [nrfdl][debug](23:44:14.782) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.782Z DEBUG [nrfdl][debug](23:44:14.782) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:14.783Z DEBUG [nrfdl][debug](23:44:14.783) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.783Z DEBUG [nrfdl][debug](23:44:14.783) [jlink] [NRFJPROG][Client][] Child process terminated with result 0
    2023-02-21T22:44:14.783Z DEBUG [nrfdl][debug](23:44:14.783) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.783Z VERBOSE [nrfdl][trace](23:44:14.783) [jlink] [NRFJPROG][Client][] Executed 4 commands for 93 milliseconds
    2023-02-21T22:44:14.783Z DEBUG [nrfdl][debug](23:44:14.783) [jlink] No SEGGER instance provided
    2023-02-21T22:44:14.783Z DEBUG [nrfdl][debug](23:44:14.783) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:14.795Z DEBUG [nrfdl][debug](23:44:14.796) [jlink] JLinkHW::enumerate waiting for futures
    2023-02-21T22:44:14.795Z DEBUG [nrfdl][debug](23:44:14.796) [jlink] JLinkHW::enumerate done waiting for futures
    2023-02-21T22:44:14.796Z DEBUG [nrfdl][debug](23:44:14.796) [jlink] [<-] NRFDL::UniversalPlatform::JLinkHW::enumerate [took 813ms]
    2023-02-21T22:44:14.798Z DEBUG [nrfdl][debug](23:44:14.799) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:14.799Z DEBUG [nrfdl][debug](23:44:14.800) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::readHwInfo
    2023-02-21T22:44:14.800Z DEBUG [nrfdl][debug](23:44:14.800) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:15.355Z INFO [nrfdl][info](23:44:15.355) [sdfu] Successfully opened \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}. Baud rate: 115200. Flow control: none. Parity: none
    2023-02-21T22:44:15.355Z DEBUG [nrfdl][debug](23:44:15.355) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:15.361Z DEBUG [nrfdl][debug](23:44:15.361) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::readHwInfo [took 561ms]
    2023-02-21T22:44:15.361Z DEBUG [nrfdl][debug](23:44:15.361) [sdfu] serial port read on port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} aborted
    2023-02-21T22:44:15.362Z VERBOSE [nrfdl][trace](23:44:15.363) [sdfu] serial io_context executed 2 handlers.
    2023-02-21T22:44:15.362Z INFO [nrfdl][info](23:44:15.363) [sdfu] serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} closed.
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.364) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Found device with serial number: 5D864555333
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Found device with serial number: D1871BF59F6B
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: device registered to device list
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Found device with serial number: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:15.364Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Found device with serial number: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:15.365Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Found device with serial number: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:15.365Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: Completed waiting for enumerate task
    2023-02-21T22:44:15.365Z VERBOSE [nrfdl][trace](23:44:15.365) DeviceLister::enumerate: done
    2023-02-21T22:44:18.981Z DEBUG Client Id: 7876168b-42c0-46e1-bc90-9a9c5205873c
    2023-02-21T22:44:20.497Z DEBUG Google Analytics for category pc-nrfconnect-programmer has initialized
    2023-02-21T22:44:26.771Z INFO Parsing HEX file:  C:\Users\Kim\Desktop\hex\ble_connectivity_s140_usb_hci_pca10056.hex
    2023-02-21T22:44:26.785Z INFO File was last modified at  8/21/2021, 4:17:03 PM
    2023-02-21T22:44:26.883Z INFO Update files regions according to Application core
    2023-02-21T22:44:26.883Z INFO Parse memory regions for file
    2023-02-21T22:44:26.884Z INFO SoftDevice detected, id 0x100 (S140 v7.2.0)
    2023-02-21T22:44:31.132Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"architecture","label":"ia32"}
    2023-02-21T22:44:31.134Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"Open device","label":"nordicUsb"}
    2023-02-21T22:44:31.137Z INFO Using @nordicsemiconductor/nrf-device-lib-js to communicate with target via USB SDFU protocol
    2023-02-21T22:44:31.137Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"Open jlink device family","label":"NRF52_FAMILY"}
    2023-02-21T22:44:31.138Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"Open jlink device version","label":"nRF52840"}
    2023-02-21T22:44:31.139Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"Open jlink device board version","label":"PCA10059"}
    2023-02-21T22:44:31.147Z VERBOSE [nrfdl][trace](23:44:31.121) DeviceLister::enumerate: Checking future_status of enumerate_task_status
    2023-02-21T22:44:31.147Z VERBOSE [nrfdl][trace](23:44:31.121) DeviceLister::enumerate: Waiting for enumerate task to complete
    2023-02-21T22:44:31.147Z VERBOSE [nrfdl][trace](23:44:31.121) DeviceLister::enumerate::enumerate_task: Start
    2023-02-21T22:44:31.147Z VERBOSE [nrfdl][trace](23:44:31.121) DeviceLister::enumerate::enumerate_task: Before async_lister->enumerate
    2023-02-21T22:44:31.147Z DEBUG [nrfdl][debug](23:44:31.122) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:31.147Z DEBUG [nrfdl][debug](23:44:31.122) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 13, origin: {F56398B9-C255-5ABC-B724-995BC41C42EE}
    2023-02-21T22:44:31.147Z DEBUG [nrfdl][debug](23:44:31.122) Starting DeviceIoControl
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Getting config descriptor
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Iterating descriptors.
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 15, origin: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Starting DeviceIoControl
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Getting config descriptor
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Iterating descriptors.
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 0ms]
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 3, origin: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.129) Starting DeviceIoControl
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.132) Getting config descriptor
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.132) Iterating descriptors.
    2023-02-21T22:44:31.148Z DEBUG [nrfdl][debug](23:44:31.132) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.132) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.132) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 4, origin: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.132) Starting DeviceIoControl
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) Getting config descriptor
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) Iterating descriptors.
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 20, origin: {E6EA6479-A448-5812-BA69-8AAB6114BB69}
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.134) Starting DeviceIoControl
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.142) Getting config descriptor
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.142) Iterating descriptors.
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.142) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:31.149Z VERBOSE [nrfdl][trace](23:44:31.142) DeviceLister::enumerate::enumerate_task: After async_lister->enumerate
    2023-02-21T22:44:31.149Z VERBOSE [nrfdl][trace](23:44:31.143) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:31.149Z DEBUG [nrfdl][debug](23:44:31.143) [jlink] [->] NRFDL::UniversalPlatform::JLinkHW::enumerate
    2023-02-21T22:44:31.150Z DEBUG [nrfdl][debug](23:44:31.143) [jlink] JLinkHW::enumerate all devices
    2023-02-21T22:44:31.310Z DEBUG [nrfdl][debug](23:44:31.310) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.310Z DEBUG [nrfdl][debug](23:44:31.310) [jlink] [NRFJPROG][Client][] open
    2023-02-21T22:44:31.310Z DEBUG [nrfdl][debug](23:44:31.310) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.310Z DEBUG [nrfdl][debug](23:44:31.310) [jlink] [NRFJPROG][Client][] start
    2023-02-21T22:44:31.351Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][SeggerBackend][] Logger sink registered in Segger backend logger
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][JLink][] Logger sink registered in JLink logger
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][nRFXX][] open
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][nRFXX][] just_check_family
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][SeggerBackend][] open_dll
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.352Z DEBUG [nrfdl][debug](23:44:31.352) [jlink] [NRFJPROG][SeggerBackend][] No J-Link DLL path was provided. Attempting to auto detect.
    2023-02-21T22:44:31.367Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] [NRFJPROG][SeggerBackend][] Set batch mode
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.368Z VERBOSE [nrfdl][trace](23:44:31.368) [jlink] [NRFJPROG][Worker][] Command open executed for 8 milliseconds with result 0
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.368Z VERBOSE [nrfdl][trace](23:44:31.368) [jlink] [NRFJPROG][Client][] Command open executed for 52 milliseconds with result 0
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.368Z DEBUG [nrfdl][debug](23:44:31.368) [jlink] [NRFJPROG][Client][] enum_emu_snr
    2023-02-21T22:44:31.383Z DEBUG [nrfdl][debug](23:44:31.384) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.384Z VERBOSE [nrfdl][trace](23:44:31.384) [jlink] [NRFJPROG][Client][] Command enum_emu_snr executed for 15 milliseconds with result 0
    2023-02-21T22:44:31.384Z DEBUG [nrfdl][debug](23:44:31.384) [jlink] Found 0 JLink devices connected
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][nRFXX][] enum_emu_snr
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] enum_emu_snr
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] ---just_enum_emu_snr
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] ---just_get_num_emus
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.400Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z VERBOSE [nrfdl][trace](23:44:31.400) [jlink] [NRFJPROG][Worker][] Command enum_emu_snr executed for 2 milliseconds with result 0
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][nRFXX][] close
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] close
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] disconnect_from_emu
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][SeggerBackend][] Segger Backend closed.
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.401Z DEBUG [nrfdl][debug](23:44:31.400) [jlink] [NRFJPROG][nRFXX][] nRF family DLL closed
    2023-02-21T22:44:31.431Z DEBUG [nrfdl][debug](23:44:31.432) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.432Z VERBOSE [nrfdl][trace](23:44:31.432) [jlink] [NRFJPROG][Client][] Command close executed for 47 milliseconds with result 0
    2023-02-21T22:44:31.432Z DEBUG [nrfdl][debug](23:44:31.432) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.432Z DEBUG [nrfdl][debug](23:44:31.432) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:31.432Z DEBUG [nrfdl][debug](23:44:31.432) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.432Z VERBOSE [nrfdl][trace](23:44:31.432) [jlink] [NRFJPROG][Worker][] Command close executed for 30 milliseconds with result 0
    2023-02-21T22:44:31.432Z DEBUG [nrfdl][debug](23:44:31.432) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.432Z VERBOSE [nrfdl][trace](23:44:31.432) [jlink] [NRFJPROG][Client][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:31.447Z DEBUG [nrfdl][debug](23:44:31.448) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.447Z VERBOSE [nrfdl][trace](23:44:31.448) [jlink] [NRFJPROG][Worker][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:31.448Z DEBUG [nrfdl][debug](23:44:31.448) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.448Z VERBOSE [nrfdl][trace](23:44:31.448) [jlink] [NRFJPROG][Worker][] Executed 4 commands for 40 milliseconds
    2023-02-21T22:44:31.480Z DEBUG [nrfdl][debug](23:44:31.481) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.480Z DEBUG [nrfdl][debug](23:44:31.481) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.481) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.481) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.482) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.482) [jlink] [NRFJPROG][Client][] Child process terminated with result 0
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.482) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.481Z VERBOSE [nrfdl][trace](23:44:31.482) [jlink] [NRFJPROG][Client][] Executed 4 commands for 114 milliseconds
    2023-02-21T22:44:31.481Z DEBUG [nrfdl][debug](23:44:31.482) [jlink] No SEGGER instance provided
    2023-02-21T22:44:31.482Z DEBUG [nrfdl][debug](23:44:31.482) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:31.497Z DEBUG [nrfdl][debug](23:44:31.497) [jlink] JLinkHW::enumerate waiting for futures
    2023-02-21T22:44:31.497Z DEBUG [nrfdl][debug](23:44:31.497) [jlink] JLinkHW::enumerate done waiting for futures
    2023-02-21T22:44:31.497Z DEBUG [nrfdl][debug](23:44:31.497) [jlink] [<-] NRFDL::UniversalPlatform::JLinkHW::enumerate [took 354ms]
    2023-02-21T22:44:31.499Z DEBUG [nrfdl][debug](23:44:31.499) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:31.501Z VERBOSE [nrfdl][trace](23:44:31.501) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:31.501Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Found device with serial number: 5D864555333
    2023-02-21T22:44:31.501Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Found device with serial number: D1871BF59F6B
    2023-02-21T22:44:31.501Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: device registered to device list
    2023-02-21T22:44:31.502Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Found device with serial number: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.502Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Found device with serial number: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.502Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Found device with serial number: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:31.502Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: Completed waiting for enumerate task
    2023-02-21T22:44:31.502Z VERBOSE [nrfdl][trace](23:44:31.502) DeviceLister::enumerate: done
    2023-02-21T22:44:31.502Z INFO [nrfdl][info](23:44:31.502) Loaded plugin C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl, starting to resolve symbols
    2023-02-21T22:44:31.502Z DEBUG [nrfdl][debug](23:44:31.502) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:32.079Z INFO [nrfdl][info](23:44:32.079) [sdfu] Successfully opened \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}. Baud rate: 115200. Flow control: none. Parity: none
    2023-02-21T22:44:32.079Z DEBUG [nrfdl][debug](23:44:32.079) [sdfu] Encoded request into 2 bytes.
    2023-02-21T22:44:32.108Z DEBUG [nrfdl][debug](23:44:32.109) [sdfu] Encoded request into 2 bytes.
    2023-02-21T22:44:32.139Z DEBUG [nrfdl][debug](23:44:32.139) [sdfu] Encoded request into 2 bytes.
    2023-02-21T22:44:32.169Z DEBUG [nrfdl][debug](23:44:32.169) [sdfu] serial port read on port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} aborted
    2023-02-21T22:44:32.171Z VERBOSE [nrfdl][trace](23:44:32.171) [sdfu] serial io_context executed 4 handlers.
    2023-02-21T22:44:32.171Z INFO [nrfdl][info](23:44:32.171) [sdfu] serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} closed.
    2023-02-21T22:44:32.171Z DEBUG [nrfdl][debug](23:44:32.171) ~async_task id: 144382704
    2023-02-21T22:44:32.171Z INFO [nrfdl][info](23:44:32.171) close plugin dll at path: C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl
    2023-02-21T22:44:32.178Z INFO Update files regions according to Application core
    2023-02-21T22:44:32.178Z INFO Parse memory regions for file
    2023-02-21T22:44:32.178Z INFO SoftDevice detected, id 0x100 (S140 v7.2.0)
    2023-02-21T22:44:44.389Z INFO Does not need to be reloaded:  C:\Users\Kim\Desktop\hex\ble_connectivity_s140_usb_hci_pca10056.hex
    2023-02-21T22:44:44.392Z INFO SdReq for SoftDevice is set as 0x00.
    2023-02-21T22:44:44.393Z INFO Hash is generated by SHA256
    2023-02-21T22:44:44.394Z INFO Hash is generated by SHA256
    2023-02-21T22:44:44.397Z INFO Performing DFU. This may take a few seconds
    2023-02-21T22:44:44.431Z INFO [nrfdl][info](23:44:44.431) Loaded plugin C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl, starting to resolve symbols
    2023-02-21T22:44:44.433Z DEBUG [nrfdl][debug](23:44:44.434) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::doProgram
    2023-02-21T22:44:44.436Z INFO Opening serial port for SDFU programming: 0%
    2023-02-21T22:44:44.436Z DEBUG [nrfdl][debug](23:44:44.436) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:44.992Z INFO Opening serial port for SDFU programming: 100%
    2023-02-21T22:44:44.992Z INFO [nrfdl][info](23:44:44.992) [sdfu] Successfully opened \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}. Baud rate: 115200. Flow control: none. Parity: none
    2023-02-21T22:44:44.992Z DEBUG [nrfdl][debug](23:44:44.992) [sdfu] Encoded request into 3 bytes.
    2023-02-21T22:44:44.993Z DEBUG [nrfdl][debug](23:44:44.994) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.010Z DEBUG [nrfdl][debug](23:44:45.010) [sdfu] SDFU MTU is 1024
    2023-02-21T22:44:45.010Z DEBUG [nrfdl][debug](23:44:45.010) [sdfu] Encoded request into 5 bytes.
    2023-02-21T22:44:45.026Z DEBUG [nrfdl][debug](23:44:45.026) [sdfu] transmit data with size: 67 type: 1 offset: 0 maxSize: 256
    2023-02-21T22:44:45.026Z DEBUG [nrfdl][debug](23:44:45.026) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:45.042Z DEBUG [nrfdl][debug](23:44:45.042) [sdfu] Encoded request into 68 bytes.
    2023-02-21T22:44:45.042Z DEBUG [nrfdl][debug](23:44:45.043) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.042Z INFO Uploading image through SDFU: 0%
    2023-02-21T22:44:45.073Z DEBUG [nrfdl][debug](23:44:45.073) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.180Z DEBUG [nrfdl][debug](23:44:45.180) [sdfu] Encoded request into 5 bytes.
    2023-02-21T22:44:45.196Z DEBUG [nrfdl][debug](23:44:45.196) [sdfu] transmit data with size: 153140 type: 2 offset: 0 maxSize: 4096
    2023-02-21T22:44:45.196Z DEBUG [nrfdl][debug](23:44:45.196) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:45.303Z DEBUG [nrfdl][debug](23:44:45.304) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.310Z DEBUG [nrfdl][debug](23:44:45.311) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.329Z INFO Uploading image through SDFU: 1%
    2023-02-21T22:44:45.330Z DEBUG [nrfdl][debug](23:44:45.329) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.347Z INFO Uploading image through SDFU: 2%
    2023-02-21T22:44:45.348Z DEBUG [nrfdl][debug](23:44:45.348) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.367Z DEBUG [nrfdl][debug](23:44:45.367) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.395Z DEBUG [nrfdl][debug](23:44:45.395) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.410Z DEBUG [nrfdl][debug](23:44:45.411) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:45.517Z DEBUG [nrfdl][debug](23:44:45.518) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.524Z INFO Uploading image through SDFU: 3%
    2023-02-21T22:44:45.524Z DEBUG [nrfdl][debug](23:44:45.524) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.543Z INFO Uploading image through SDFU: 4%
    2023-02-21T22:44:45.543Z DEBUG [nrfdl][debug](23:44:45.543) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.563Z DEBUG [nrfdl][debug](23:44:45.562) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.580Z INFO Uploading image through SDFU: 5%
    2023-02-21T22:44:45.581Z DEBUG [nrfdl][debug](23:44:45.581) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.624Z DEBUG [nrfdl][debug](23:44:45.625) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.639Z DEBUG [nrfdl][debug](23:44:45.640) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:45.745Z DEBUG [nrfdl][debug](23:44:45.745) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.752Z INFO Uploading image through SDFU: 6%
    2023-02-21T22:44:45.752Z DEBUG [nrfdl][debug](23:44:45.752) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.771Z DEBUG [nrfdl][debug](23:44:45.772) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.790Z INFO Uploading image through SDFU: 7%
    2023-02-21T22:44:45.790Z DEBUG [nrfdl][debug](23:44:45.790) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.809Z INFO Uploading image through SDFU: 8%
    2023-02-21T22:44:45.809Z DEBUG [nrfdl][debug](23:44:45.809) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.851Z DEBUG [nrfdl][debug](23:44:45.852) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:45.867Z DEBUG [nrfdl][debug](23:44:45.867) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:45.978Z DEBUG [nrfdl][debug](23:44:45.974) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.980Z DEBUG [nrfdl][debug](23:44:45.980) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:45.999Z INFO Uploading image through SDFU: 9%
    2023-02-21T22:44:45.999Z DEBUG [nrfdl][debug](23:44:45.999) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.018Z INFO Uploading image through SDFU: 10%
    2023-02-21T22:44:46.018Z DEBUG [nrfdl][debug](23:44:46.018) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.037Z DEBUG [nrfdl][debug](23:44:46.037) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.065Z DEBUG [nrfdl][debug](23:44:46.066) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.080Z DEBUG [nrfdl][debug](23:44:46.081) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:46.187Z DEBUG [nrfdl][debug](23:44:46.187) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.194Z INFO Uploading image through SDFU: 11%
    2023-02-21T22:44:46.194Z DEBUG [nrfdl][debug](23:44:46.194) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.212Z INFO Uploading image through SDFU: 12%
    2023-02-21T22:44:46.212Z DEBUG [nrfdl][debug](23:44:46.212) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.231Z DEBUG [nrfdl][debug](23:44:46.231) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.250Z INFO Uploading image through SDFU: 13%
    2023-02-21T22:44:46.250Z DEBUG [nrfdl][debug](23:44:46.251) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.293Z DEBUG [nrfdl][debug](23:44:46.294) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.309Z DEBUG [nrfdl][debug](23:44:46.310) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:46.415Z DEBUG [nrfdl][debug](23:44:46.415) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.422Z INFO Uploading image through SDFU: 14%
    2023-02-21T22:44:46.422Z DEBUG [nrfdl][debug](23:44:46.422) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.441Z DEBUG [nrfdl][debug](23:44:46.441) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.460Z INFO Uploading image through SDFU: 15%
    2023-02-21T22:44:46.460Z DEBUG [nrfdl][debug](23:44:46.460) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.478Z INFO Uploading image through SDFU: 16%
    2023-02-21T22:44:46.478Z DEBUG [nrfdl][debug](23:44:46.479) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.521Z DEBUG [nrfdl][debug](23:44:46.522) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.538Z DEBUG [nrfdl][debug](23:44:46.538) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:46.644Z DEBUG [nrfdl][debug](23:44:46.644) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.650Z DEBUG [nrfdl][debug](23:44:46.651) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.669Z INFO Uploading image through SDFU: 17%
    2023-02-21T22:44:46.669Z DEBUG [nrfdl][debug](23:44:46.670) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.688Z INFO Uploading image through SDFU: 18%
    2023-02-21T22:44:46.688Z DEBUG [nrfdl][debug](23:44:46.688) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.707Z DEBUG [nrfdl][debug](23:44:46.707) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.737Z DEBUG [nrfdl][debug](23:44:46.737) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.752Z DEBUG [nrfdl][debug](23:44:46.752) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:46.858Z DEBUG [nrfdl][debug](23:44:46.859) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.865Z INFO Uploading image through SDFU: 19%
    2023-02-21T22:44:46.865Z DEBUG [nrfdl][debug](23:44:46.865) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.884Z INFO Uploading image through SDFU: 20%
    2023-02-21T22:44:46.884Z DEBUG [nrfdl][debug](23:44:46.884) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.903Z DEBUG [nrfdl][debug](23:44:46.903) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:46.922Z INFO Uploading image through SDFU: 21%
    2023-02-21T22:44:46.922Z DEBUG [nrfdl][debug](23:44:46.922) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.952Z DEBUG [nrfdl][debug](23:44:46.952) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:46.967Z DEBUG [nrfdl][debug](23:44:46.967) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:47.072Z DEBUG [nrfdl][debug](23:44:47.072) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.079Z INFO Uploading image through SDFU: 22%
    2023-02-21T22:44:47.079Z DEBUG [nrfdl][debug](23:44:47.079) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.097Z DEBUG [nrfdl][debug](23:44:47.097) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.116Z INFO Uploading image through SDFU: 23%
    2023-02-21T22:44:47.116Z DEBUG [nrfdl][debug](23:44:47.117) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.135Z INFO Uploading image through SDFU: 24%
    2023-02-21T22:44:47.135Z DEBUG [nrfdl][debug](23:44:47.136) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.164Z DEBUG [nrfdl][debug](23:44:47.164) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.180Z DEBUG [nrfdl][debug](23:44:47.180) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:47.286Z DEBUG [nrfdl][debug](23:44:47.286) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.293Z DEBUG [nrfdl][debug](23:44:47.293) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.311Z INFO Uploading image through SDFU: 25%
    2023-02-21T22:44:47.311Z DEBUG [nrfdl][debug](23:44:47.312) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.330Z INFO Uploading image through SDFU: 26%
    2023-02-21T22:44:47.330Z DEBUG [nrfdl][debug](23:44:47.330) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.349Z DEBUG [nrfdl][debug](23:44:47.349) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.392Z DEBUG [nrfdl][debug](23:44:47.392) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.407Z DEBUG [nrfdl][debug](23:44:47.407) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:47.513Z DEBUG [nrfdl][debug](23:44:47.513) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.519Z INFO Uploading image through SDFU: 27%
    2023-02-21T22:44:47.519Z DEBUG [nrfdl][debug](23:44:47.519) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.538Z INFO Uploading image through SDFU: 28%
    2023-02-21T22:44:47.538Z DEBUG [nrfdl][debug](23:44:47.539) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.557Z DEBUG [nrfdl][debug](23:44:47.557) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.581Z DEBUG [nrfdl][debug](23:44:47.576) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.581Z INFO Uploading image through SDFU: 29%
    2023-02-21T22:44:47.620Z DEBUG [nrfdl][debug](23:44:47.620) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.635Z DEBUG [nrfdl][debug](23:44:47.635) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:47.741Z DEBUG [nrfdl][debug](23:44:47.741) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.747Z INFO Uploading image through SDFU: 30%
    2023-02-21T22:44:47.747Z DEBUG [nrfdl][debug](23:44:47.748) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.766Z DEBUG [nrfdl][debug](23:44:47.767) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.785Z INFO Uploading image through SDFU: 31%
    2023-02-21T22:44:47.786Z DEBUG [nrfdl][debug](23:44:47.786) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.804Z INFO Uploading image through SDFU: 32%
    2023-02-21T22:44:47.805Z DEBUG [nrfdl][debug](23:44:47.805) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.848Z DEBUG [nrfdl][debug](23:44:47.848) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:47.863Z DEBUG [nrfdl][debug](23:44:47.863) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:47.968Z DEBUG [nrfdl][debug](23:44:47.969) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.980Z DEBUG [nrfdl][debug](23:44:47.975) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:47.994Z INFO Uploading image through SDFU: 33%
    2023-02-21T22:44:47.994Z DEBUG [nrfdl][debug](23:44:47.994) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.013Z INFO Uploading image through SDFU: 34%
    2023-02-21T22:44:48.013Z DEBUG [nrfdl][debug](23:44:48.013) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.032Z DEBUG [nrfdl][debug](23:44:48.032) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.074Z DEBUG [nrfdl][debug](23:44:48.075) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.090Z DEBUG [nrfdl][debug](23:44:48.091) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:48.196Z DEBUG [nrfdl][debug](23:44:48.196) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.202Z INFO Uploading image through SDFU: 35%
    2023-02-21T22:44:48.202Z DEBUG [nrfdl][debug](23:44:48.203) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.222Z INFO Uploading image through SDFU: 36%
    2023-02-21T22:44:48.222Z DEBUG [nrfdl][debug](23:44:48.222) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.241Z DEBUG [nrfdl][debug](23:44:48.241) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.259Z INFO Uploading image through SDFU: 37%
    2023-02-21T22:44:48.259Z DEBUG [nrfdl][debug](23:44:48.260) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.302Z DEBUG [nrfdl][debug](23:44:48.302) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.318Z DEBUG [nrfdl][debug](23:44:48.319) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:48.424Z DEBUG [nrfdl][debug](23:44:48.425) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.431Z INFO Uploading image through SDFU: 38%
    2023-02-21T22:44:48.431Z DEBUG [nrfdl][debug](23:44:48.431) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.450Z DEBUG [nrfdl][debug](23:44:48.450) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.469Z INFO Uploading image through SDFU: 39%
    2023-02-21T22:44:48.469Z DEBUG [nrfdl][debug](23:44:48.469) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.488Z INFO Uploading image through SDFU: 40%
    2023-02-21T22:44:48.488Z DEBUG [nrfdl][debug](23:44:48.488) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.531Z DEBUG [nrfdl][debug](23:44:48.531) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.547Z DEBUG [nrfdl][debug](23:44:48.547) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:48.654Z DEBUG [nrfdl][debug](23:44:48.654) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.661Z DEBUG [nrfdl][debug](23:44:48.661) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.679Z INFO Uploading image through SDFU: 41%
    2023-02-21T22:44:48.679Z DEBUG [nrfdl][debug](23:44:48.679) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.698Z INFO Uploading image through SDFU: 42%
    2023-02-21T22:44:48.698Z DEBUG [nrfdl][debug](23:44:48.698) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.717Z DEBUG [nrfdl][debug](23:44:48.717) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.761Z DEBUG [nrfdl][debug](23:44:48.761) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.783Z DEBUG [nrfdl][debug](23:44:48.776) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:48.883Z DEBUG [nrfdl][debug](23:44:48.883) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.890Z INFO Uploading image through SDFU: 43%
    2023-02-21T22:44:48.890Z DEBUG [nrfdl][debug](23:44:48.890) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.909Z INFO Uploading image through SDFU: 44%
    2023-02-21T22:44:48.909Z DEBUG [nrfdl][debug](23:44:48.909) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.928Z DEBUG [nrfdl][debug](23:44:48.929) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:48.946Z INFO Uploading image through SDFU: 45%
    2023-02-21T22:44:48.947Z DEBUG [nrfdl][debug](23:44:48.947) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:48.990Z DEBUG [nrfdl][debug](23:44:48.990) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.005Z DEBUG [nrfdl][debug](23:44:49.006) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:49.112Z DEBUG [nrfdl][debug](23:44:49.113) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.119Z INFO Uploading image through SDFU: 46%
    2023-02-21T22:44:49.119Z DEBUG [nrfdl][debug](23:44:49.119) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.138Z DEBUG [nrfdl][debug](23:44:49.138) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.157Z INFO Uploading image through SDFU: 47%
    2023-02-21T22:44:49.157Z DEBUG [nrfdl][debug](23:44:49.157) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.181Z DEBUG [nrfdl][debug](23:44:49.176) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.181Z INFO Uploading image through SDFU: 48%
    2023-02-21T22:44:49.218Z DEBUG [nrfdl][debug](23:44:49.219) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.234Z DEBUG [nrfdl][debug](23:44:49.234) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:49.340Z DEBUG [nrfdl][debug](23:44:49.340) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.346Z DEBUG [nrfdl][debug](23:44:49.347) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.365Z INFO Uploading image through SDFU: 49%
    2023-02-21T22:44:49.366Z DEBUG [nrfdl][debug](23:44:49.366) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.385Z INFO Uploading image through SDFU: 50%
    2023-02-21T22:44:49.385Z DEBUG [nrfdl][debug](23:44:49.385) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.404Z DEBUG [nrfdl][debug](23:44:49.404) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.446Z DEBUG [nrfdl][debug](23:44:49.446) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.462Z DEBUG [nrfdl][debug](23:44:49.462) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:49.568Z DEBUG [nrfdl][debug](23:44:49.568) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.585Z DEBUG [nrfdl][debug](23:44:49.575) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.585Z INFO Uploading image through SDFU: 51%
    2023-02-21T22:44:49.594Z INFO Uploading image through SDFU: 52%
    2023-02-21T22:44:49.594Z DEBUG [nrfdl][debug](23:44:49.594) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.613Z DEBUG [nrfdl][debug](23:44:49.613) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.631Z INFO Uploading image through SDFU: 53%
    2023-02-21T22:44:49.631Z DEBUG [nrfdl][debug](23:44:49.632) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.674Z DEBUG [nrfdl][debug](23:44:49.674) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.690Z DEBUG [nrfdl][debug](23:44:49.690) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:49.796Z DEBUG [nrfdl][debug](23:44:49.797) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.803Z INFO Uploading image through SDFU: 54%
    2023-02-21T22:44:49.803Z DEBUG [nrfdl][debug](23:44:49.803) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.822Z DEBUG [nrfdl][debug](23:44:49.822) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.841Z INFO Uploading image through SDFU: 55%
    2023-02-21T22:44:49.841Z DEBUG [nrfdl][debug](23:44:49.842) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:49.860Z INFO Uploading image through SDFU: 56%
    2023-02-21T22:44:49.860Z DEBUG [nrfdl][debug](23:44:49.860) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.903Z DEBUG [nrfdl][debug](23:44:49.903) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:49.919Z DEBUG [nrfdl][debug](23:44:49.919) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:50.026Z DEBUG [nrfdl][debug](23:44:50.026) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.033Z DEBUG [nrfdl][debug](23:44:50.033) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.051Z INFO Uploading image through SDFU: 57%
    2023-02-21T22:44:50.052Z DEBUG [nrfdl][debug](23:44:50.052) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.071Z INFO Uploading image through SDFU: 58%
    2023-02-21T22:44:50.071Z DEBUG [nrfdl][debug](23:44:50.071) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.090Z DEBUG [nrfdl][debug](23:44:50.090) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.133Z DEBUG [nrfdl][debug](23:44:50.134) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.149Z DEBUG [nrfdl][debug](23:44:50.150) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:50.256Z DEBUG [nrfdl][debug](23:44:50.256) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.263Z DEBUG [nrfdl][debug](23:44:50.262) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.263Z INFO Uploading image through SDFU: 59%
    2023-02-21T22:44:50.281Z INFO Uploading image through SDFU: 60%
    2023-02-21T22:44:50.282Z DEBUG [nrfdl][debug](23:44:50.281) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.300Z DEBUG [nrfdl][debug](23:44:50.301) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.319Z INFO Uploading image through SDFU: 61%
    2023-02-21T22:44:50.319Z DEBUG [nrfdl][debug](23:44:50.320) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.363Z DEBUG [nrfdl][debug](23:44:50.362) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.380Z DEBUG [nrfdl][debug](23:44:50.378) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:50.484Z DEBUG [nrfdl][debug](23:44:50.484) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.490Z INFO Uploading image through SDFU: 62%
    2023-02-21T22:44:50.490Z DEBUG [nrfdl][debug](23:44:50.491) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.510Z DEBUG [nrfdl][debug](23:44:50.510) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.529Z INFO Uploading image through SDFU: 63%
    2023-02-21T22:44:50.530Z DEBUG [nrfdl][debug](23:44:50.529) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.547Z INFO Uploading image through SDFU: 64%
    2023-02-21T22:44:50.547Z DEBUG [nrfdl][debug](23:44:50.547) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.591Z DEBUG [nrfdl][debug](23:44:50.591) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.607Z DEBUG [nrfdl][debug](23:44:50.607) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:50.714Z DEBUG [nrfdl][debug](23:44:50.714) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.721Z DEBUG [nrfdl][debug](23:44:50.721) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.739Z INFO Uploading image through SDFU: 65%
    2023-02-21T22:44:50.739Z DEBUG [nrfdl][debug](23:44:50.740) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.759Z INFO Uploading image through SDFU: 66%
    2023-02-21T22:44:50.759Z DEBUG [nrfdl][debug](23:44:50.759) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.781Z DEBUG [nrfdl][debug](23:44:50.777) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.820Z DEBUG [nrfdl][debug](23:44:50.820) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:50.835Z DEBUG [nrfdl][debug](23:44:50.835) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:50.929Z DEBUG [nrfdl][debug](23:44:50.929) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.935Z INFO Uploading image through SDFU: 67%
    2023-02-21T22:44:50.935Z DEBUG [nrfdl][debug](23:44:50.935) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.954Z INFO Uploading image through SDFU: 68%
    2023-02-21T22:44:50.954Z DEBUG [nrfdl][debug](23:44:50.954) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.973Z DEBUG [nrfdl][debug](23:44:50.973) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:50.992Z INFO Uploading image through SDFU: 69%
    2023-02-21T22:44:50.992Z DEBUG [nrfdl][debug](23:44:50.992) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.034Z DEBUG [nrfdl][debug](23:44:51.034) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.049Z DEBUG [nrfdl][debug](23:44:51.050) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:51.157Z DEBUG [nrfdl][debug](23:44:51.157) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.163Z INFO Uploading image through SDFU: 70%
    2023-02-21T22:44:51.163Z DEBUG [nrfdl][debug](23:44:51.164) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.182Z DEBUG [nrfdl][debug](23:44:51.183) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.201Z INFO Uploading image through SDFU: 71%
    2023-02-21T22:44:51.201Z DEBUG [nrfdl][debug](23:44:51.202) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.220Z INFO Uploading image through SDFU: 72%
    2023-02-21T22:44:51.220Z DEBUG [nrfdl][debug](23:44:51.221) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.263Z DEBUG [nrfdl][debug](23:44:51.263) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.279Z DEBUG [nrfdl][debug](23:44:51.279) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:51.385Z DEBUG [nrfdl][debug](23:44:51.385) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.391Z DEBUG [nrfdl][debug](23:44:51.392) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.410Z INFO Uploading image through SDFU: 73%
    2023-02-21T22:44:51.410Z DEBUG [nrfdl][debug](23:44:51.411) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.429Z INFO Uploading image through SDFU: 74%
    2023-02-21T22:44:51.430Z DEBUG [nrfdl][debug](23:44:51.430) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.448Z DEBUG [nrfdl][debug](23:44:51.449) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.491Z DEBUG [nrfdl][debug](23:44:51.492) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.522Z DEBUG [nrfdl][debug](23:44:51.523) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:51.614Z DEBUG [nrfdl][debug](23:44:51.615) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.622Z INFO Uploading image through SDFU: 75%
    2023-02-21T22:44:51.622Z DEBUG [nrfdl][debug](23:44:51.622) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.641Z INFO Uploading image through SDFU: 76%
    2023-02-21T22:44:51.641Z DEBUG [nrfdl][debug](23:44:51.641) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.659Z DEBUG [nrfdl][debug](23:44:51.659) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.678Z INFO Uploading image through SDFU: 77%
    2023-02-21T22:44:51.678Z DEBUG [nrfdl][debug](23:44:51.678) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.719Z DEBUG [nrfdl][debug](23:44:51.720) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.750Z DEBUG [nrfdl][debug](23:44:51.751) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:51.857Z DEBUG [nrfdl][debug](23:44:51.858) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.864Z INFO Uploading image through SDFU: 78%
    2023-02-21T22:44:51.864Z DEBUG [nrfdl][debug](23:44:51.864) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.883Z DEBUG [nrfdl][debug](23:44:51.883) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.902Z INFO Uploading image through SDFU: 79%
    2023-02-21T22:44:51.902Z DEBUG [nrfdl][debug](23:44:51.902) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:51.921Z INFO Uploading image through SDFU: 80%
    2023-02-21T22:44:51.921Z DEBUG [nrfdl][debug](23:44:51.921) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.964Z DEBUG [nrfdl][debug](23:44:51.965) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:51.981Z DEBUG [nrfdl][debug](23:44:51.981) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:52.088Z DEBUG [nrfdl][debug](23:44:52.088) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.094Z DEBUG [nrfdl][debug](23:44:52.095) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.113Z INFO Uploading image through SDFU: 81%
    2023-02-21T22:44:52.113Z DEBUG [nrfdl][debug](23:44:52.114) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.132Z INFO Uploading image through SDFU: 82%
    2023-02-21T22:44:52.132Z DEBUG [nrfdl][debug](23:44:52.133) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.151Z DEBUG [nrfdl][debug](23:44:52.152) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.194Z DEBUG [nrfdl][debug](23:44:52.195) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.226Z DEBUG [nrfdl][debug](23:44:52.226) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:52.332Z DEBUG [nrfdl][debug](23:44:52.332) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.338Z INFO Uploading image through SDFU: 83%
    2023-02-21T22:44:52.339Z DEBUG [nrfdl][debug](23:44:52.339) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.357Z INFO Uploading image through SDFU: 84%
    2023-02-21T22:44:52.357Z DEBUG [nrfdl][debug](23:44:52.358) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.381Z DEBUG [nrfdl][debug](23:44:52.377) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.395Z INFO Uploading image through SDFU: 85%
    2023-02-21T22:44:52.395Z DEBUG [nrfdl][debug](23:44:52.396) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.439Z DEBUG [nrfdl][debug](23:44:52.439) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.455Z DEBUG [nrfdl][debug](23:44:52.455) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:52.548Z DEBUG [nrfdl][debug](23:44:52.548) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.555Z INFO Uploading image through SDFU: 86%
    2023-02-21T22:44:52.555Z DEBUG [nrfdl][debug](23:44:52.555) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.573Z DEBUG [nrfdl][debug](23:44:52.574) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.593Z INFO Uploading image through SDFU: 87%
    2023-02-21T22:44:52.593Z DEBUG [nrfdl][debug](23:44:52.593) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.611Z INFO Uploading image through SDFU: 88%
    2023-02-21T22:44:52.612Z DEBUG [nrfdl][debug](23:44:52.612) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.653Z DEBUG [nrfdl][debug](23:44:52.653) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.668Z DEBUG [nrfdl][debug](23:44:52.669) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:52.780Z DEBUG [nrfdl][debug](23:44:52.776) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.782Z DEBUG [nrfdl][debug](23:44:52.782) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.801Z INFO Uploading image through SDFU: 89%
    2023-02-21T22:44:52.801Z DEBUG [nrfdl][debug](23:44:52.801) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.820Z INFO Uploading image through SDFU: 90%
    2023-02-21T22:44:52.820Z DEBUG [nrfdl][debug](23:44:52.820) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:52.839Z DEBUG [nrfdl][debug](23:44:52.839) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.881Z DEBUG [nrfdl][debug](23:44:52.882) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:52.896Z DEBUG [nrfdl][debug](23:44:52.897) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:53.004Z DEBUG [nrfdl][debug](23:44:53.004) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.011Z INFO Uploading image through SDFU: 91%
    2023-02-21T22:44:53.011Z DEBUG [nrfdl][debug](23:44:53.011) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.029Z DEBUG [nrfdl][debug](23:44:53.029) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.029Z INFO Uploading image through SDFU: 92%
    2023-02-21T22:44:53.048Z DEBUG [nrfdl][debug](23:44:53.048) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.067Z INFO Uploading image through SDFU: 93%
    2023-02-21T22:44:53.067Z DEBUG [nrfdl][debug](23:44:53.067) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.109Z DEBUG [nrfdl][debug](23:44:53.109) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.125Z DEBUG [nrfdl][debug](23:44:53.126) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:53.231Z DEBUG [nrfdl][debug](23:44:53.232) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.237Z INFO Uploading image through SDFU: 94%
    2023-02-21T22:44:53.238Z DEBUG [nrfdl][debug](23:44:53.238) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.256Z DEBUG [nrfdl][debug](23:44:53.257) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.276Z INFO Uploading image through SDFU: 95%
    2023-02-21T22:44:53.276Z DEBUG [nrfdl][debug](23:44:53.276) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.295Z INFO Uploading image through SDFU: 96%
    2023-02-21T22:44:53.295Z DEBUG [nrfdl][debug](23:44:53.295) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.337Z DEBUG [nrfdl][debug](23:44:53.338) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.353Z DEBUG [nrfdl][debug](23:44:53.353) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:53.460Z DEBUG [nrfdl][debug](23:44:53.460) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.466Z DEBUG [nrfdl][debug](23:44:53.467) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.485Z INFO Uploading image through SDFU: 97%
    2023-02-21T22:44:53.486Z DEBUG [nrfdl][debug](23:44:53.486) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.504Z INFO Uploading image through SDFU: 98%
    2023-02-21T22:44:53.505Z DEBUG [nrfdl][debug](23:44:53.505) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.523Z DEBUG [nrfdl][debug](23:44:53.524) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.566Z DEBUG [nrfdl][debug](23:44:53.566) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.582Z DEBUG [nrfdl][debug](23:44:53.582) [sdfu] Encoded request into 6 bytes.
    2023-02-21T22:44:53.687Z DEBUG [nrfdl][debug](23:44:53.688) [sdfu] Encoded request into 1025 bytes.
    2023-02-21T22:44:53.694Z INFO Uploading image through SDFU: 99%
    2023-02-21T22:44:53.695Z DEBUG [nrfdl][debug](23:44:53.695) [sdfu] Encoded request into 565 bytes.
    2023-02-21T22:44:53.710Z INFO Uploading image through SDFU: 100%
    2023-02-21T22:44:53.710Z DEBUG [nrfdl][debug](23:44:53.710) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.734Z DEBUG [nrfdl][debug](23:44:53.734) [sdfu] Encoded request into 1 bytes.
    2023-02-21T22:44:53.765Z DEBUG [nrfdl][debug](23:44:53.765) [sdfu] serial port read on port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} aborted
    2023-02-21T22:44:53.833Z VERBOSE [nrfdl][trace](23:44:53.833) [sdfu] serial io_context executed 122 handlers.
    2023-02-21T22:44:53.833Z INFO [nrfdl][info](23:44:53.833) [sdfu] serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} closed.
    2023-02-21T22:44:53.833Z INFO Waiting for serial port to be attached: 0%
    2023-02-21T22:44:53.875Z DEBUG Sending usage data {"category":"pc-nrfconnect-programmer","action":"Close device","label":""}
    2023-02-21T22:44:53.880Z INFO Target device closed
    2023-02-21T22:44:53.915Z DEBUG [nrfdl][debug](23:44:53.915) unknown device removed id: {F56398B9-C255-5ABC-B724-995BC41C42EE} sn: D1871BF59F6B id_list: 
    2023-02-21T22:44:54.417Z DEBUG [nrfdl][debug](23:44:54.417) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:54.417Z DEBUG [nrfdl][debug](23:44:54.417) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 13, origin: {F56398B9-C255-5ABC-B724-995BC41C42EE}
    2023-02-21T22:44:54.417Z DEBUG [nrfdl][debug](23:44:54.417) Starting DeviceIoControl
    2023-02-21T22:44:54.424Z DEBUG [nrfdl][debug](23:44:54.425) Getting config descriptor
    2023-02-21T22:44:54.424Z DEBUG [nrfdl][debug](23:44:54.425) Iterating descriptors.
    2023-02-21T22:44:54.424Z DEBUG [nrfdl][debug](23:44:54.425) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 15, origin: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Starting DeviceIoControl
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Getting config descriptor
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Iterating descriptors.
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 0ms]
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 3, origin: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.425Z DEBUG [nrfdl][debug](23:44:54.425) Starting DeviceIoControl
    2023-02-21T22:44:54.427Z DEBUG [nrfdl][debug](23:44:54.428) Getting config descriptor
    2023-02-21T22:44:54.427Z DEBUG [nrfdl][debug](23:44:54.428) Iterating descriptors.
    2023-02-21T22:44:54.427Z DEBUG [nrfdl][debug](23:44:54.428) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:54.427Z DEBUG [nrfdl][debug](23:44:54.428) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:54.428Z DEBUG [nrfdl][debug](23:44:54.428) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 4, origin: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.428Z DEBUG [nrfdl][debug](23:44:54.428) Starting DeviceIoControl
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) Getting config descriptor
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) Iterating descriptors.
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 20, origin: {E6EA6479-A448-5812-BA69-8AAB6114BB69}
    2023-02-21T22:44:54.430Z DEBUG [nrfdl][debug](23:44:54.430) Starting DeviceIoControl
    2023-02-21T22:44:54.443Z DEBUG [nrfdl][debug](23:44:54.444) Getting config descriptor
    2023-02-21T22:44:54.443Z DEBUG [nrfdl][debug](23:44:54.444) Iterating descriptors.
    2023-02-21T22:44:54.443Z DEBUG [nrfdl][debug](23:44:54.444) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 13ms]
    2023-02-21T22:44:54.443Z DEBUG [nrfdl][debug](23:44:54.444) USBLister: Adding device with serial number: 5D864555333
    2023-02-21T22:44:54.444Z DEBUG [nrfdl][debug](23:44:54.444) USBLister: Adding device with serial number: D1871BF59F6B
    2023-02-21T22:44:54.444Z DEBUG [nrfdl][debug](23:44:54.444) USBLister: Adding device with serial number: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.444Z DEBUG [nrfdl][debug](23:44:54.444) USBLister: Adding device with serial number: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.444Z DEBUG [nrfdl][debug](23:44:54.444) USBLister: Adding device with serial number: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:54.444Z VERBOSE [nrfdl][trace](23:44:54.445) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:54.445Z DEBUG [nrfdl][debug](23:44:54.445) [jlink] [->] NRFDL::UniversalPlatform::JLinkHW::enumerate
    2023-02-21T22:44:54.445Z DEBUG [nrfdl][debug](23:44:54.445) [jlink] JLinkHW::enumerate all devices
    2023-02-21T22:44:54.629Z DEBUG [nrfdl][debug](23:44:54.629) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.629Z DEBUG [nrfdl][debug](23:44:54.629) [jlink] [NRFJPROG][Client][] open
    2023-02-21T22:44:54.629Z DEBUG [nrfdl][debug](23:44:54.629) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.629Z DEBUG [nrfdl][debug](23:44:54.629) [jlink] [NRFJPROG][Client][] start
    2023-02-21T22:44:54.670Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][SeggerBackend][] Logger sink registered in Segger backend logger
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][JLink][] Logger sink registered in JLink logger
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][nRFXX][] open
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][nRFXX][] just_check_family
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][SeggerBackend][] open_dll
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.671Z DEBUG [nrfdl][debug](23:44:54.671) [jlink] [NRFJPROG][SeggerBackend][] No J-Link DLL path was provided. Attempting to auto detect.
    2023-02-21T22:44:54.679Z DEBUG [nrfdl][debug](23:44:54.680) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.680Z VERBOSE [nrfdl][trace](23:44:54.680) [jlink] [NRFJPROG][Client][] Command open executed for 46 milliseconds with result 0
    2023-02-21T22:44:54.680Z DEBUG [nrfdl][debug](23:44:54.680) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.680Z DEBUG [nrfdl][debug](23:44:54.680) [jlink] [NRFJPROG][Client][] enum_emu_snr
    2023-02-21T22:44:54.684Z DEBUG [nrfdl][debug](23:44:54.685) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.685Z VERBOSE [nrfdl][trace](23:44:54.685) [jlink] [NRFJPROG][Client][] Command enum_emu_snr executed for 4 milliseconds with result 0
    2023-02-21T22:44:54.685Z DEBUG [nrfdl][debug](23:44:54.685) [jlink] Found 0 JLink devices connected
    2023-02-21T22:44:54.710Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] Set batch mode
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] dll_version
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z VERBOSE [nrfdl][trace](23:44:54.711) [jlink] [NRFJPROG][Worker][] Command open executed for 8 milliseconds with result 0
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][nRFXX][] enum_emu_snr
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] enum_emu_snr
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] ---just_enum_emu_snr
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] ---just_get_num_emus
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z VERBOSE [nrfdl][trace](23:44:54.711) [jlink] [NRFJPROG][Worker][] Command enum_emu_snr executed for 2 milliseconds with result 0
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][nRFXX][] close
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.711Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] close
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] disconnect_from_emu
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] is_connected_to_emu
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][SeggerBackend][] Segger Backend closed.
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.712Z DEBUG [nrfdl][debug](23:44:54.711) [jlink] [NRFJPROG][nRFXX][] nRF family DLL closed
    2023-02-21T22:44:54.727Z DEBUG [nrfdl][debug](23:44:54.728) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.728Z VERBOSE [nrfdl][trace](23:44:54.728) [jlink] [NRFJPROG][Client][] Command close executed for 42 milliseconds with result 0
    2023-02-21T22:44:54.728Z DEBUG [nrfdl][debug](23:44:54.728) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.728Z DEBUG [nrfdl][debug](23:44:54.728) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:54.743Z DEBUG [nrfdl][debug](23:44:54.744) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.744Z VERBOSE [nrfdl][trace](23:44:54.744) [jlink] [NRFJPROG][Worker][] Command close executed for 40 milliseconds with result 0
    2023-02-21T22:44:54.744Z DEBUG [nrfdl][debug](23:44:54.744) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.744Z VERBOSE [nrfdl][trace](23:44:54.744) [jlink] [NRFJPROG][Client][] Command terminate executed for 15 milliseconds with result 0
    2023-02-21T22:44:54.759Z DEBUG [nrfdl][debug](23:44:54.759) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.759Z VERBOSE [nrfdl][trace](23:44:54.759) [jlink] [NRFJPROG][Worker][] Command terminate executed for 0 milliseconds with result 0
    2023-02-21T22:44:54.759Z DEBUG [nrfdl][debug](23:44:54.759) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.759Z VERBOSE [nrfdl][trace](23:44:54.759) [jlink] [NRFJPROG][Worker][] Executed 4 commands for 50 milliseconds
    2023-02-21T22:44:54.791Z DEBUG [nrfdl][debug](23:44:54.791) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.791Z DEBUG [nrfdl][debug](23:44:54.791) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:54.791Z DEBUG [nrfdl][debug](23:44:54.791) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.791Z DEBUG [nrfdl][debug](23:44:54.791) [jlink] [NRFJPROG][Client][] Worker process exited with code: 0
    2023-02-21T22:44:54.792Z DEBUG [nrfdl][debug](23:44:54.792) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.792Z DEBUG [nrfdl][debug](23:44:54.792) [jlink] [NRFJPROG][Client][] Child process terminated with result 0
    2023-02-21T22:44:54.792Z DEBUG [nrfdl][debug](23:44:54.792) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.792Z VERBOSE [nrfdl][trace](23:44:54.792) [jlink] [NRFJPROG][Client][] Executed 4 commands for 107 milliseconds
    2023-02-21T22:44:54.792Z DEBUG [nrfdl][debug](23:44:54.792) [jlink] No SEGGER instance provided
    2023-02-21T22:44:54.792Z DEBUG [nrfdl][debug](23:44:54.792) [jlink] [NRFJPROG][Client][] terminate
    2023-02-21T22:44:54.804Z DEBUG [nrfdl][debug](23:44:54.805) [jlink] JLinkHW::enumerate waiting for futures
    2023-02-21T22:44:54.805Z DEBUG [nrfdl][debug](23:44:54.805) [jlink] JLinkHW::enumerate done waiting for futures
    2023-02-21T22:44:54.805Z DEBUG [nrfdl][debug](23:44:54.805) [jlink] [<-] NRFDL::UniversalPlatform::JLinkHW::enumerate [took 359ms]
    2023-02-21T22:44:54.806Z DEBUG [nrfdl][debug](23:44:54.807) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:54.807Z DEBUG [nrfdl][debug](23:44:54.807) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::readHwInfo
    2023-02-21T22:44:54.807Z DEBUG [nrfdl][debug](23:44:54.807) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:55.008Z ERROR [nrfdl][error](23:44:55.009) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:55.309Z ERROR [nrfdl][error](23:44:55.309) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:55.609Z ERROR [nrfdl][error](23:44:55.609) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:55.910Z ERROR [nrfdl][error](23:44:55.910) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
    2023-02-21T22:44:55.910Z DEBUG [nrfdl][debug](23:44:55.910) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::readHwInfo [took 1102ms]
    2023-02-21T22:44:55.910Z ERROR [nrfdl][error](23:44:55.910) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
    2023-02-21T22:44:55.910Z ERROR [nrfdl][error](23:44:55.910) [sdfu] Failed to query sdfu hw info
    2023-02-21T22:44:55.912Z VERBOSE [nrfdl][trace](23:44:55.912) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:55.913Z DEBUG [nrfdl][debug](23:44:55.913) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:55.913Z DEBUG [nrfdl][debug](23:44:55.913) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 13, origin: {F56398B9-C255-5ABC-B724-995BC41C42EE}
    2023-02-21T22:44:55.913Z DEBUG [nrfdl][debug](23:44:55.913) Starting DeviceIoControl
    2023-02-21T22:44:55.920Z DEBUG [nrfdl][debug](23:44:55.921) Getting config descriptor
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Iterating descriptors.
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 15, origin: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Starting DeviceIoControl
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Getting config descriptor
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Iterating descriptors.
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 0ms]
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:55.921Z DEBUG [nrfdl][debug](23:44:55.921) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 3, origin: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:55.922Z DEBUG [nrfdl][debug](23:44:55.921) Starting DeviceIoControl
    2023-02-21T22:44:55.923Z DEBUG [nrfdl][debug](23:44:55.923) Getting config descriptor
    2023-02-21T22:44:55.923Z DEBUG [nrfdl][debug](23:44:55.924) Iterating descriptors.
    2023-02-21T22:44:55.923Z DEBUG [nrfdl][debug](23:44:55.924) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:55.923Z DEBUG [nrfdl][debug](23:44:55.924) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:55.924Z DEBUG [nrfdl][debug](23:44:55.924) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 4, origin: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:55.924Z DEBUG [nrfdl][debug](23:44:55.924) Starting DeviceIoControl
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) Getting config descriptor
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) Iterating descriptors.
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 20, origin: {E6EA6479-A448-5812-BA69-8AAB6114BB69}
    2023-02-21T22:44:55.926Z DEBUG [nrfdl][debug](23:44:55.926) Starting DeviceIoControl
    2023-02-21T22:44:55.934Z DEBUG [nrfdl][debug](23:44:55.934) Getting config descriptor
    2023-02-21T22:44:55.934Z DEBUG [nrfdl][debug](23:44:55.934) Iterating descriptors.
    2023-02-21T22:44:55.934Z DEBUG [nrfdl][debug](23:44:55.934) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:55.934Z DEBUG [nrfdl][debug](23:44:55.934) USBLister: Adding device with serial number: D1871BF59F6B
    2023-02-21T22:44:55.934Z VERBOSE [nrfdl][trace](23:44:55.935) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:55.938Z DEBUG [nrfdl][debug](23:44:55.939) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:55.939Z DEBUG [nrfdl][debug](23:44:55.939) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::readHwInfo
    2023-02-21T22:44:55.939Z DEBUG [nrfdl][debug](23:44:55.939) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:56.139Z ERROR [nrfdl][error](23:44:56.140) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:56.440Z ERROR [nrfdl][error](23:44:56.440) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:56.740Z ERROR [nrfdl][error](23:44:56.741) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:57.041Z ERROR [nrfdl][error](23:44:57.041) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
    2023-02-21T22:44:57.041Z DEBUG [nrfdl][debug](23:44:57.041) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::readHwInfo [took 1101ms]
    2023-02-21T22:44:57.041Z ERROR [nrfdl][error](23:44:57.041) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
    2023-02-21T22:44:57.041Z ERROR [nrfdl][error](23:44:57.041) [sdfu] Failed to query sdfu hw info
    2023-02-21T22:44:57.043Z VERBOSE [nrfdl][trace](23:44:57.043) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:57.049Z DEBUG [nrfdl][debug](23:44:57.044) [sdfu] notify sdfu device event: 0 for sn: D1871BF59F6B
    2023-02-21T22:44:57.049Z DEBUG [nrfdl][debug](23:44:57.045) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:57.049Z DEBUG [nrfdl][debug](23:44:57.045) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 13, origin: {F56398B9-C255-5ABC-B724-995BC41C42EE}
    2023-02-21T22:44:57.049Z DEBUG [nrfdl][debug](23:44:57.045) Starting DeviceIoControl
    2023-02-21T22:44:57.052Z DEBUG [nrfdl][debug](23:44:57.053) Getting config descriptor
    2023-02-21T22:44:57.052Z DEBUG [nrfdl][debug](23:44:57.053) Iterating descriptors.
    2023-02-21T22:44:57.052Z DEBUG [nrfdl][debug](23:44:57.053) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 8ms]
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 15, origin: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Starting DeviceIoControl
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Getting config descriptor
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Iterating descriptors.
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 0ms]
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 3, origin: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:57.053Z DEBUG [nrfdl][debug](23:44:57.053) Starting DeviceIoControl
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) Getting config descriptor
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) Iterating descriptors.
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 4, origin: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:57.055Z DEBUG [nrfdl][debug](23:44:57.056) Starting DeviceIoControl
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) Getting config descriptor
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) Iterating descriptors.
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 20, origin: {E6EA6479-A448-5812-BA69-8AAB6114BB69}
    2023-02-21T22:44:57.058Z DEBUG [nrfdl][debug](23:44:57.058) Starting DeviceIoControl
    2023-02-21T22:44:57.066Z DEBUG [nrfdl][debug](23:44:57.066) Getting config descriptor
    2023-02-21T22:44:57.066Z DEBUG [nrfdl][debug](23:44:57.066) Iterating descriptors.
    2023-02-21T22:44:57.066Z DEBUG [nrfdl][debug](23:44:57.066) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:57.066Z VERBOSE [nrfdl][trace](23:44:57.067) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:57.068Z DEBUG [nrfdl][debug](23:44:57.068) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:57.069Z DEBUG [nrfdl][debug](23:44:57.069) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::readHwInfo
    2023-02-21T22:44:57.069Z DEBUG [nrfdl][debug](23:44:57.069) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:57.270Z ERROR [nrfdl][error](23:44:57.270) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:57.570Z ERROR [nrfdl][error](23:44:57.570) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:57.870Z ERROR [nrfdl][error](23:44:57.871) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:58.170Z ERROR [nrfdl][error](23:44:58.171) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
    2023-02-21T22:44:58.171Z DEBUG [nrfdl][debug](23:44:58.171) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::readHwInfo [took 1101ms]
    2023-02-21T22:44:58.171Z ERROR [nrfdl][error](23:44:58.171) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
    2023-02-21T22:44:58.171Z ERROR [nrfdl][error](23:44:58.171) [sdfu] Failed to query sdfu hw info
    2023-02-21T22:44:58.173Z VERBOSE [nrfdl][trace](23:44:58.173) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:58.174Z DEBUG [nrfdl][debug](23:44:58.174) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:58.174Z DEBUG [nrfdl][debug](23:44:58.174) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 13, origin: {F56398B9-C255-5ABC-B724-995BC41C42EE}
    2023-02-21T22:44:58.174Z DEBUG [nrfdl][debug](23:44:58.174) Starting DeviceIoControl
    2023-02-21T22:44:58.181Z DEBUG [nrfdl][debug](23:44:58.182) Getting config descriptor
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Iterating descriptors.
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 15, origin: {A16CC4C5-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Starting DeviceIoControl
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Getting config descriptor
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Iterating descriptors.
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 0ms]
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 3, origin: {A16CC4BF-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:58.182Z DEBUG [nrfdl][debug](23:44:58.182) Starting DeviceIoControl
    2023-02-21T22:44:58.184Z DEBUG [nrfdl][debug](23:44:58.185) Getting config descriptor
    2023-02-21T22:44:58.184Z DEBUG [nrfdl][debug](23:44:58.185) Iterating descriptors.
    2023-02-21T22:44:58.184Z DEBUG [nrfdl][debug](23:44:58.185) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:58.184Z DEBUG [nrfdl][debug](23:44:58.185) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:58.185Z DEBUG [nrfdl][debug](23:44:58.185) Hub device path: \\?\USB#ROOT_HUB30#5&1309358B&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 4, origin: {A16CC4C7-359D-11EC-8322-806E6F6E6963}
    2023-02-21T22:44:58.185Z DEBUG [nrfdl][debug](23:44:58.185) Starting DeviceIoControl
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) Getting config descriptor
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) Iterating descriptors.
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 2ms]
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) [->] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) Hub device path: \\?\USB#ROOT_HUB30#5&1C0A1D4D&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8}, port number: 20, origin: {E6EA6479-A448-5812-BA69-8AAB6114BB69}
    2023-02-21T22:44:58.187Z DEBUG [nrfdl][debug](23:44:58.187) Starting DeviceIoControl
    2023-02-21T22:44:58.194Z DEBUG [nrfdl][debug](23:44:58.195) Getting config descriptor
    2023-02-21T22:44:58.195Z DEBUG [nrfdl][debug](23:44:58.195) Iterating descriptors.
    2023-02-21T22:44:58.195Z DEBUG [nrfdl][debug](23:44:58.195) [<-] NRFDL::Win32::USBPlatformAPI::copyDeviceToUSB [took 7ms]
    2023-02-21T22:44:58.196Z VERBOSE [nrfdl][trace](23:44:58.196) [jlink] JLinkPlugin::enumerate
    2023-02-21T22:44:58.198Z DEBUG [nrfdl][debug](23:44:58.199) [serialport] serialport enumerate update device: D1871BF59F6B
    2023-02-21T22:44:58.199Z DEBUG [nrfdl][debug](23:44:58.200) [sdfu] [->] NRFDL::SDFU::SDfuPlugin::readHwInfo
    2023-02-21T22:44:58.200Z DEBUG [nrfdl][debug](23:44:58.200) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:58.400Z ERROR [nrfdl][error](23:44:58.400) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:58.700Z ERROR [nrfdl][error](23:44:58.700) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:59.000Z ERROR [nrfdl][error](23:44:59.000) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:59.300Z ERROR [nrfdl][error](23:44:59.301) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
    2023-02-21T22:44:59.301Z DEBUG [nrfdl][debug](23:44:59.301) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::readHwInfo [took 1100ms]
    2023-02-21T22:44:59.301Z ERROR [nrfdl][error](23:44:59.301) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
    2023-02-21T22:44:59.301Z ERROR [nrfdl][error](23:44:59.301) [sdfu] Failed to query sdfu hw info
    2023-02-21T22:44:59.303Z VERBOSE [nrfdl][trace](23:44:59.303) [broken] BrokenPlugin::enumerate
    2023-02-21T22:44:59.305Z DEBUG [nrfdl][debug](23:44:59.304) [sdfu] notify sdfu device event: 1 for sn: D1871BF59F6B
    2023-02-21T22:44:59.305Z DEBUG [nrfdl][debug](23:44:59.304) [sdfu] Trying to open COM port: \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}
    2023-02-21T22:44:59.305Z INFO Device was attached: 100%
    2023-02-21T22:44:59.305Z INFO Opening serial port for SDFU programming: 0%
    2023-02-21T22:44:59.504Z ERROR [nrfdl][error](23:44:59.504) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 1. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:44:59.804Z ERROR [nrfdl][error](23:44:59.805) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 2. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:45:00.105Z ERROR [nrfdl][error](23:45:00.105) [sdfu] Error when trying to open port=\\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73} at try 3. Exception=open: Access is denied.. Retrying...
    2023-02-21T22:45:00.405Z ERROR [nrfdl][error](23:45:00.405) [sdfu] Error setting up serial port \\?\USB#VID_1915&PID_521F&MI_00#7&3A620DFD&1&0000#{86e0d1e0-8089-11d0-9ce4-08003e301f73}, open: Access is denied.
    2023-02-21T22:45:00.405Z DEBUG [nrfdl][debug](23:45:00.405) [sdfu] [<-] NRFDL::SDFU::SDfuPlugin::doProgram [took 15971ms]
    2023-02-21T22:45:00.405Z DEBUG [nrfdl][debug](23:45:00.406) ~async_task id: 157311817
    2023-02-21T22:45:00.406Z ERROR [nrfdl][error](23:45:00.406) [sdfu] Error closing serial port cancel: The file handle supplied is not valid.
    2023-02-21T22:45:00.406Z INFO [nrfdl][info](23:45:00.406) close plugin dll at path: C:\Users\Kim\AppData\Local\Programs\nrfconnect\resources\app.asar.unpacked\node_modules\@nordicsemiconductor\nrf-device-lib-js\Release\nrfdl-sdfu-plugin.nrfdl
    2023-02-21T22:45:00.406Z ERROR async task failure for id: 157311817 msg: [sdfu] serialport could not be opened (Origin: "Error when running fw program.")

  • When you program the device via DFU, it may change com port, because the use of the USB is not the same in your application as it is in the bootloader. Try refreshing your com ports, and see if it popped up on another port.

    BR,
    Edvin

  • Sorry I have been out of office, so just tried your suggestions, but unfortunately no luck. The dongle is enumerated to COM5 in my system both during the first successful upload and the second failing upload.

    Windows 10 Device Manager:

    As said if I reboot my PC I'm able upload the first part of the DFU process (SDFU progress goes from 0 - 100%), but fails on the next step in the update process (unable to access COM port - access denied).

    Above is on a Windows 10 (AMD Ryzen system)

    Then I tried on a Windows 10 (Intel I7 system) and it worked, the update process succeeded the two upload cycles with no errors and the dongle behaves as expected.

    Then I plugged the newly flashed dongle back into the AMD Ryzen system and again (all tested with elevated privileges) the Access denied popped up, so clearly something related to this PC.

    Could there be any compatibility issues running on a AMD Ryzen system with the Nordic USB drivers (I have similar issues with your PPK2 kit, it doesn't work properly on my AMD Ryzen system).

    BR,

    Kim

  • Hello Kim,

    I suspect this is a general AMD Ryzen USB driver issue, not related to Nordic. Try googling AMD Ryzen USB issues.

    Perhaps you can look into that first, before trying to patch it from the Nordic USB drivers.

    BR,
    Edvin

Related