none
NdisWanLegacy protocol driver issue on windows 8.1 RRS feed

  • Question

  • I have an NDIS WAN miniport driver, that will (sometimes) not bind to NDISWANLEGACY protocol driver.  If the miniport device itself is present before the NIDSWAN service is started, only then will the binding occur.  It seems like this is a major flaw built into the NDISWANLEGACY protocol driver itself.  Some more testing details..

    1. if the device is present before the NDISWAN service loads then the binding will occur.  Plug and play will then function normally and respect the binding. Everything works ok with no bug.  

    2. if the device is inserted after the NDISWAN service loads then the binding will never occur.  Subsequent plug and play operations will not respect the binding.   

    3. testing in Windows 7 shows the bug never occurs (in windows 7 our miniport driver is not under legacy service)

    4. the microsoft bindview DDK test application sees the binding path, but will not perform a bind/unbind. (during the bug is present case)

    5. bindview will see the binding path and will perform bind and unbind when not in the bug case. (device inserted before NDISWAN starts)


    Wednesday, April 30, 2014 7:22 PM

Answers

  • You can mail the entire thing to Jeffrey.Tippet at Microsoft dot com.

    How to read this trace:

    Creating this binding because it is statically-defined in the registry [NDISWANLEGACY <-> miniport FFFFE00002E901A0]

    This means that NIDS knows NDISWANLEGACY should bind to the miniport.  (From elsewhere in the log file, you can see that "FFFFE00002E901A0" corresponds to "IPWireless 3G Network Adapter", which I assume is your miniport.

    So NDIS wants to bind NDISWANLEGACY... we just need to figure out why it's not binding.

    Old UnbindReasons: Reason_MiniportDeviceNotStarted(0x80000); new UnbindReasons: (0x0)

    NDIS keeps a set of flags that tell you why a miniport can't bind yet.  This trace tells us that those flags transitioned from 0x80000 to 0.  That is, we had one reason the miniport couldn't bind, that reason was removed, and now we have no reason the miniport can't bind.

    Even though the miniport is ready to bind, there may still be reasons that NDISWANLEGACY cannot bind.  Unfortunately, I can't see from the trace log any further mentions of the NDISWANLEGACY driver, so I can't tell what its state is.

    • Marked as answer by allencoreyl Monday, May 5, 2014 1:43 PM
    Thursday, May 1, 2014 7:52 PM
  • answered here, http://www.osronline.com/showthread.cfm?link=255533


    d -- This posting is provided "AS IS" with no warranties, and confers no rights.

    Wednesday, April 30, 2014 11:39 PM

All replies

  • answered here, http://www.osronline.com/showthread.cfm?link=255533


    d -- This posting is provided "AS IS" with no warranties, and confers no rights.

    Wednesday, April 30, 2014 11:39 PM
  • That answer on OSR is pretty crappy though.  Let's see if I can do better than that Tippet guy.

    It sounds like you have new information that wasn't in the original OSR post.  It's not a difference between server / client SKUs -- it's actually a nondeterministic race.

    Can you collect NDIS traces of the buggy case? Use trace flags 0x88035f3.  (In particular, flag 0x8000000 tells you why NDIS decides to not bind a protocol to a driver.) You can decode the traces if you like, or just upload the ETL file somewhere I can access it.


    Thursday, May 1, 2014 4:54 AM
  • Jeffrey,

    I have generated a txt file.  How can i upload it to you?  Here is a small snippet.  It is the IPWireless 3g network adapter.

    [0]0004.0FE0::05/01/2014-07:07:26.459 [km]Old BindSources: (0x0); new BindSources: (0x1)
    [0]0004.0FE0::05/01/2014-07:07:26.459 [km]Creating this binding because it is statically-defined in the registry [NDISWANLEGACY <-> miniport FFFFE00002E901A0]
    [0]0004.0FE0::05/01/2014-07:07:26.459 [km]Begin PNP operations on miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [km]End PNP operations on miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]==>ndisNotifyMiniportsPowerProfileChange: Miniport FFFFE00002E901A0, Event 5, Buffer FFFFD0002DA77E18
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]==>ndisNotifyMiniportsPowerProfileChange: Miniport FFFFE00002E901A0, DevicePnPEvent 5
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]<==ndisNotifyMiniportsPowerProfileChange: Miniport FFFFE00002E901A0, DevicePnPEvent 5
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]<==ndisNotifyMiniportsPowerProfileChange: Miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]<==ndisMInitializeAdapter: Miniport FFFFE00002E901A0, Status 0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]Adapter Initialized: Miniport FFFFE00002E901A0, IPWireless 3G Network Adapter v4.0 Status 0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]ndisLogMiniportEvent: NdisMEvent_MiniportInitialized, Miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]<==ndisInitializeAdapter: Miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [mp]<==ndisPnPStartDevice: Miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.459 [km]Begin PNP operations on miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [km]End PNP operations on miniport FFFFE00002E901A0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]<==ndisStartDeviceSynchronous: Miniport FFFFE00002E901A0, Status 0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]<==ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction 0, Status 0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]==>ndisPnPDispatch: DeviceObject FFFFE00002E90050, Irp FFFFE000018BA3E0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction: 9
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]==>ndisPnPIrpQueryCapabilities: Miniport FFFFE00002E901A0, IRP_MN_QUERY_CAPABILITIES
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]===ndisPnPIrpQueryCapabilities: Miniport FFFFE00002E901A0, Clearing the SupriseRemovalOk bit.
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]<==ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction 9, Status 0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]==>ndisCreateIrpHandler: DeviceObject FFFFE00002E90050, Irp FFFFE00000B8F5B0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]<==ndisCreateIrpHandler: DeviceObject FFFFE00002E90050, Irp FFFFE00000B8F5B0, Status 0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]The miniport FFFFE00002E901A0 has successfully completed its start IRP and is now ready to bind
    [1]0004.00B4::05/01/2014-07:07:26.460 [km]Old UnbindReasons: Reason_MiniportDeviceNotStarted(0x80000); new UnbindReasons: (0x0)
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]==>ndisPnPDispatch: DeviceObject FFFFE00002E90050, Irp FFFFE000018BA3E0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction: 14
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]Now allowing bindings on miniport (Reason_MiniportDeviceNotStarted(0x80000)) [IPWireless 3G Network Adapter <-> miniport FFFFE00002E901A0]
    [1]0004.00B4::05/01/2014-07:07:26.460 [km]Old PauseReasons: PauseReason_Pnp(0x4); new PauseReasons: (0x0)
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]<==ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction 14, Status 0
    [1]0004.00B4::05/01/2014-07:07:26.460 [km]The miniport was paused for PNP operations, which have now completed; restarting the miniport's datapath [IPWireless 3G Network Adapter <-> miniport FFFFE00002E901A0]
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]==>ndisDummyIrpHandler: DeviceObject FFFFE00002E90050, Irp FFFFE00000B8F5B0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]<==ndisDummyIrpHandler: DeviceObject FFFFE00002E90050, Irp FFFFE00000B8F5B0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]==>ndisCloseIrpHandler: Miniport FFFFE00002E901A0, Irp FFFFE00000B8F5B0
    [1]0004.00B4::05/01/2014-07:07:26.460 [mp]<==ndisCloseIrpHandler: Miniport FFFFE00002E901A0, Irp FFFFE00000B8F5B0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]==>ndisPnPDispatch: DeviceObject FFFFE00002E90050, Irp FFFFE000018BA3E0
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction: 7
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction 0x7
    [0]0004.0FE0::05/01/2014-07:07:26.460 [mp]<==ndisPnPDispatch: Miniport FFFFE00002E901A0, MinorFunction 7, Status c00000bb
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, SourceFilter 0000000000000000, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0, Status 0x0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, DeQueued Request FFFFD000234387E0, OID 10101
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisPreProcessOid: Object FFFFE00002E901A0, Type 11, Request FFFFD000234387E0, Oid 10101
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidPreSupportedList: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidPreSupportedList: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidRequestComplete: Miniport FFFFE00002E901A0, Filter 0000000000000000, Open 0000000000000000, Request FFFFD000234387E0, OID 10101, Status c0010014
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisOidRequestComplete: Request Clearing Processing Request Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidRequestComplete: Miniport(Filter) FFFFE00002E901A0, Request FFFFD000234387E0, Open 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisPreProcessOid: Request FFFFD000234387E0, CompletionStatus 103, bComplete 1
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0: Request FFFFD000234387E0, OID 10101, bComplete 1, Status 103
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, Status 103
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0 TargetFilter 0000000000000000 Status c0010014
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, SourceFilter 0000000000000000, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0, Status 0x0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, DeQueued Request FFFFD000234387E0, OID 10101
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisPreProcessOid: Object FFFFE00002E901A0, Type 11, Request FFFFD000234387E0, Oid 10101
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidPreSupportedList: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidPreSupportedList: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidRequestComplete: Miniport FFFFE00002E901A0, Filter 0000000000000000, Open 0000000000000000, Request FFFFD000234387E0, OID 10101, Status 0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisOidRequestComplete: Request Clearing Processing Request Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidRequestComplete: Miniport(Filter) FFFFE00002E901A0, Request FFFFD000234387E0, Open 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisPreProcessOid: Request FFFFD000234387E0, CompletionStatus 103, bComplete 1
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0: Request FFFFD000234387E0, OID 10101, bComplete 1, Status 103
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, Status 103
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0 TargetFilter 0000000000000000 Status 0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, SourceFilter 0000000000000000, TargetFilter 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidSendToMiniportDirect: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidTranslateBetweenOids: Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD000234387E0, Status 0x0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, DeQueued Request FFFFD000234387E0, OID 10117
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisPreProcessOid: Object FFFFE00002E901A0, Type 11, Request FFFFD000234387E0, Oid 10117
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisPreProcessOid: Request FFFFD000234387E0, CompletionStatus 0, bComplete 0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0: Request FFFFD000234387E0, OID 10117, bComplete 0, Status 0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMOidRequestToRequest: Miniport FFFFE00002E901A0, OidRequest FFFFD000234387E0, Oid 10117
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisMOidRequestToRequest: Miniport FFFFE00002E901A0, OidRequest FFFFD000234387E0, Oid 10117, Status c0010017
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, Completing Request FFFFD000234387E0, OID 10117, Status c0010017
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]==>ndisOidRequestComplete: Miniport FFFFE00002E901A0, Filter 0000000000000000, Open 0000000000000000, Request FFFFD000234387E0, OID 10117, Status c0010017
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]ndisOidRequestComplete: Request Clearing Processing Request Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisOidRequestComplete: Miniport(Filter) FFFFE00002E901A0, Request FFFFD000234387E0, Open 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQueueOidRequest: Request FFFFD000234387E0, Miniport FFFFE00002E901A0, Status 103
    [0]0004.099C::05/01/2014-07:07:26.460 [mp]<==ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD000234387E0 TargetFilter 0000000000000000 Status c0010017
    [0]0004.099C::05/01/2014-07:07:26.465 [km]Begin PNP operations on miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]Begin restart miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]==>ndisMRestartMiniportInner: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]==>ndisSetupLwfMiniportHandlers : Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]==>ndisSetupNoFilterHandlers : Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]<==ndisSetupNoFilterHandlers : Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]<==ndisSetupLwfMiniportHandlers: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]Miniport Restart: Miniport FFFFE00002E901A0, IPWireless 3G Network Adapter
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]==>NdisMRestartComplete: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]<==NdisMRestartComplete: Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]ndisLogMiniportEvent: NdisMEvent_MiniportRestarted, Miniport FFFFE00002E901A0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]==>ndisIndicateStatusInternal, Miniport FFFFE00002E901A0, Status 40010023, Open 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]ndisIndicateStatusInternal: NDIS_STATUS_OPER_STATUS, Miniport FFFFE00002E901A0, OperStatus NET_IF_OPER_STATUS_UP, OperFlags 0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]<==ndisIndicateStatusInternal, Miniport FFFFE00002E901A0, Status 40010023, DestinationOpen 0000000000000000
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]<==ndisMRestartMiniportInner: Miniport FFFFE00002E901A0, Status 0
    [0]0004.099C::05/01/2014-07:07:26.465 [mp]Finish restart miniport FFFFE00002E901A0 with status 0x00000000
    [0]0004.099C::05/01/2014-07:07:26.465 [km]End PNP operations on miniport FFFFE00002E901A0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD00023AB4FF0, TargetFilter 0000000000000000
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisQueueOidRequest: Request FFFFD00023AB4FF0, Miniport FFFFE00002E901A0, SourceFilter 0000000000000000, TargetFilter 0000000000000000
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisOidSendToMiniportDirect: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisOidSendToMiniportDirect: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisOidTranslateBetweenOids: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisOidTranslateBetweenOids: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisMQueueOidRequest: Miniport FFFFE00002E901A0, Request FFFFD00023AB4FF0, Status 0x0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, DeQueued Request FFFFD00023AB4FF0, OID 20104
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisPreProcessOid: Object FFFFE00002E901A0, Type 11, Request FFFFD00023AB4FF0, Oid 20104
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisPreProcessOid: Request FFFFD00023AB4FF0, CompletionStatus 0, bComplete 0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0: Request FFFFD00023AB4FF0, OID 20104, bComplete 0, Status 0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisMOidRequestToRequest: Miniport FFFFE00002E901A0, OidRequest FFFFD00023AB4FF0, Oid 20104
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisMOidRequestToRequest: Miniport FFFFE00002E901A0, OidRequest FFFFD00023AB4FF0, Oid 20104, Status c0010017
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]ndisMDoOidRequest: Miniport FFFFE00002E901A0, Completing Request FFFFD00023AB4FF0, OID 20104, Status c0010017
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisOidRequestComplete: Miniport FFFFE00002E901A0, Filter 0000000000000000, Open 0000000000000000, Request FFFFD00023AB4FF0, OID 20104, Status c0010017
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]ndisOidRequestComplete: Request Clearing Processing Request Miniport FFFFE00002E901A0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisOidRequestComplete: Miniport(Filter) FFFFE00002E901A0, Request FFFFD00023AB4FF0, Open 0000000000000000
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisMDoOidRequest: Miniport FFFFE00002E901A0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisQueueOidRequest: Request FFFFD00023AB4FF0, Miniport FFFFE00002E901A0, Status 103
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD00023AB4FF0 TargetFilter 0000000000000000 Status c0010017
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisQuerySetMiniportEx Miniport FFFFE00002E901A0 Request FFFFD00023AB4FF0, TargetFilter 0000000000000000
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisQueueOidRequest: Request FFFFD00023AB4FF0, Miniport FFFFE00002E901A0, SourceFilter 0000000000000000, TargetFilter 0000000000000000
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisOidSendToMiniportDirect: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisOidSendToMiniportDirect: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]==>ndisOidTranslateBetweenOids: Request FFFFD00023AB4FF0
    [0]0348.0FC8::05/01/2014-07:07:26.466 [mp]<==ndisOidTranslateBetweenOids: Request FFFFD00023AB4FF0


    -Corey

    Thursday, May 1, 2014 2:31 PM
  • You can mail the entire thing to Jeffrey.Tippet at Microsoft dot com.

    How to read this trace:

    Creating this binding because it is statically-defined in the registry [NDISWANLEGACY <-> miniport FFFFE00002E901A0]

    This means that NIDS knows NDISWANLEGACY should bind to the miniport.  (From elsewhere in the log file, you can see that "FFFFE00002E901A0" corresponds to "IPWireless 3G Network Adapter", which I assume is your miniport.

    So NDIS wants to bind NDISWANLEGACY... we just need to figure out why it's not binding.

    Old UnbindReasons: Reason_MiniportDeviceNotStarted(0x80000); new UnbindReasons: (0x0)

    NDIS keeps a set of flags that tell you why a miniport can't bind yet.  This trace tells us that those flags transitioned from 0x80000 to 0.  That is, we had one reason the miniport couldn't bind, that reason was removed, and now we have no reason the miniport can't bind.

    Even though the miniport is ready to bind, there may still be reasons that NDISWANLEGACY cannot bind.  Unfortunately, I can't see from the trace log any further mentions of the NDISWANLEGACY driver, so I can't tell what its state is.

    • Marked as answer by allencoreyl Monday, May 5, 2014 1:43 PM
    Thursday, May 1, 2014 7:52 PM
  • just an update on this issue.  The bug does not appear to exist in Windows 8.0 

    -Corey

    Tuesday, June 3, 2014 3:00 PM