Docker not able start under windows 10 version 1511 OS build 10586.420

szmulder · July 6, 2016, 3:41am

Expected behavior

Docker started

Actual behavior

[Database ][Error ] Unable to write to the database: Unable to remove from the database. Exit code: 1.

Information

host distribution and version: windows 10 version 1511 OS build 10586.420

Steps to reproduce the behavior

Install windows 10 on Virtualbox
Install Docker for windows

Log:
[20:22:38.064][Program ][Info ] 1.12.0-rc3-beta18
[20:22:38.064][Program ][Info ] Starting on: 7/5/2016 8:22:38 PM
[20:22:38.064][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[20:22:38.078][Program ][Info ] OS: Windows 10 Pro
[20:22:38.078][Program ][Info ] Edition: Professional
[20:22:38.078][Program ][Info ] Id: 1511
[20:22:38.078][Program ][Info ] Build: 10586
[20:22:38.092][Program ][Info ] BuildLabName: 10586.420.amd64fre.th2_release_sec.160527-1834
[20:22:38.092][Program ][Info ] Mixpanel Id: C61CD210-E9F7-4F01-A4FF-7132AB2584F4
[20:22:38.092][Program ][Info ] Sha1: 5cbabb0783b6d281b8f579cb9f4dff38373b2c10
[20:22:38.092][Program ][Info ] You can send feedback, including this log file, at https://forums.docker.com/c/docker-for-windows
[20:22:38.392][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[20:22:38.421][Mixpanel ][Info ] Usage Statistic: appLaunched
[20:22:38.978][NamedPipeClient][Info ] Sending Version()…
[20:22:38.991][NamedPipeClient][Info ] Received response for Version
[20:22:38.991][Mixpanel ][Info ] Usage Statistic: heartbeat
[20:22:36.908][BackendServer ][Info ] Started
[20:22:38.991][NamedPipeServer][Info ] Version()
[20:22:38.991][NamedPipeServer][Info ] Version done.
[20:22:39.220][Notifications ][Info ] Docker is starting…
[20:22:39.220][Updater ][Info ] Checking for updates on channel Beta…
[20:22:39.234][NamedPipeClient][Info ] Sending Stop()…
[20:22:39.234][NamedPipeServer][Info ] Stop()
[20:22:39.249][HyperV ][Info ] Stop
[20:22:39.249][PowerShell ][Info ] Run script with parameters: -Stop True…
[20:22:39.406][Updater ][Info ] Local build 5193 is as good as the remote 5193 on channel Beta
[20:22:42.992][HyperV ][Info ] VM MobyLinuxVM does not exist
[20:22:42.992][NamedPipeClient][Info ] Received response for Stop
[20:22:42.992][NamedPipeServer][Info ] Stop done.
[20:22:43.005][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)…
[20:22:43.005][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[20:22:43.005][HyperVGuids ][Info ] Installing GUIDs…
[20:22:43.018][HyperVGuids ][Info ] GUIDs installed
[20:22:43.018][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe…
[20:22:43.390][Firewall ][Info ] Ports are opened
[20:22:43.390][HyperV ][Info ] Create
[20:22:43.390][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…
[20:22:44.046][HyperV ][Info ] Enabled workaround for Build 10586 VMSwitch issue
[20:22:45.729][HyperV ][Info ] Creating Switch: DockerNAT…
[20:22:46.577][HyperV ][Info ] Switch created.
[20:22:47.155][HyperV ][Info ] Set IP address on switch
[20:22:48.196][HyperV ][Info ] VHD C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks\MobyLinuxVM.vhdx already exists
[20:22:48.285][HyperV ][Info ] Creating VM MobyLinuxVM…
[20:22:51.036][HyperV ][Info ] VM created.
[20:22:51.050][ProxyProcess ][Info ] Starting…
[20:22:51.050][PowerShell ][Info ] Run script…
[20:22:51.135][ProxyProcess ][Info ] Started
[20:22:51.148][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:22:51.148][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:22:51.163][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:22:51.191][ProxyProcess ][Info ] Start com.docker.db.exe --git C:\ProgramData\Docker\database --url hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f,\.\pipe\dockerDataBase --color never
[20:22:51.205][ProxyProcess ][Info ] Start com.docker.slirp.exe --ethernet hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f --dns 10.0.75.1 --port hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f --debug
[20:22:51.205][ProxyProcess ][Info ] docker proxy (on deprecated port): ready
[20:22:51.219][ProxyProcess ][Info ] dns server: ready
[20:22:51.233][ProxyProcess ][Info ] com.docker.slirp.exe: using DNS forwarder on 10.0.75.1:53
[20:22:51.248][ProxyProcess ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f
[20:22:51.261][ProxyProcess ][Info ] com.docker.slirp.exe: connecting to 77fb8ef8-d427-42c7-98a2-6ce97c49513f:0B95756A-9985-48AD-9470-78E060895BE7
[20:22:51.270][ProxyProcess ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f
[20:22:51.277][ProxyProcess ][Error ] com.docker.slirp.exe: start_port_server: caught Unix.Unix_error(Unix.ENETDOWN, “socket”, “”)
[20:22:51.285][ProxyProcess ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[20:22:51.292][ProxyProcess ][Info ] com.docker.slirp.exe: connecting to 77fb8ef8-d427-42c7-98a2-6ce97c49513f:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[20:22:51.298][ProxyProcess ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f
[20:22:51.308][ProxyProcess ][Info ] proxy: internal error, uncaught exception:
[20:22:51.316][ProxyProcess ][Info ] Unix.Unix_error(Unix.ENETDOWN, “socket”, “”)
[20:22:51.233][ProxyProcess ][Info ] poison pill com.docker.service
[20:22:51.329][ProxyProcess ][Info ]
[20:22:51.333][ProxyProcess ][Info ] +000us Datakit Starting com.docker.db…
[20:22:51.338][ProxyProcess ][Info ] WARNING: Io_page on Windows doesn’t guarantee alignment
[20:22:51.342][ProxyProcess ][Info ] docker proxy: ready
[20:22:51.322][ProxyProcess ][Info ] Raised by primitive operation at file “lwt/lwt_hvsock.ml”, line 101, characters 21-32
[20:22:51.350][ProxyProcess ][Info ] Called from file “src/main.ml”, line 45, characters 17-36
[20:22:51.357][ProxyProcess ][Info ] Called from file “src/main.ml”, line 139, characters 2-337
[20:22:51.361][ProxyProcess ][Info ] Called from file “src/main.ml”, line 156, characters 4-63
[20:22:51.366][ProxyProcess ][Info ] Failed to set up server socket listening on “hyperv-connect://77fb8ef8-d427-42c7-98a2-6ce97c49513f”: Unix.Unix_error(Unix.ENETDOWN, “socket”, “”)
[20:22:52.105][Database ][Error ] 2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:51 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:52 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:52 Failed to contact the database on \.\pipe\dockerDataBase: open \.\pipe\dockerDataBase: The system cannot find the file specified.

[20:22:52.109][Database ][Error ] Unable to write to the database: Unable to remove from the database. Exit code: 1. Let’s retry in 1s
[20:22:53.020][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:22:53.024][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:22:53.029][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:22:53.986][Database ][Error ] 2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:53 Failed to contact the database on \.\pipe\dockerDataBase: open \.\pipe\dockerDataBase: The system cannot find the file specified.

[20:22:53.992][Database ][Error ] Unable to write to the database: Unable to remove from the database. Exit code: 1. Let’s retry in 1s
[20:22:54.904][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:22:54.906][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:22:54.911][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:22:55.867][Database ][Error ] 2016/07/05 20:22:54 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:55 Failed to contact the database on \.\pipe\dockerDataBase: open \.\pipe\dockerDataBase: The system cannot find the file specified.

[20:22:55.871][Database ][Error ] Unable to write to the database: Unable to remove from the database. Exit code: 1. Let’s retry in 1s
[20:22:56.783][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:22:56.785][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:22:56.789][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:22:57.730][Database ][Error ] 2016/07/05 20:22:56 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:56 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:57 Failed to contact the database on \.\pipe\dockerDataBase: open \.\pipe\dockerDataBase: The system cannot find the file specified.

[20:22:57.734][Database ][Error ] Unable to write to the database: Unable to remove from the database. Exit code: 1. Let’s retry in 1s
[20:22:58.655][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:22:58.658][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:22:58.662][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:22:59.706][Database ][Error ] 2016/07/05 20:22:58 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:58 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:58 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Retry dialing \.\pipe\dockerDataBase in 100ms
2016/07/05 20:22:59 Failed to contact the database on \.\pipe\dockerDataBase: open \.\pipe\dockerDataBase: The system cannot find the file specified.

[20:22:59.713][NamedPipeServer][Error ] Unable to execute Start: Unable to remove from the database. Exit code: 1 at Docker.Backend.Database.RemoveKeys(IList`1 keys)
at Docker.Backend.Database.TryWrite(Settings settings)
at Docker.Backend.Database.Write(Settings settings)
at Docker.Backend.Backend.Start(Settings settings)
at Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass7_0.b__0(Object[] parameters)
at Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)
[20:22:59.723][NamedPipeClient][Error ] Unable to send Start: Unable to remove from the database. Exit code: 1
[20:22:59.732][NamedPipeClient][Info ] Sending CheckInstallation()…
[20:22:59.737][NamedPipeServer][Info ] CheckInstallation()
[20:22:59.744][HyperV ][Info ] Check Hyper-V Windows optional feature
[20:22:59.748][PowerShell ][Info ] Run script…
[20:23:00.881][HyperV ][Info ] Hyper-V Windows feature is enabled
[20:23:00.886][NamedPipeServer][Info ] CheckInstallation done.
[20:23:00.890][NamedPipeClient][Info ] Received response for CheckInstallation
[20:23:00.906][NamedPipeClient][Info ] Sending CheckHyperVState()…
[20:23:00.911][NamedPipeServer][Info ] CheckHyperVState()
[20:23:00.917][HyperV ][Info ] Check Hyper-V Service State
[20:23:00.923][PowerShell ][Info ] Run script with parameters: -Check True…
[20:23:01.639][HyperV ][Info ] Hyper-V is running and responding
[20:23:01.644][HyperV ][Info ] Hyper-V is running
[20:23:01.657][NamedPipeClient][Info ] Received response for CheckHyperVState
[20:23:01.649][NamedPipeServer][Info ] CheckHyperVState done.
[20:23:01.665][Notifications ][Error ] Unable to remove from the database. Exit code: 1
[20:23:01.686][NamedPipeClient][Info ] Sending GetDebugInfo()…
[20:23:01.709][NamedPipeServer][Info ] GetDebugInfo()
[20:23:09.288][ErrorReportWindow][Info ] Open logs

errsher · July 7, 2016, 2:21pm

Having the same problem, running Windows 10 Enterprise in Parallels from OS X.

Diagnostics id: DB7C9115-71CB-4821-8537-B4AD2E7DFD01/2016-07-07_10-04-42

gist: https://gist.github.com/Errsher/b5f4ac4435dda4dbe7b4dfaaed18efeb

jburnett · July 13, 2016, 5:57pm

I have the same issue as @Szmulder. Windows 10 v 1511 bld 10586.494. Installed Docker 1.12.0-rc3-beta18, bld 5226 & had it install hyper-v

errsher · July 13, 2016, 7:08pm

Using Parallels, was able to get this working once I enabled “nested virtualization”. Definitely looks like this is related to Hyper-V not working in virtualized environments.

http://kb.parallels.com/en/116239

aggieben · July 13, 2016, 7:31pm

Same. diagnostic id: FE7B67CD-21DA-447C-891E-C478E1AF4BF8/2016-07-13_14-21-01

jburnett · July 13, 2016, 7:46pm

Tried reinstalling Docker in the off chance that it would work when installing after Hyper-V config’d. Still get the same error, “Unable to remove from the database. Exit code:1.”

diagnostic id: 4AE19D0C-EA86-4D8F-81B8-511A118E0D6A/2016-07-13_15-22-13

gist: https://gist.github.com/jburnett/8a05b0741e34e724256be40218cc61ba

csabaharmath · July 15, 2016, 1:49am

did someone troubleshoot this already ? I have the same issue.

rneugeba · July 15, 2016, 7:41pm

Hi

thanks for reporting the issue. We unfortunately are unable to reproduce this issue locally (and we run this on quite a few machines). However, we made some changes to the failing database process in the latest Beta (released yesterday). It would be good to hear if the issue persists after the update.

Thanks
Rolf

aggieben · July 15, 2016, 9:20pm

The issue persists for me, although the error message is somewhat different:

I’m on build 5465.

leepa · July 16, 2016, 11:39am

This is seemingly caused by corruption of the DB for me.

jburnett · July 18, 2016, 6:23pm

This resolved our problem on Win10

aggieben · July 19, 2016, 8:41pm

I just updated to build 5579 and the daemon was able to start.

Now docker-compose fails

ERROR: Couldn't connect to Docker daemon. You might need to install docker

The docker command works, though.