locked
DM Enrollment stuck after Discovery Response RRS feed

  • Question

  • Hi all,

    I am working on a DM/MDM solution for Windows Phone and have exhausted my resources trying to resolve an issue I'm having. Basically, after the MDM server responds to the second Discovery request, there is no further activity.

    The final request and its response are as follows:


    POST /EnrollmentServer/Discovery.svc HTTP/1.1
    Cache-Control: no-cache
    Connection: keep-alive
    Pragma: no-cache
    Content-Length: 908
    Content-Type: application/soap+xml; charset=utf-8
    Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
    Accept-Encoding: gzip, deflate
    Accept-Language: en-GB,en;q=0.5
    Host: enterpriseenrollment.dev-vm02.company.local
    User-Agent: Windows Phone 8 Enrollment Client
    
    
    <s:Envelope xmlns:a="http://www.w3.org/2005/08/addressing" xmlns:s="http://www.w3.org/2003/05/soap-envelope">
    	<s:Header>
    		<a:Action s:mustUnderstand="1">http://schemas.microsoft.com/windows/management/2012/01/enrollment/IDiscoveryService/Discover</a:Action>
    		<a:MessageID>urn:uuid:748132ec-a575-4329-b01b-6171a9cf8478</a:MessageID>
    		<a:ReplyTo>
    			<a:Address>http://www.w3.org/2005/08/addressing/anonymous</a:Address>
    		</a:ReplyTo>
    		<a:To s:mustUnderstand="1">https://dev-vm02.company.local:443/EnrollmentServer/Discovery.svc</a:To>
    	</s:Header>
    	<s:Body>
    		<Discover xmlns="http://schemas.microsoft.com/windows/management/2012/01/enrollment">
    			<request xmlns:i="http://www.w3.org/2001/XMLSchema-instance">
    				<EmailAddress>h@dev-vm02.company.local</EmailAddress>
    				<RequestVersion>2.0</RequestVersion>
    				<DeviceType>WindowsPhone</DeviceType>
    			</request>
    		</Discover>
    	</s:Body>
    </s:Envelope>
    =========================
    HTTP/1.1 200 OK
    Server: Microsoft-IIS/7.5,enterpriseenrollment.dev-vm02.company.local
    Content-Length: 1066
    Cache-Control: private
    X-AspNet-Version: 4.0.30319
    Content-Type: application/soap+xml; charset=utf-8
    
    <s:Envelope xmlns:a="http://www.w3.org/2005/08/addressing" xmlns:s="http://www.w3.org/2003/05/soap-envelope">
      <s:Header>
        <a:Action s:mustUnderstand="1">http://schemas.microsoft.com/windows/management/2012/01/enrollment/IDiscoveryService/DiscoverResponse</a:Action>
        <ActivityId>9d89f867-f8f7-4a16-83ee-9acd1846314e</ActivityId>
        <a:RelatesTo>urn:uuid:748132ec-a575-4329-b01b-6171a9cf8478</a:RelatesTo>
      </s:Header>
      <s:Body xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema">
        <DiscoverResponse xmlns="http://schemas.microsoft.com/windows/management/2012/01/enrollment">
          <DiscoverResult>
            <AuthPolicy>OnPremise</AuthPolicy>
            <EnrollmentPolicyServiceUrl>https://dev-vm02.company.local/EnrollmentServer/DeviceEnrollmentWebService.svc</EnrollmentPolicyServiceUrl>
            <EnrollmentServiceUrl>https://dev-vm02.company.local/EnrollmentServer/DeviceEnrollmentWebService.svc</EnrollmentServiceUrl>
          </DiscoverResult>
        </DiscoverResponse>
      </s:Body>
    </s:Envelope>

    After this, nothing happens. Looking at the phone debug logs for the OMA_DM subsystem, I find that the response is received but then the process is stopped with the final log entry of "Handle 0x00CC003C failed to close: Error=6". I have no idea if this is relevant and I have no more avenues left to explore so I am here with cap in hand to see if any hardened DM devs can help get beyond this.

    FYI, the OMA_DM log is as follows:

    ========================================================

    Timestamp	Summary	
    2015-06-17T09:40:24.7878783	Session handle 0x00CC0034 created: UserAgent=Windows Phone 8 Enrollment Client, AccessType=PRECONFIG, ProxyList=, ProxyBypassList=, Flags=0x00000000	
    2015-06-17T09:40:24.7883439	Handle 0x00CC0038 created by InternetConnect: SessionHandle=0x00CC0034, Server=dev-vm02.company.local:443, HTTP, Flags=0x00000200	
    2015-06-17T09:40:24.7887079	Request handle 0x00CC003C created by HttpOpenRequest: ConnectionHandle=0x00CC0038, GET, Target=EnrollmentServer/Discovery.svc, Ver=HTTP/1.1, Referrer=, Media types=, Flags=0x00800200	
    2015-06-17T09:40:24.7887172	Request handle 0x00CC003C Server Details: Server=dev-vm02.company.local Port=443 Service=http	
    2015-06-17T09:40:24.8226497	HTTP request was received from API: RequestHandle=0x00CC003C	
    2015-06-17T09:40:24.8226777	Event_1007{Request=EtwPointer{pointerValue=44216712},AddressName=https://dev-vm02.company.local/EnrollmentServer/Discovery.svc,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8227347	Event_1015{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8228272	Event_1019{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8276796	Event_1045{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8277152	Event_1047{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8277663	Event_1048{Request=EtwPointer{pointerValue=44216712},Socket=4294967295,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8279029	Event_1029{Request=EtwPointer{pointerValue=44216712},HostName=dev-vm02.company.local,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8279501	DNS query for dev-vm02.company.local hostname is sent: Handle=0x00CC003C	
    2015-06-17T09:40:24.8625803	DNS query for dev-vm02.company.local hostname is complete: Handle=0x00CC003C, Addresses=10.1.200.110;	
    2015-06-17T09:40:24.8626135	Event_1030{Request=EtwPointer{pointerValue=44216712},HostName=dev-vm02.company.local,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:24.8635429	Event_1059{Request=EtwPointer{pointerValue=44216712},Socket=1604,SourcePort=49492,RemoteAddressIndex=0,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.0821463	Event_1060{Request=EtwPointer{pointerValue=44216712},Socket=1604,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.0821660	TCP connection to server dev-vm02.company.local established: ConnectionHandle=0x00CC003C, SocketHandle=0x00000644, LocalPort=49492	
    2015-06-17T09:40:25.0822517	Client begins SSL negotiation: RequestHandle=0x00CC003C	
    2015-06-17T09:40:25.0837716	HTTP request was sent successfully: RequestHandle=0x00CC003C, SocketHandle=0x00000644, GET, CookieSent= False	
    2015-06-17T09:40:25.0838365	Event_1039{Request=EtwPointer{pointerValue=44216712},Size=1024,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.0876359	Event_1037{Request=EtwPointer{pointerValue=44216712},Size=145,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.0895609	HTTP request was sent successfully: RequestHandle=0x00CC003C, SocketHandle=0x00000644, GET, CookieSent= False	
    2015-06-17T09:40:25.1545966	Server certificate validated: RequestHandle=0x00CC003C, CertHash=4C7C00B2FA1EEBA7C224C255502927E8FA229938, WarningFlags=4352	
    2015-06-17T09:40:25.1549119	SSL negotiation between client and server has completed successfully: RequestHandle=0x00CC003C	
    2015-06-17T09:40:25.1550073	Event_1046_1{Request=EtwPointer{pointerValue=44216712},Socket=49492,Protocol=0,LocalAddressLength=16,LocalAddress=10.1.200.157:49492,RemoteAddressLength=16,RemoteAddress=10.1.200.110:443,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1550544	HTTP Request Headers RequestHandle=0x00CC003C GET /EnrollmentServer/Discovery.svc HTTP/1.1
    Content-Type: application/soap+xml; charset=utf-8
    User-Agent: Windows Phone 8 Enrollment Client
    Host: dev-vm02.company.local
    
    	
    2015-06-17T09:40:25.1550623	Event_1031{Request=EtwPointer{pointerValue=44216712},Size=176,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1554554	HTTP request was sent successfully: RequestHandle=0x00CC003C, SocketHandle=0x00000644, GET, CookieSent= False	
    2015-06-17T09:40:25.1554888	Event_1041{Request=EtwPointer{pointerValue=44216712},Size=1024,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1555257	Event_1039{Request=EtwPointer{pointerValue=44216712},Size=32768,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1785994	Event_1037{Request=EtwPointer{pointerValue=44216712},Size=245,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1787492	HTTP response (200) received: RequestHandle=0x00CC003C, SocketHandle=0x00000644, GET, ContentLength=0	
    2015-06-17T09:40:25.1787552	HTTP Response Headers RequestHandle=0x00CC003C HTTP/1.1 200 OK
    Cache-Control: no-cache, no-store, must-revalidate
    Pragma: no-cache
    Content-Type: text/html
    Expires: -1
    X-Powered-By: ASP.NET
    Date: Wed, 17 Jun 2015 08:42:45 GMT
    Content-Length: 0
    
    	
    2015-06-17T09:40:25.1788707	Connection is returned to keep-alive pool: RequestHandle=0x00CC003C, SocketHandle=0x00000644	
    2015-06-17T09:40:25.1789473	Event_1021{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1789775	Event_1025{Request=EtwPointer{pointerValue=44216712},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1790280	Event_1008{Request=EtwPointer{pointerValue=44216712},StatusLineLength=15,StatusLine=HTTP/1.1 200 OK,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1791034	Handle 0x00CC003C closed successfully	
    2015-06-17T09:40:25.1792756	Handle 0x00CC0038 closed successfully	
    2015-06-17T09:40:25.1793123	Handle 0x00CC0034 closed successfully	
    2015-06-17T09:40:25.1793894	Handle 0x00CC003C failed to close: Error=6	
    2015-06-17T09:40:25.1837277	Session handle 0x00CC0034 created: UserAgent=Windows Phone 8 Enrollment Client, AccessType=PRECONFIG, ProxyList=, ProxyBypassList=, Flags=0x00000000	
    2015-06-17T09:40:25.1838230	Handle 0x00CC0038 created by InternetConnect: SessionHandle=0x00CC0034, Server=dev-vm02.company.local:443, HTTP, Flags=0x00000200	
    2015-06-17T09:40:25.1840433	Request handle 0x00CC003C created by HttpOpenRequest: ConnectionHandle=0x00CC0038, POST, Target=EnrollmentServer/Discovery.svc, Ver=HTTP/1.1, Referrer=, Media types=, Flags=0x00800200	
    2015-06-17T09:40:25.1840517	Request handle 0x00CC003C Server Details: Server=dev-vm02.company.local Port=443 Service=http	
    2015-06-17T09:40:25.1846830	HTTP request was received from API: RequestHandle=0x00CC003C	
    2015-06-17T09:40:25.1846941	Event_1007{Request=EtwPointer{pointerValue=14296624},AddressName=https://dev-vm02.company.local/EnrollmentServer/Discovery.svc,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1901932	Event_1045{Request=EtwPointer{pointerValue=14296624},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1902220	Event_1047{Request=EtwPointer{pointerValue=14296624},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1903125	Keep-alive connection is re-used: ConnectionHandle=0x00CC003C, SocketHandle=0x00000644	
    2015-06-17T09:40:25.1903658	Event_1048{Request=EtwPointer{pointerValue=14296624},Socket=49492,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1904177	Event_1046_1{Request=EtwPointer{pointerValue=14296624},Socket=49492,Protocol=0,LocalAddressLength=16,LocalAddress=10.1.200.157:49492,RemoteAddressLength=16,RemoteAddress=10.1.200.110:443,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1904549	HTTP Request Headers RequestHandle=0x00CC003C POST /EnrollmentServer/Discovery.svc HTTP/1.1
    Content-Type: application/soap+xml; charset=utf-8
    User-Agent: Windows Phone 8 Enrollment Client
    Host: dev-vm02.company.local
    Content-Length: 926
    Cache-Control: no-cache
    
    	
    2015-06-17T09:40:25.1904722	HTTP Request Headers OptionalData RequestHandle=0x00CC003C <s:Envelope xmlns:a="http://www.w3.org/2005/08/addressing" xmlns:s="http://www.w3.org/2003/05/soap-envelope">
    	<s:Header>
    		<a:Action s:mustUnderstand="1">http://schemas.microsoft.com/windows/management/2012/01/enrollment/IDiscoveryService/Discover</a:Action>
    		<a:MessageID>urn:uuid:748132ec-a575-4329-b01b-6171a9cf8478</a:MessageID>
    		<a:ReplyTo>
    			<a:Address>http://www.w3.org/2005/08/addressing/anonymous</a:Address>
    		</a:ReplyTo>
    		<a:To s:mustUnderstand="1">https://dev-vm02.company.local:443/EnrollmentServer/Discovery.svc</a:To>
    	</s:Header>
    	<s:Body>
    		<Discover xmlns="http://schemas.microsoft.com/windows/management/2012/01/enrollment">
    			<request xmlns:i="http://www.w3.org/2001/XMLSchema-instance">
    				<EmailAddress>v@dev-vm02.company.local</EmailAddress>
    				<RequestVersion>2.0</RequestVersion>
    				<DeviceType>WindowsPhone</DeviceType>
    			</request>
    		</Discover>
    	</s:Body>
    </s:Envelope>	
    2015-06-17T09:40:25.1904818	Event_1031{Request=EtwPointer{pointerValue=14296624},Size=223,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1909312	HTTP request was sent successfully: RequestHandle=0x00CC003C, SocketHandle=0x00000644, POST, CookieSent= False	
    2015-06-17T09:40:25.1909731	Event_1033{Request=EtwPointer{pointerValue=14296624},Size=926,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1915566	HTTP request was sent successfully: RequestHandle=0x00CC003C, SocketHandle=0x00000644, POST, CookieSent= False	
    2015-06-17T09:40:25.1915993	Event_1041{Request=EtwPointer{pointerValue=14296624},Size=1024,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:25.1916415	Event_1039{Request=EtwPointer{pointerValue=14296624},Size=32768,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:28.4599144	Event_1037{Request=EtwPointer{pointerValue=14296624},Size=1333,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:28.4602058	HTTP response (200) received: RequestHandle=0x00CC003C, SocketHandle=0x00000644, POST, ContentLength=1066	
    2015-06-17T09:40:28.4602140	HTTP Response Headers RequestHandle=0x00CC003C HTTP/1.1 200 OK
    Cache-Control: no-cache, no-store, must-revalidate
    Pragma: no-cache
    Content-Type: application/soap+xml; charset=utf-8
    Expires: -1
    X-Powered-By: ASP.NET
    Date: Wed, 17 Jun 2015 08:42:48 GMT
    Content-Length: 1066
    
    	
    2015-06-17T09:40:28.4602550	Event_1021{Request=EtwPointer{pointerValue=14296624},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:28.4602954	Event_1025{Request=EtwPointer{pointerValue=14296624},EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:28.4603802	Event_1008{Request=EtwPointer{pointerValue=14296624},StatusLineLength=15,StatusLine=HTTP/1.1 200 OK,EtwKeywords=Keywords{StandardKeywords=WindowsEtwKeywords{EventlogClassic=False,CorrelationHint=False,AuditSuccess=False,AuditFailure=False,SQM=False,WDIDiag=False,WDIContext=False},WININET_KEYWORD_PII_PRESENT=False,WININET_KEYWORD_AOAC=False,WININET_KEYWORD_IE=True,WININET_KEYWORD_COOKIES=False,WININET_KEYWORD_AUTOPROXY=False,WININET_KEYWORD_HTTPS=False,WININET_KEYWORD_AUTH=False,WININET_KEYWORD_CONNECTION=False,WININET_KEYWORD_HTTP=False,WININET_KEYWORD_HANDLES=False}}	
    2015-06-17T09:40:28.4608589	Connection is returned to keep-alive pool: RequestHandle=0x00CC003C, SocketHandle=0x00000644	
    2015-06-17T09:40:28.4609312	HTTP response entity body received: RequestHandle=0x00CC003C	
    2015-06-17T09:40:28.4610909	Handle 0x00CC003C closed successfully	
    2015-06-17T09:40:28.4626097	Handle 0x00CC0038 closed successfully	
    2015-06-17T09:40:28.4626765	Handle 0x00CC0034 closed successfully	
    2015-06-17T09:40:28.4743639	Handle 0x00CC003C failed to close: Error=6	
    



    Wednesday, June 17, 2015 10:50 AM

Answers

  • OK, so it turns out this was due to a certificate issue. In the first step of entering the email address the user was being warned that the certificate could not be verified but they were allowed to tap 'Continue' to proceed with the process and so we were ignoring this warning. However, despite this warning occurring earlier in the process and despite the user confirming their willingness to continue, it appears that the underlying SSL issue was still causing the enrollment process to sliently fail before moving on from discovery... frustrating to say the least.

    The SSL issue was that a) the certificate was bound to dev-vm02.company.com when it should have been bound to enterpriseenrollment.dev-vm02.company.com and b) the CRL was a HTTP URL while ADCS only published the CRL on HTTPS. Rather than fix the issues with ADCS publishing incorrect CRL URLs we have simply removed the SSL restriction on the CRL endpoint so that the CRL is also published on HTTP and we are now moving forward again.

    Just a hint incase anyone from MS sees this, some documentation on what the DM WinINet subsystem will and won't accept in regards to certificate issues would have saved us several days of trial and error. For example, it seems that certificates signed by an internal ADCS CA are fine despite warnings while incorrect CRLs are not acceptable despite user consent to continue.


    Thursday, June 18, 2015 9:51 AM

All replies

  • Bump
    Thursday, June 18, 2015 6:21 AM
  • Hi Stephenroughley,

    You are asking question about Developing for the Mobile Device Management Protocol, I will help move thread to that forum for effective response.

    Thank you for understanding.

    Regards, 


    We are trying to better understand customer views on social support experience, so your participation in this interview project would be greatly appreciated if you have time. Thanks for helping make community forums a great place.
    Click HERE to participate the survey.

    Thursday, June 18, 2015 7:57 AM
  • OK, so it turns out this was due to a certificate issue. In the first step of entering the email address the user was being warned that the certificate could not be verified but they were allowed to tap 'Continue' to proceed with the process and so we were ignoring this warning. However, despite this warning occurring earlier in the process and despite the user confirming their willingness to continue, it appears that the underlying SSL issue was still causing the enrollment process to sliently fail before moving on from discovery... frustrating to say the least.

    The SSL issue was that a) the certificate was bound to dev-vm02.company.com when it should have been bound to enterpriseenrollment.dev-vm02.company.com and b) the CRL was a HTTP URL while ADCS only published the CRL on HTTPS. Rather than fix the issues with ADCS publishing incorrect CRL URLs we have simply removed the SSL restriction on the CRL endpoint so that the CRL is also published on HTTP and we are now moving forward again.

    Just a hint incase anyone from MS sees this, some documentation on what the DM WinINet subsystem will and won't accept in regards to certificate issues would have saved us several days of trial and error. For example, it seems that certificates signed by an internal ADCS CA are fine despite warnings while incorrect CRLs are not acceptable despite user consent to continue.


    Thursday, June 18, 2015 9:51 AM
  • Thank you for this information,

    WP8.1 works like a charme and on Windows 10 mobile or Desktop we have exactly the same problems. We check this issue on every insider release and hope this problem will be resolved until final release.

    On one of the first w10mobile releases everything worked fine, but with every release it´s getting worst.

    First you cannot accept a self signed certificate, now this is fixed. But now the device just fires discovery request and nothing else.

    I hope microsoft is fixing this issue, or how would it be possible to change the communication address to enterpriseenrollment.server.example.com on devices (8.1) which are already enrolled against server.example.com without reenrollment?

    I´ll give it a try in our testenvironment. CRL is already correct at http listener, let´s check your hint with enterpriseenrollment. certificate.

    Thanks.

    Frank

    Tuesday, July 14, 2015 2:23 PM