SOLVED: Docker fails to start after upgrade from RC2 to RC3

uliweltersbach · July 6, 2016, 9:30am

EDIT 3: I ran a Windows Update and after that Docker was able to create the MobyLinuxVM again. Windows 10 Enterprise Version 1607 Build 14383.

Expected behavior

Docker for Windows is able to run on Windows 10 Enterprise Insider Preview Build 14379.

Actual behavior

After upgrading from RC2 to RC3, Docker fails to start.

Information

According to the log file there’s an issue when trying to get an (unspecified) object from Hyper-V.
I’ve looked at this issue but it doesn’t fit what I’m seeing in the logs translated from Danish:

“Hyper-V encountered an error trying to access an object on computer ‘{0}’ because the object was not found. The object might have been deleted, or you might not have permission to perform the task. Verify that the Virtual Machine Management service on the computer is running. If the service is running, try to perform the task again by using Run as Administrator.”

The Hyper-V installation check however doesn’t seem to find an issue:

[11:22:03.646][NamedPipeClient][Info ] Sending CheckInstallation()…
[11:22:03.646][NamedPipeServer][Info ] CheckInstallation()
[11:22:03.668][HyperV ][Info ] Check Hyper-V Windows optional feature
[11:22:03.668][PowerShell ][Info ] Run script…
[11:22:04.423][HyperV ][Info ] Hyper-V Windows feature is enabled
[11:22:04.431][NamedPipeClient][Info ] Received response for CheckInstallation
[11:22:04.428][NamedPipeServer][Info ] CheckInstallation done.
[11:22:04.436][NamedPipeClient][Info ] Sending CheckHyperVState()…
[11:22:04.442][NamedPipeServer][Info ] CheckHyperVState()
[11:22:04.446][HyperV ][Info ] Check Hyper-V Service State
[11:22:04.450][PowerShell ][Info ] Run script with parameters: -Check True…
[11:22:04.505][HyperV ][Info ] Hyper-V is running and responding
[11:22:04.505][HyperV ][Info ] Hyper-V is running
[11:22:04.521][NamedPipeClient][Info ] Received response for CheckHyperVState
[11:22:04.505][NamedPipeServer][Info ] CheckHyperVState done.

Full log:

[11:18:44.398][Program ][Info ] 1.12.0-rc3-beta18
[11:18:44.398][Program ][Info ] Starting on: 06-Jul-16 11:18:44
[11:18:44.414][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[11:18:44.414][Program ][Info ] OS: Windows 10 Enterprise Insider Preview
[11:18:44.414][Program ][Info ] Edition: Enterprise
[11:18:44.414][Program ][Info ] Id: 1607
[11:18:44.414][Program ][Info ] Build: 14379
[11:18:44.430][Program ][Info ] BuildLabName: 14379.0.amd64fre.rs1_release.160627-1607
[11:18:44.430][Program ][Info ] Mixpanel Id: D5267B6D-15EE-4628-8AA6-1F83E935E123
[11:18:44.430][Program ][Info ] Sha1: 5cbabb0783b6d281b8f579cb9f4dff38373b2c10
[11:18:44.445][Program ][Info ] You can send feedback, including this log file, at forums.docker.com/c/docker-for-windows
[11:18:44.784][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[11:18:44.831][Mixpanel ][Info ] Usage Statistic: appLaunched
[11:18:45.116][NamedPipeClient][Info ] Sending Version()…
[11:18:45.131][NamedPipeClient][Info ] Received response for Version
[11:18:45.147][Mixpanel ][Info ] Usage Statistic: heartbeat
[11:18:45.131][NamedPipeServer][Info ] Version done.
[11:18:45.285][NamedPipeClient][Info ] Sending Stop()…
[11:18:45.285][Updater ][Info ] Checking for updates on channel Beta…
[11:18:45.285][NamedPipeServer][Info ] Stop()
[11:18:45.301][HyperV ][Info ] Stop
[11:18:45.301][PowerShell ][Info ] Run script with parameters: -Stop True…
[11:18:45.385][HyperV ][Info ] VM MobyLinuxVM does not exist
[11:18:45.401][NamedPipeClient][Info ] Received response for Stop
[11:18:45.401][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)…
[11:18:45.385][NamedPipeServer][Info ] Stop done.
[11:18:45.401][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[11:18:45.401][HyperVGuids ][Info ] Installing GUIDs…
[11:18:45.417][HyperVGuids ][Info ] GUIDs installed
[11:18:45.417][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe…
[11:18:45.486][Updater ][Info ] Local build 5193 is as good as the remote 5193 on channel Beta
[11:21:59.546][Firewall ][Info ] Ports are opened
[11:21:59.552][HyperV ][Info ] Create
[11:21:59.556][PowerShell ][Info ] Run script with parameters: -Create True -SwitchSubnetAddress 10.0.75.0 -SwitchSubnetMaskSize 24 -CPUs 2 -Memory 2048 -IsoFile C:\Program Files\Docker\Docker\Resources\mobylinux.iso…
[11:21:59.712][HyperV ][Info ] Using existing Switch: DockerNAT
[11:22:00.244][HyperV ][Info ] Using existing Switch IP address
[11:22:00.264][HyperV ][Info ] VHD D:\Hyper-V\Virtual Hard Disks\MobyLinuxVM.vhdx already exists
[11:22:00.322][HyperV ][Info ] Creating VM MobyLinuxVM…
[11:22:03.615][NamedPipeServer][Error ] Unable to execute Start: Failed to create VM “MobyLinuxVM”: Der opstod en fejl i Hyper-V under forsøget på at få adgang til et objekt på computeren ‘LAPTOP’, fordi objektet ikke blev fundet. Objektet er muligvis blevet slettet, eller du har ikke tilladelse til at udføre handlingen. Kontrollér, at tjenesten Administration af virtuel maskine kører på computeren. Hvis tjenesten kører, skal du prøve at udføre opgaven igen ved hjælp af Kør som administrator. ved System.Management.Automation.Runspaces.AsyncResult.EndInvoke()
ved System.Management.Automation.PowerShell.EndInvoke(IAsyncResult asyncResult)
ved Docker.Core.PowerShell.Run(String script, Dictionary2 parameters, Action1 lineReceived)
ved Docker.Backend.Backend.Start(Settings settings)
ved Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass7_0.b__0(Object[] parameters)
ved Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)
[11:22:03.631][NamedPipeClient][Error ] Unable to send Start: Failed to create VM “MobyLinuxVM”: Der opstod en fejl i Hyper-V under forsøget på at få adgang til et objekt på computeren ‘LAPTOP’, fordi objektet ikke blev fundet. Objektet er muligvis blevet slettet, eller du har ikke tilladelse til at udføre handlingen. Kontrollér, at tjenesten Administration af virtuel maskine kører på computeren. Hvis tjenesten kører, skal du prøve at udføre opgaven igen ved hjælp af Kør som administrator.
[11:22:03.646][NamedPipeClient][Info ] Sending CheckInstallation()…
[11:22:03.646][NamedPipeServer][Info ] CheckInstallation()
[11:22:03.668][HyperV ][Info ] Check Hyper-V Windows optional feature
[11:22:03.668][PowerShell ][Info ] Run script…
[11:22:04.423][HyperV ][Info ] Hyper-V Windows feature is enabled
[11:22:04.431][NamedPipeClient][Info ] Received response for CheckInstallation
[11:22:04.428][NamedPipeServer][Info ] CheckInstallation done.
[11:22:04.436][NamedPipeClient][Info ] Sending CheckHyperVState()…
[11:22:04.442][NamedPipeServer][Info ] CheckHyperVState()
[11:22:04.446][HyperV ][Info ] Check Hyper-V Service State
[11:22:04.450][PowerShell ][Info ] Run script with parameters: -Check True…
[11:22:04.505][HyperV ][Info ] Hyper-V is running and responding
[11:22:04.505][HyperV ][Info ] Hyper-V is running
[11:22:04.521][NamedPipeClient][Info ] Received response for CheckHyperVState
[11:22:04.505][NamedPipeServer][Info ] CheckHyperVState done.
[11:22:04.521][Notifications ][Error ] Failed to create VM “MobyLinuxVM”: Der opstod en fejl i Hyper-V under forsøget på at få adgang til et objekt på computeren ‘LAPTOP’, fordi objektet ikke blev fundet. Objektet er muligvis blevet slettet, eller du har ikke tilladelse til at udføre handlingen. Kontrollér, at tjenesten Administration af virtuel maskine kører på computeren. Hvis tjenesten kører, skal du prøve at udføre opgaven igen ved hjælp af Kør som administrator.
[11:22:04.539][NamedPipeClient][Info ] Sending GetDebugInfo()…
[11:22:04.539][NamedPipeServer][Info ] GetDebugInfo()
[11:22:12.412][NamedPipeServer][Info ] GetDebugInfo done.
[11:22:12.428][NamedPipeClient][Info ] Received response for GetDebugInfo
[11:22:12.447][Program ][Info ] Sending Bugsnag report 96c11884-73ce-4b7d-817c-7a268ca9c49d…
[11:22:13.450][Program ][Info ] Bugsnag report 96c11884-73ce-4b7d-817c-7a268ca9c49d sent
[11:22:13.497][NamedPipeClient][Info ] Sending DownloadVmLogs()…
[11:22:13.512][NamedPipeServer][Info ] DownloadVmLogs()
[11:22:13.512][PowerShell ][Info ] Run script…
[11:22:13.547][HyperV ][Warning] Unable to download logs: The running command stopped because the preference variable “ErrorActionPreference” or common parameter is set to Stop: Hyper-V was unable to find a virtual machine with name “MobyLinuxVM”.
[11:22:13.550][NamedPipeClient][Info ] Received response for DownloadVmLogs
[11:22:13.550][NamedPipeServer][Info ] DownloadVmLogs done.
[11:22:20.818][ErrorReportWindow][Info ] Open logs

nicotexas · July 6, 2016, 4:47pm

From this
[11:22:00.264][HyperV ][Info ] VHD D:\Hyper-V\Virtual Hard Disks\MobyLinuxVM.vhdx already exists
[11:22:00.322][HyperV ][Info ] Creating VM MobyLinuxVM…
[11:22:03.615][NamedPipeServer][Error ] Unable to execute Start: Failed to create VM “MobyLinuxVM”:

I think the installer is trying to recreate the VM even if already there. I would clean-up Hyper-V (maybe even try to disable the feature) and let Docker re-enable the Hyper-V feature.
But that seems like a bug in the installer.

Also have a look here "DockerNAT": Instance MSFT_NetIPAddress already exists
Might be related somehow!

uliweltersbach · July 7, 2016, 10:26am

Thanks for the reply.
I’ve tried completely removing the Hyper-V feature and reinstalling/reenabling it, still no luck though.
Fails with exactly the same log messages.

EDIT 1: After reading this I tried creating a “vanilla VM” and it succeeded, so Hyper-V is working correctly.
EDIT 2: Docker manages to create the virtual hard disk and DockerNAT virtual switch, but fails creating the virtual machine itself.
EDIT 3: I ran a Windows Update and after that Docker was able to create the MobyLinuxVM again. Windows 10 Enterprise Version 1607 Build 14383.

pitaki · July 7, 2016, 11:24am

Docker ceased to initiate after latest update (R2 - R3). My logging looks like
this: (My windows is localized Finnish)

[13:58:48.424][Program ][Info ] 1.12.0-rc3-beta18
[13:58:48.469][Program ][Info ] Starting on: 7/7/2016 1:58:48 PM
[13:58:48.480][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[13:58:48.492][Program ][Info ] OS: Windows 10 Pro
[13:58:48.504][Program ][Info ] Edition: Professional
[13:58:48.517][Program ][Info ] Id: 1511
[13:58:48.528][Program ][Info ] Build: 10586
[13:58:48.544][Program ][Info ] BuildLabName: 10586.420.amd64fre.th2_release_sec.160527-1834
[13:58:48.563][Program ][Info ] Mixpanel Id: 9B3DBE94-F1F1-4E08-8C17-C05E09E58B1A
[13:58:48.580][Program ][Info ] Sha1: 5cbabb0783b6d281b8f579cb9f4dff38373b2c10
[13:58:48.594][Program ][Info ] You can send feedback, including this log file, at https://forums.docker.com/c/docker-for-windows
[13:58:49.583][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[13:58:49.749][Mixpanel ][Info ] Usage Statistic: appLaunched
[13:58:50.572][NamedPipeClient][Info ] Sending Version()…
[13:58:50.624][NamedPipeClient][Info ] Received response for Version
[13:58:50.670][Mixpanel ][Info ] Usage Statistic: heartbeat
[13.58.50.623][NamedPipeServer][Info ] Version done.
[13.58.51.247][NamedPipeClient][Info ] Sending Stop()…
[13:58:51.262][Updater ][Info ] Checking for updates on channel Beta…
[13.58.51.304][NamedPipeServer][Info ] Stop()
[13.58.51.305][HyperV ][Info ] Stop
[13.58.51.388][PowerShell ][Info ] Run script with parameters: -Stop True…
[13:58:51.679][Updater ][Info ] Local build 5193 is as good as the remote 5022 on channel Beta
[13.58.53.366][HyperV ][Info ] Stopping VM MobyLinuxVM…
[13.59.00.929][ProxyProcess ][Info ]
[13.59.00.930][ProxyProcess ][Info ] +000us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.ECONNABORTED, “read”, “”): no more requests will be handled
[13.59.00.931][ProxyProcess ][Error ] com.docker.slirp.exe: Hvsock.read raised Unix.Unix_error(Unix.ECONNABORTED, “read”, “”): returning `Eof
[13.59.00.932][ProxyProcess ][Debug ] com.docker.slirp.exe: S error reading: Caught EOF on underlying FLOW
[13.59.00.932][ProxyProcess ][Debug ] com.docker.slirp.exe: Disconnecting client
[13.59.01.323][HyperV ][Info ] Turn Off VM MobyLinuxVM…
[13.59.01.345][HyperV ][Info ] VM MobyLinuxVM is stopped
[13.59.01.352][ProxyProcess ][Info ] Killing existing process with PID 11652
[13.59.01.369][ProxyProcess ][Info ] Killing existing process with PID 7500
[13.59.01.382][ProxyProcess ][Info ] Killing existing process with PID 6532
[13.59.01.389][NamedPipeServer][Info ] Stop done.
[13.59.01.390][NamedPipeClient][Info ] Received response for Stop
[13.59.01.413][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)…
[13.59.01.421][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[13.59.01.423][HyperVGuids ][Info ] Installing GUIDs…
[13.59.01.424][HyperVGuids ][Info ] GUIDs installed
[13.59.01.425][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe…
[13.59.04.304][Firewall ][Info ] Ports are opened
[13.59.04.319][HyperV ][Info ] Create
[13.59.04.319][PowerShell ][Info ] Run script with parameters: -Create True -SwitchSubnetAddress 10.0.75.0 -SwitchSubnetMaskSize 24 -CPUs 2 -Memory 2048 -IsoFile C:\Program Files\Docker\Docker\Resources\mobylinux.iso…
[13.59.05.813][HyperV ][Info ] Using existing Switch: DockerNAT
[13.59.06.768][HyperV ][Info ] Using existing Switch IP address
[13.59.06.799][HyperV ][Info ] Using existing: MobyLinuxVM
[13.59.07.239][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[13.59.07.249][ProxyProcess ][Info ] Starting…
[13.59.07.250][PowerShell ][Info ] Run script…
[13.59.08.133][ProxyProcess ][Info ] Started
[13.59.08.133][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[13.59.08.134][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[13.59.08.135][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[13.59.13.017][ProxyProcess ][Info ] Start com.docker.db.exe --git C:\ProgramData\Docker\database --url hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800,\.\pipe\dockerDataBase --color never
[13.59.13.321][ProxyProcess ][Info ] Start com.docker.slirp.exe --ethernet hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800 --dns 10.0.75.1 --port hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800 --debug
[13.59.13.354][ProxyProcess ][Info ]
[13.59.13.355][ProxyProcess ][Info ] +000us Datakit Starting com.docker.db…
[13.59.13.629][ProxyProcess ][Info ] docker proxy (on deprecated port): ready
[13.59.13.637][ProxyProcess ][Info ] dns server: ready
[13.59.13.638][ProxyProcess ][Info ] poison pill com.docker.service
[13.59.13.650][ProxyProcess ][Info ] docker proxy: ready
[13.59.13.671][ProxyProcess ][Info ] com.docker.slirp.exe: using DNS forwarder on 10.0.75.1:53
[13.59.13.672][ProxyProcess ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800
[13.59.13.673][ProxyProcess ][Info ] com.docker.slirp.exe: connecting to 3eefd10d-4c5f-4c7b-b793-78be48be0800:0B95756A-9985-48AD-9470-78E060895BE7
[13.59.13.673][ProxyProcess ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800
[13.59.13.674][ProxyProcess ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[13.59.13.674][ProxyProcess ][Info ] com.docker.slirp.exe: connecting to 3eefd10d-4c5f-4c7b-b793-78be48be0800:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[13.59.13.675][ProxyProcess ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://3eefd10d-4c5f-4c7b-b793-78be48be0800
[13.59.13.840][Database ][Info ] Successfully removed 3 value(s)
[13.59.13.841][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[13.59.13.840][ProxyProcess ][Info ]
[13.59.13.858][ProxyProcess ][Info ] +000us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, “bytes_read”, “”): no more requests will be handled
[13.59.13.857][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[13.59.13.861][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[13.59.13.862][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[13.59.13.862][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[13.59.13.863][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[13.59.13.863][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={“registry-mirrors”:[],“insecure-registries”:[],“debug”:false}
[13.59.13.864][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[13.59.13.864][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[13.59.14.678][ProxyProcess ][Info ]
[13.59.14.679][ProxyProcess ][Info ] +000us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, “bytes_read”, “”): no more requests will be handled
[13.59.14.681][Database ][Info ] Successfully wrote 9 value(s)
[13.59.14.682][HyperV ][Info ] Start
[13.59.14.683][PowerShell ][Info ] Run script with parameters: -Start True…
[13.59.15.887][HyperV ][Info ] Starting VM MobyLinuxVM…
[13.59.16.975][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.17.997][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.19.382][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.20.560][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.23.405][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.24.681][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.25.865][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.27.931][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.30.544][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.31.636][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.32.789][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.33.971][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.35.000][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.36.829][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.37.972][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.39.205][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.40.281][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.41.315][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.42.598][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.43.783][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.44.845][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.45.634][ProxyProcess ][Info ] com.docker.slirp.exe: hvsock connected successfully
[13.59.45.654][ProxyProcess ][Info ] com.docker.slirp.exe: PPP.negotiate: received ((magic VMN3T)(version 13)(commit"\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000"))
[13.59.45.669][ProxyProcess ][Info ] com.docker.slirp.exe: PPP.negotiate: received (Ethernet"\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000")
[13.59.45.670][ProxyProcess ][Info ] com.docker.slirp.exe: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee))
[13.59.45.671][ProxyProcess ][Debug ] com.docker.slirp.exe: accepted vmnet connection
[13.59.45.671][ProxyProcess ][Debug ] com.docker.slirp.exe: Disabling any active packet capture
[13.59.45.671][ProxyProcess ][Info ] com.docker.slirp.exe: PPP.listen: called a second time: doing nothing
[13.59.45.672][ProxyProcess ][Info ] com.docker.slirp.exe: TCP/IP ready
[13.59.45.672][ProxyProcess ][Info ] com.docker.slirp.exe: stack connected
[13.59.45.740][ProxyProcess ][Debug ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 308
[13.59.45.741][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[13.59.45.741][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREQUEST from 5a:1e:1c:bd:8b:73
[13.59.45.742][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREPLY to 5a:1e:1c:bd:8b:73 yiddr 192.168.65.4 siddr 192.168.65.1 dns 192.168.65.1 router 192.168.65.1 domain local
[13.59.45.742][ProxyProcess ][Debug ] com.docker.slirp.exe: lease database updated
[13.59.45.743][ProxyProcess ][Debug ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 308
[13.59.45.743][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[13.59.45.744][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREQUEST from 5a:1e:1c:bd:8b:73
[13.59.45.744][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREPLY to 5a:1e:1c:bd:8b:73 yiddr 192.168.65.4 siddr 192.168.65.1 dns 192.168.65.1 router 192.168.65.1 domain local
[13.59.45.745][ProxyProcess ][Debug ] com.docker.slirp.exe: lease database updated
[13.59.45.797][ProxyProcess ][Debug ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 308
[13.59.45.798][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[13.59.45.799][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREQUEST from c0:ff:ee:c0:ff:ee
[13.59.45.800][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREPLY to ff:ff:ff:ff:ff:ff yiddr 0.0.0.0 siddr 0.0.0.0 dns none router none domain unknown
[13.59.45.801][ProxyProcess ][Debug ] com.docker.slirp.exe: lease database updated
[13.59.45.861][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.46.882][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.48.108][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.48.916][ProxyProcess ][Debug ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 308
[13.59.48.917][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[13.59.48.918][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREQUEST from c0:ff:ee:c0:ff:ee
[13.59.48.918][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREPLY to c0:ff:ee:c0:ff:ee yiddr 192.168.65.2 siddr 192.168.65.1 dns 192.168.65.1 router 192.168.65.1 domain local
[13.59.48.919][ProxyProcess ][Debug ] com.docker.slirp.exe: lease database updated
[13.59.48.956][ProxyProcess ][Debug ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 308
[13.59.48.957][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[13.59.48.957][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREQUEST from c0:ff:ee:c0:ff:ee
[13.59.48.958][ProxyProcess ][Debug ] com.docker.slirp.exe: BOOTREPLY to c0:ff:ee:c0:ff:ee yiddr 192.168.65.2 siddr 192.168.65.1 dns 192.168.65.1 router 192.168.65.1 domain local
[13.59.48.958][ProxyProcess ][Debug ] com.docker.slirp.exe: lease database updated
[13.59.49.198][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.50.282][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.51.305][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.51.858][ProxyProcess ][Debug ] com.docker.slirp.exe: ARP responding to: who-has 192.168.65.1?
[13.59.51.860][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[2e3a] 192.168.65.2:51346 -> 192.168.65.1 2e3a Query:0 na:c:r:rn 0 <qs:moby.local. <A|IN>> an: au: ad:
[13.59.51.861][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[2e3a] Forwarding to 10.0.75.1
[13.59.51.861][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 192.168.65.2:51346-10.0.75.1:53: creating UDP NAT rule
[13.59.51.861][ProxyProcess ][Info ] Handle DNS query ;; opcode: QUERY, status: NOERROR, id: 11834 ;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: ;moby.local. IN A
[13.59.51.863][ProxyProcess ][Info ] Search for dns server…
[13.59.52.025][ProxyProcess ][Info ] Server: google-public-dns-a.google.com
[13.59.52.026][ProxyProcess ][Info ] Address: 8.8.8.8
[13.59.52.027][ProxyProcess ][Info ]
[13.59.52.027][ProxyProcess ][Info ]
[13.59.52.028][ProxyProcess ][Info ] Found dns server 8.8.8.8
[13.59.52.029][ProxyProcess ][Info ] Forward DNS query on udp to 8.8.8.8:53
[13.59.52.058][ProxyProcess ][Info ] Got response ;; opcode: QUERY, status: NXDOMAIN, id: 11834 ;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0 ;; QUESTION SECTION: ;moby.local. IN A ;; AUTHORITY SECTION: . 1796 IN SOA a.root-servers.net. nstld.verisign-grs.com. 2016070700 1800 900 604800 86400
[13.59.52.058][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[2e3a] 192.168.65.2:51346 <- 192.168.65.1 2e3a Response:0 na:c:r:ra 3 <qs:moby.local. <A|IN>> an: <au: <IN|1796> [SOA (a.root-servers.net,nstld.verisign-grs.com, 2016070700,1800,900,604800,86400)]> ad:
[13.59.52.060][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[ae9c] 192.168.65.2:38765 -> 192.168.65.1 ae9c Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> an: au: ad:
[13.59.52.061][ProxyProcess ][Info ] Handle DNS query ;; opcode: QUERY, status: NOERROR, id: 44700 ;; flags: rd; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: ;moby. IN A
[13.59.52.062][ProxyProcess ][Info ] Forward DNS query on udp to 8.8.8.8:53
[13.59.52.061][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[ae9c] Forwarding to 10.0.75.1
[13.59.52.063][ProxyProcess ][Debug ] com.docker.slirp.exe: Socket.Datagram.input 192.168.65.2:38765-10.0.75.1:53: creating UDP NAT rule
[13.59.52.093][ProxyProcess ][Debug ] com.docker.slirp.exe: DNS[ae9c] 192.168.65.2:38765 <- 192.168.65.1 ae9c Response:0 na:c:r:ra 3 <qs:moby. <A|IN>> an: <au: <IN|1798> [SOA (a.root-servers.net,nstld.verisign-grs.com, 2016070700,1800,900,604800,86400)]> ad:
[13.59.52.093][ProxyProcess ][Info ] Got response ;; opcode: QUERY, status: NXDOMAIN, id: 44700 ;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0 ;; QUESTION SECTION: ;moby. IN A ;; AUTHORITY SECTION: . 1798 IN SOA a.root-servers.net. nstld.verisign-grs.com. 2016070700 1800 900 604800 86400
[13.59.52.330][HyperV ][Info ] Wait for the VM to get an IP address
[13.59.53.636][ProxyProcess ][Info ] com.docker.slirp.exe: hvsock connected successfully
[13.59.53.659][ProxyProcess ][Debug ] com.docker.slirp.exe: C ((tag ())
[13.59.53.660][ProxyProcess ][Info ] (payload
[13.59.53.661][ProxyProcess ][Info ] (Version
[13.59.53.661][ProxyProcess ][Info ] ((msize 8192) (version TwoThousand)))))
[13.59.53.662][ProxyProcess ][Debug ] com.docker.slirp.exe: S ((tag ())
[13.59.53.662][ProxyProcess ][Info ] (payload
[13.59.53.663][ProxyProcess ][Info ] (Version
[13.59.53.663][ProxyProcess ][Info ] ((msize 8192) (version TwoThousand)))))
[13.59.53.664][ProxyProcess ][Info ] com.docker.slirp.exe: Using protocol TwoThousand msize 8192
[13.59.53.681][ProxyProcess ][Debug ] com.docker.slirp.exe: C ((tag (1))
[13.59.53.682][ProxyProcess ][Info ] (payload
[13.59.53.683][ProxyProcess ][Info ] (Attach
[13.59.53.684][ProxyProcess ][Info ] ((fid 0) (afid -1) (uname nobody)
[13.59.53.684][ProxyProcess ][Info ] (aname “”) (n_uname ())))))
[13.59.53.684][ProxyProcess ][Debug ] com.docker.slirp.exe: S ((tag (1))
[13.59.53.684][ProxyProcess ][Info ] (payload
[13.59.53.684][ProxyProcess ][Info ] (Attach
[13.59.53.684][ProxyProcess ][Info ] ((qid
[13.59.53.688][ProxyProcess ][Info ] ((flags (Directory)) (version 0) (id 0)))))))
[13.59.53.688][ProxyProcess ][Info ] com.docker.slirp.exe: Client connected to 9P port control server
[13.59.53.688][ProxyProcess ][Debug ] com.docker.slirp.exe: C ((tag (1)) (payload (Stat ((fid 0)))))
[13.59.53.688][ProxyProcess ][Debug ] com.docker.slirp.exe: S ((tag (1))
[13.59.53.688][ProxyProcess ][Info ] (payload
[13.59.53.688][ProxyProcess ][Info ] (Stat
[13.59.53.688][ProxyProcess ][Info ] ((stat
[13.59.53.688][ProxyProcess ][Info ] ((ty 65535) (dev -1)
[13.59.53.692][ProxyProcess ][Info ] (qid ((flags ()) (version 0) (id 3)))
[13.59.53.692][ProxyProcess ][Info ] (mode
[13.59.53.693][ProxyProcess ][Info ] ((owner (Read Write Execute))
[13.59.53.693][ProxyProcess ][Info ] (group (Read Write Execute))
[13.59.53.694][ProxyProcess ][Info ] (other (Read Write Execute))
[13.59.53.694][ProxyProcess ][Info ] (is_directory true)
[13.59.53.695][ProxyProcess ][Info ] (append_only false) (exclusive false)
[13.59.53.696][ProxyProcess ][Info ] (is_mount false) (is_auth false)
[13.59.53.696][ProxyProcess ][Info ] (temporary false) (is_device false)
[13.59.53.697][ProxyProcess ][Info ] (is_symlink false)
[13.59.53.697][ProxyProcess ][Info ] (is_hardlink false)
[13.59.53.698][ProxyProcess ][Info ] (is_namedpipe false)
[13.59.53.698][ProxyProcess ][Info ] (is_socket false) (is_setuid false)
[13.59.53.699][ProxyProcess ][Info ] (is_setgid false) (is_any false)))
[13.59.53.702][ProxyProcess ][Info ] (atime 1146711721) (mtime 1146711721)
[13.59.53.702][ProxyProcess ][Info ] (length 0) (name “”) (uid uid)
[13.59.53.703][ProxyProcess ][Info ] (gid gid) (muid muid) (u ())))))))
[13.59.54.023][ProxyProcess ][Info ] proxy >> GET /v1.24/containers/json
[13.59.54.024][ProxyProcess ][Info ] Dial Hyper-V socket 3eefd10d-4c5f-4c7b-b793-78be48be0800:23a432c2-537a-4291-bcb5-d62504644739

SOLVED: Docker fails to start after upgrade from RC2 to RC3

Expected behavior

Actual behavior

Information

Docker ceased to initiate after latest update (R2 - R3). My logging looks like this: (My windows is localized Finnish)

Docker ceased to initiate after latest update (R2 - R3). My logging looks like
this: (My windows is localized Finnish)