Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries #54

Closed
cuikangyi opened this issue Aug 23, 2016 · 200 comments

Comments

@cuikangyi
Copy link

[20:58:41.979][DockerService ][Info ] Version: 1.12.0-stable
[20:58:41.987][DockerService ][Info ] Starting on: 8/23/2016 8:58:41 PM
[20:58:41.995][DockerService ][Info ] Sha1: 9cd1dc28d3ac81cb5177ff9728f65200304b36f6
[20:58:42.579][BackendServer ][Info ] Started
[20:58:44.013][NamedPipeServer][Info ] Version()
[20:58:44.019][NamedPipeServer][Info ] Version done.
[20:58:44.723][NamedPipeServer][Info ] Stop()
[20:58:44.731][HyperV ][Info ] Stop
[20:58:44.751][PowerShell ][Info ] Run script with parameters: -Stop True...
[20:58:48.989][HyperV ][Info ] VM MobyLinuxVM is stopped
[20:58:49.007][NamedPipeServer][Info ] Stop done.
[20:58:49.033][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[20:58:49.047][HyperVGuids ][Info ] Installing GUIDs...
[20:58:49.057][HyperVGuids ][Info ] GUIDs installed
[20:58:49.068][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[20:58:50.680][Firewall ][Info ] Ports are opened
[20:58:50.692][HyperV ][Info ] Create
[20:58:50.702][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:58:51.162][HyperV ][Info ] Using existing Switch: DockerNAT
[20:58:52.215][HyperV ][Info ] Using existing Switch IP address
[20:58:53.545][HyperV ][Info ] Using existing: MobyLinuxVM
[20:58:53.885][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[20:58:53.896][DataKit ][Info ] Starting...
[20:58:53.906][PowerShell ][Info ] Run script...
[20:58:53.987][DataKit ][Info ] Started
[20:58:53.998][VpnKit ][Info ] Starting...
[20:58:54.010][PowerShell ][Info ] Run script...
[20:58:54.038][DataKit ][Info ]
[20:58:54.046][DataKit ][Info ] +11649us Datakit Starting com.docker.db.exe 16bb469 ...
[20:58:55.074][VpnKit ][Info ] Started
[20:58:55.093][ApiProxy ][Info ] Starting...
[20:58:55.105][PowerShell ][Info ] Run script...
[20:58:56.013][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[20:58:56.028][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[20:58:56.034][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[20:58:56.042][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://09a8824a-e88e-4bb4-aa91-ee60054eb72a max_connections:None vsock_path:
[20:58:56.049][VpnKit ][Info ] com.docker.slirp.exe: connecting to 09a8824a-e88e-4bb4-aa91-ee60054eb72a:0B95756A-9985-48AD-9470-78E060895BE7
[20:58:56.057][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://09a8824a-e88e-4bb4-aa91-ee60054eb72a
[20:58:56.064][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[20:58:56.071][VpnKit ][Info ] com.docker.slirp.exe: connecting to 09a8824a-e88e-4bb4-aa91-ee60054eb72a:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[20:58:56.079][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://09a8824a-e88e-4bb4-aa91-ee60054eb72a
[20:58:57.059][ApiProxy ][Info ] Started
[20:58:57.073][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[20:58:57.081][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[20:58:57.087][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[20:58:57.135][ApiProxy ][Info ] docker proxy (on deprecated port): ready
[20:58:57.146][ApiProxy ][Info ] dns server: ready
[20:58:57.154][ApiProxy ][Info ] docker proxy: ready
[20:58:57.325][DataKit ][Info ]
[20:58:57.332][DataKit ][Info ] +3299821us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[20:58:59.012][Database ][Info ] Successfully removed 3 value(s)
[20:58:59.028][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[20:58:59.034][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[20:58:59.042][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[20:58:59.050][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[20:58:59.057][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[20:58:59.064][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[20:58:59.074][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[20:58:59.082][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[20:58:59.088][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[20:59:00.223][DataKit ][Info ]
[20:59:00.223][Database ][Info ] Successfully wrote 9 value(s)
[20:59:00.231][DataKit ][Info ] +6198121us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[20:59:00.238][HyperV ][Info ] Start
[20:59:00.253][PowerShell ][Info ] Run script with parameters: -Start True...
[20:59:00.353][HyperV ][Info ] Starting VM MobyLinuxVM...
[20:59:01.878][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:02.967][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:04.006][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:05.040][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:06.117][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:07.806][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:08.837][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:09.872][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:10.912][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:11.965][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:13.673][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:14.716][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:15.771][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:16.814][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:17.867][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:19.484][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:20.536][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:21.581][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:22.636][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:23.711][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:25.278][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:26.309][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:27.340][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:28.392][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:29.438][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:31.083][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:32.132][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:33.167][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:34.205][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:35.262][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:36.822][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:37.872][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:38.905][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:39.979][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:41.053][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:42.658][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:43.717][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:44.766][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:45.827][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:46.901][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:48.489][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:49.552][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:50.608][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:51.639][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:52.706][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:54.305][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:55.373][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:56.430][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:57.473][HyperV ][Info ] Wait for the VM to get an IP address
[20:59:58.553][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:00.120][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:01.176][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:02.225][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:03.273][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:04.340][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:05.941][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:07.007][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:08.039][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:09.074][HyperV ][Info ] Wait for the VM to get an IP address
[21:00:10.159][NamedPipeServer][Error ] Unable to execute Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries 在 System.Management.Automation.Runspaces.AsyncResult.EndInvoke()
在 System.Management.Automation.PowerShell.EndInvoke(IAsyncResult asyncResult)
在 Docker.Core.PowerShell.Run(String script, Dictionary2 parameters, Action1 lineReceived)
在 Docker.Backend.Backend.Start(Settings settings)
在 Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass7_0.b__0(Object[] parameters)
在 Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)
[21:00:10.203][NamedPipeServer][Info ] CheckInstallation()
[21:00:10.214][HyperV ][Info ] Check Hyper-V Windows optional feature
[21:00:10.225][PowerShell ][Info ] Run script...
[21:00:11.644][HyperV ][Info ] Hyper-V Windows feature is enabled
[21:00:11.654][NamedPipeServer][Info ] CheckInstallation done.
[21:00:11.702][NamedPipeServer][Info ] CheckHyperVState()
[21:00:11.714][HyperV ][Info ] Check Hyper-V State
[21:00:11.725][PowerShell ][Info ] Run script with parameters: -Check True...
[21:00:11.870][HyperV ][Info ] Hyper-V is running
[21:00:11.880][NamedPipeServer][Info ] CheckHyperVState done.

@cuikangyi cuikangyi changed the title unable to execute Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries Aug 23, 2016
@rn
Copy link
Contributor

rn commented Aug 23, 2016

Hi @cuikangyi. Thanks for the bug report. Could you upload and provide a diagnostic ID for us to take a closer look?
Thanks
rolf

@friism
Copy link

friism commented Aug 23, 2016

Also see #17

@cuikangyi
Copy link
Author

cuikangyi commented Aug 23, 2016

@rneugeba Thanks for your replay.
diagnostic ID
99C4A82F-9319-4FEC-84B7-788A8624E363/2016-08-24_07-00-22

and log
[06:57:38.273][Program ][Info ] Version: 1.12.0-stable
[06:57:38.273][Program ][Info ] Starting on: 8/24/2016 6:57:38 AM
[06:57:38.289][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[06:57:38.289][Program ][Info ] OS: Windows 10 Pro
[06:57:38.304][Program ][Info ] Edition: Professional
[06:57:38.304][Program ][Info ] Id: 1607
[06:57:38.320][Program ][Info ] Build: 14393
[06:57:38.320][Program ][Info ] BuildLabName: 14393.0.amd64fre.rs1_release.160715-1616
[06:57:38.336][Program ][Info ] Sha1: 9cd1dc28d3ac81cb5177ff9728f65200304b36f6
[06:57:38.351][Program ][Info ] You can send feedback, including this log file, at https://github.com/docker/for-win/issues
[06:57:39.523][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[06:57:39.570][Mixpanel ][Info ] Id: 99C4A82F-9319-4FEC-84B7-788A8624E363
[06:57:39.617][Mixpanel ][Info ] Usage Statistic: appLaunched
[06:57:41.551][NamedPipeClient][Info ] Sending Version()...
[06:57:41.691][NamedPipeClient][Info ] Received response for Version
[06:57:41.703][Mixpanel ][Info ] Usage Statistic: heartbeat
[06:57:13.984][BackendServer ][Info ] Started
[06:57:41.612][NamedPipeServer][Info ] Version()
[06:57:41.627][NamedPipeServer][Info ] Version done.
[06:57:43.504][NamedPipeClient][Info ] Sending Stop()...
[06:57:43.525][Updater ][Info ] Checking for updates on channel Stable...
[06:57:43.532][NamedPipeServer][Info ] Stop()
[06:57:43.556][HyperV ][Info ] Stop
[06:57:43.619][PowerShell ][Info ] Run script with parameters: -Stop True...
[06:57:44.970][Updater ][Info ] Local build 5968 is as good as the remote 5968 on channel Stable
[06:57:50.741][HyperV ][Info ] VM MobyLinuxVM is stopped
[06:57:50.795][NamedPipeClient][Info ] Received response for Stop
[06:57:50.788][NamedPipeServer][Info ] Stop done.
[06:57:50.802][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)...
[06:57:50.821][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[06:57:50.828][HyperVGuids ][Info ] Installing GUIDs...
[06:57:50.838][HyperVGuids ][Info ] GUIDs installed
[06:57:50.919][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[06:57:53.251][Firewall ][Info ] Ports are opened
[06:57:53.259][HyperV ][Info ] Create
[06:57:53.267][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...
[06:57:53.891][HyperV ][Info ] Using existing Switch: DockerNAT
[06:57:55.145][HyperV ][Info ] Using existing Switch IP address
[06:57:57.300][HyperV ][Info ] Using existing: MobyLinuxVM
[06:57:57.660][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[06:57:57.675][DataKit ][Info ] Starting...
[06:57:57.691][PowerShell ][Info ] Run script...
[06:57:58.003][DataKit ][Info ] Started
[06:57:58.019][VpnKit ][Info ] Starting...
[06:57:58.035][PowerShell ][Info ] Run script...
[06:57:58.113][DataKit ][Info ]
[06:57:58.113][DataKit ][Info ] +11925us Datakit Starting com.docker.db.exe 16bb469 ...
[06:57:58.566][VpnKit ][Info ] Started
[06:57:58.566][ApiProxy ][Info ] Starting...
[06:57:58.582][PowerShell ][Info ] Run script...
[06:57:59.254][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[06:57:59.254][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[06:57:59.254][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[06:57:59.269][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://15cedee8-7d59-4a48-9138-439e2190d58a max_connections:None vsock_path:
[06:57:59.269][VpnKit ][Info ] com.docker.slirp.exe: connecting to 15cedee8-7d59-4a48-9138-439e2190d58a:0B95756A-9985-48AD-9470-78E060895BE7
[06:57:59.285][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://15cedee8-7d59-4a48-9138-439e2190d58a
[06:57:59.285][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[06:57:59.300][VpnKit ][Info ] com.docker.slirp.exe: connecting to 15cedee8-7d59-4a48-9138-439e2190d58a:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[06:57:59.300][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://15cedee8-7d59-4a48-9138-439e2190d58a
[06:58:00.457][ApiProxy ][Info ] Started
[06:58:00.488][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[06:58:00.503][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[06:58:00.503][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[06:58:00.550][ApiProxy ][Info ] docker proxy (on deprecated port): ready
[06:58:00.589][ApiProxy ][Info ] dns server: ready
[06:58:00.600][ApiProxy ][Info ] docker proxy: ready
[06:58:00.918][DataKit ][Info ]
[06:58:00.925][DataKit ][Info ] +2819025us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[06:58:02.750][Database ][Info ] Successfully removed 3 value(s)
[06:58:02.759][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[06:58:02.766][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[06:58:02.773][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[06:58:02.780][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[06:58:02.787][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[06:58:02.794][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[06:58:02.800][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[06:58:02.807][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[06:58:02.813][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[06:58:03.971][DataKit ][Info ]
[06:58:03.973][Database ][Info ] Successfully wrote 9 value(s)
[06:58:03.979][DataKit ][Info ] +5873052us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[06:58:03.987][HyperV ][Info ] Start
[06:58:04.001][PowerShell ][Info ] Run script with parameters: -Start True...
[06:58:04.113][HyperV ][Info ] Starting VM MobyLinuxVM...
[06:58:05.857][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:06.949][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:07.999][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:09.038][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:10.103][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:11.734][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:12.770][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:13.812][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:14.852][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:15.905][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:17.719][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:18.753][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:19.786][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:20.820][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:21.870][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:23.679][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:24.725][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:25.769][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:26.822][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:27.873][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:29.460][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:30.494][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:31.531][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:32.566][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:33.629][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:35.327][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:36.360][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:37.396][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:38.432][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:39.483][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:41.138][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:42.197][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:43.238][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:44.278][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:45.357][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:47.087][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:48.126][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:49.171][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:50.210][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:51.282][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:53.064][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:54.121][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:55.158][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:56.202][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:57.258][HyperV ][Info ] Wait for the VM to get an IP address
[06:58:58.998][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:00.061][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:01.111][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:02.167][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:03.226][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:04.931][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:05.989][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:07.045][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:08.087][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:09.146][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:10.854][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:11.890][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:12.921][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:13.958][HyperV ][Info ] Wait for the VM to get an IP address
[06:59:15.095][NamedPipeServer][Error ] Unable to execute Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries 在 System.Management.Automation.Runspaces.AsyncResult.EndInvoke()
在 System.Management.Automation.PowerShell.EndInvoke(IAsyncResult asyncResult)
在 Docker.Core.PowerShell.Run(String script, Dictionary2 parameters, Action1 lineReceived)
在 Docker.Backend.Backend.Start(Settings settings)
在 Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass7_0.b__0(Object[] parameters)
在 Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)
[06:59:15.139][NamedPipeClient][Error ] Unable to send Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
[06:59:15.153][NamedPipeClient][Info ] Sending CheckInstallation()...
[06:59:15.168][NamedPipeServer][Info ] CheckInstallation()
[06:59:15.184][HyperV ][Info ] Check Hyper-V Windows optional feature
[06:59:15.195][PowerShell ][Info ] Run script...
[06:59:17.322][HyperV ][Info ] Hyper-V Windows feature is enabled
[06:59:17.333][NamedPipeServer][Info ] CheckInstallation done.
[06:59:17.363][NamedPipeClient][Info ] Received response for CheckInstallation
[06:59:17.398][NamedPipeClient][Info ] Sending CheckHyperVState()...
[06:59:17.416][NamedPipeServer][Info ] CheckHyperVState()
[06:59:17.435][HyperV ][Info ] Check Hyper-V State
[06:59:17.450][PowerShell ][Info ] Run script with parameters: -Check True...
[06:59:18.343][HyperV ][Info ] Hyper-V is running
[06:59:18.364][NamedPipeClient][Info ] Received response for CheckHyperVState
[06:59:18.353][NamedPipeServer][Info ] CheckHyperVState done.
[06:59:18.378][Notifications ][Error ] Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
[06:59:50.683][FeedbackSettings][Info ] Open logs
[06:59:56.093][FeedbackSettings][Info ] Upload diagnostic
[06:59:56.105][NamedPipeClient][Info ] Sending GetDebugInfo()...
[06:59:56.115][NamedPipeServer][Info ] GetDebugInfo()
[07:00:16.648][NamedPipeServer][Info ] GetDebugInfo done.
[07:00:16.667][NamedPipeClient][Info ] Received response for GetDebugInfo
[07:00:16.757][Program ][Info ] Sending Bugsnag report 3af2f647-b500-4560-829c-d11685e82718...
[07:00:22.702][Program ][Info ] Bugsnag report 3af2f647-b500-4560-829c-d11685e82718 sent
[07:00:22.763][NamedPipeClient][Info ] Sending DownloadVmLogs()...
[07:00:22.775][NamedPipeServer][Info ] DownloadVmLogs()
[07:00:22.788][PowerShell ][Info ] Run script...
[07:00:25.511][FeedbackSettings][Info ] Open logs
[07:00:53.091][HyperV ][Warning] Unable to download logs: 2016/08/24 07:00:53 Failed to Dial 15cedee8-7d59-4a48-9138-439e2190d58a 445ba2cb-e69b-4912-8b42-d7f494d007ea : A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.

[07:00:53.112][NamedPipeClient][Info ] Received response for DownloadVmLogs
[07:00:53.104][NamedPipeServer][Info ] DownloadVmLogs done.
[07:02:26.088][FeedbackSettings][Info ] Open logs

@tonercart
Copy link

I have the same issue.

Diagnostic loaded E29FCE66-DA9C-40C9-85CC-AD63831ADA03/2016-08-25_10-23-51

@sstodd7532
Copy link

Would really like to see this get resolved. I have been getting this for a few days and have not been able to get it working after a few reinstalls. Its also on 2 different computers I use.

A diagnostic was uploaded with id: 25FF9A46-6991-4935-885B-AE50E0FB18AC/2016-08-28_13-10-19

[18:20:27.179][NamedPipeClient][Error ] Unable to send Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
[18:20:27.186][NamedPipeClient][Info ] Sending CheckInstallation()...
[18:20:27.193][NamedPipeServer][Info ] CheckInstallation()
[18:20:27.199][HyperV ][Info ] Check Hyper-V Windows optional feature
[18:20:27.204][PowerShell ][Info ] Run script...
[18:21:07.460][HyperV ][Info ] Hyper-V Windows feature is enabled
[18:21:08.205][NamedPipeClient][Info ] Received response for CheckInstallation
[18:21:08.169][NamedPipeServer][Info ] CheckInstallation done.
[18:21:08.245][NamedPipeClient][Info ] Sending CheckHyperVState()...
[18:21:08.587][NamedPipeServer][Info ] CheckHyperVState()
[18:21:08.636][HyperV ][Info ] Check Hyper-V State
[18:21:08.735][PowerShell ][Info ] Run script with parameters: -Check True...
[18:21:09.371][HyperV ][Info ] Hyper-V is running
[18:21:09.421][NamedPipeClient][Info ] Received response for CheckHyperVState
[18:21:09.427][Notifications ][Error ] Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
[18:21:09.415][NamedPipeServer][Info ] CheckHyperVState done.
[11:25:55.312][Mixpanel ][Info ] Usage Statistic: heartbeat
[11:25:55.371][Mixpanel ][Info ] Failed to upload an event and will retry later
[12:06:33.036][Notifications ][Info ] Docker is restarting...
[12:06:33.118][NamedPipeClient][Info ] Sending Stop()...
[12:06:33.125][NamedPipeServer][Info ] Stop()
[12:06:33.130][HyperV ][Info ] Stop
[12:06:33.135][PowerShell ][Info ] Run script with parameters: -Stop True...
[12:06:34.196][HyperV ][Info ] Stopping VM MobyLinuxVM...
[12:06:57.354][HyperV ][Info ] Kill VM MobyLinuxVM process...
[12:06:58.560][HyperV ][Info ] Kill VM MobyLinuxVM process...
[12:06:59.582][HyperV ][Info ] VM MobyLinuxVM is stopped
[12:06:59.594][ApiProxy ][Info ] Killing existing com.docker.proxy with PID 8992
[12:06:59.603][VpnKit ][Info ] Killing existing com.docker.slirp with PID 9228
[12:06:59.612][DataKit ][Info ] Killing existing com.docker.db with PID 9976
[12:06:59.626][NamedPipeClient][Info ] Received response for Stop
[12:06:59.632][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)...
[12:06:59.618][NamedPipeServer][Info ] Stop done.
[12:06:59.646][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[12:06:59.651][HyperVGuids ][Info ] Installing GUIDs...
[12:06:59.655][HyperVGuids ][Info ] GUIDs installed
[12:06:59.660][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[12:07:01.324][Firewall ][Info ] Ports are opened
[12:07:01.330][HyperV ][Info ] Create
[12:07:01.336][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...
[12:07:01.739][HyperV ][Info ] Using existing Switch: DockerNAT
[12:07:02.107][HyperV ][Info ] Using existing Switch IP address
[12:07:03.365][HyperV ][Info ] Using existing: MobyLinuxVM
[12:07:03.681][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[12:07:03.690][DataKit ][Info ] Starting...
[12:07:03.695][PowerShell ][Info ] Run script...
[12:07:03.721][DataKit ][Info ] Started
[12:07:03.732][VpnKit ][Info ] Starting...
[12:07:03.737][PowerShell ][Info ] Run script...
[12:07:03.751][DataKit ][Info ]
[12:07:03.758][DataKit ][Info ] +6340us Datakit Starting com.docker.db.exe 16bb469 ...
[12:07:03.763][VpnKit ][Info ] Started
[12:07:03.771][ApiProxy ][Info ] Starting...
[12:07:03.776][PowerShell ][Info ] Run script...
[12:07:03.794][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[12:07:03.801][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[12:07:03.807][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[12:07:03.812][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://e2ed945c-1804-46c4-84b5-73195a72963e max_connections:None vsock_path:
[12:07:03.817][VpnKit ][Info ] com.docker.slirp.exe: connecting to e2ed945c-1804-46c4-84b5-73195a72963e:0B95756A-9985-48AD-9470-78E060895BE7
[12:07:03.822][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://e2ed945c-1804-46c4-84b5-73195a72963e
[12:07:03.828][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[12:07:03.833][VpnKit ][Info ] com.docker.slirp.exe: connecting to e2ed945c-1804-46c4-84b5-73195a72963e:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[12:07:03.838][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://e2ed945c-1804-46c4-84b5-73195a72963e
[12:07:03.808][ApiProxy ][Info ] Started
[12:07:03.847][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/exclude
[12:07:03.851][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/http
[12:07:03.855][Database ][Info ] Removing com.docker.driver.amd64-linux/proxy/https
[12:07:03.854][ApiProxy ][Info ] docker proxy (on deprecated port): ready
[12:07:03.865][ApiProxy ][Info ] dns server: ready
[12:07:03.870][ApiProxy ][Info ] docker proxy: ready
[12:07:04.086][DataKit ][Info ]
[12:07:04.090][DataKit ][Info ] +340814us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:04.087][Database ][Info ] Successfully removed 3 value(s)
[12:07:04.100][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[12:07:04.104][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[12:07:04.108][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[12:07:04.112][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[12:07:04.116][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[12:07:04.120][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[12:07:04.124][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[12:07:04.129][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[12:07:04.133][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[12:07:04.973][DataKit ][Info ]
[12:07:04.978][DataKit ][Info ] +1228543us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:04.975][Database ][Info ] Successfully wrote 9 value(s)
[12:07:04.988][HyperV ][Info ] Start
[12:07:04.992][PowerShell ][Info ] Run script with parameters: -Start True...
[12:07:05.058][HyperV ][Info ] Starting VM MobyLinuxVM...
[12:07:10.678][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:11.697][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:12.716][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:13.734][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:14.754][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:16.080][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:17.122][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:18.167][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:19.186][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:20.207][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:21.526][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:22.544][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:23.563][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:24.588][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:25.605][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:26.940][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:27.959][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:28.976][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:30.022][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:31.042][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:32.410][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:33.427][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:34.448][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:35.464][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:36.481][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:37.816][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:38.832][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:39.869][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:40.888][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:41.907][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:43.256][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:44.274][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:45.292][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:46.310][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:47.328][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:48.649][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:49.666][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:50.684][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:51.702][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:52.719][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:54.025][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:55.043][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:56.058][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:57.085][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:58.109][HyperV ][Info ] Wait for the VM to get an IP address
[12:07:59.413][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:00.431][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:01.449][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:02.466][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:03.484][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:04.812][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:05.828][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:06.845][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:07.862][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:08.881][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:10.202][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:11.222][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:12.242][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:13.264][HyperV ][Info ] Wait for the VM to get an IP address
[12:08:14.285][NamedPipeServer][Error ] Unable to execute Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries at System.Management.Automation.Runspaces.AsyncResult.EndInvoke()
at System.Management.Automation.PowerShell.EndInvoke(IAsyncResult asyncResult)
at Docker.Core.PowerShell.Run(String script, Dictionary2 parameters, Action1 lineReceived)
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)
[12:08:14.292][NamedPipeClient][Error ] Unable to send Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
[12:08:14.301][Notifications ][Error ] Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries

@rn
Copy link
Contributor

rn commented Aug 29, 2016

@runner7532 thanks for the report. Could you try the latest beta from https://download.docker.com/win/beta/InstallDocker.msi? It looks like in your case the Linux VM hasn't fully started and we made some omprovements in that area.

@sstodd7532
Copy link

Here is the Diagnostic ID for the beta version that I just installed
6D84E753-2F42-45E0-BE49-D065DFF0E8F1/2016-08-29_22-58-26

If you want the log I will get that as well.

@Irieish
Copy link

Irieish commented Sep 1, 2016

Seem to be dealing w/ a similar issue, but am experiencing a Fatal error:

Unable to execute Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322 at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.LinuxContainersEngine.DoStart(Settings settings)
at Docker.Backend.LinuxContainersEngine.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)

Version: 1.12.1-beta24 + latest Windows 10 update - build 14393 (1607 - KB3176938 8/31/16).

Diagnostic ID - 6F013C72-7104-4907-BC8B-D7F1D42EE211/2016-08-31_20-09-12

Hyper-V Virtual Ethernet Adapter keeps creating "vEthernet (DockerNAT) 2" which Network Connections shows as an "unidentified network" with "no network access".

NetworkInterfaceView shows vEthernet (DockerNAT) 2 as pointing to 10.0.75.1

Hyper-V Manager shows MobyLinuxVM as running, but the Network Adapter it's referencing is DockerNAT - which it lists as having "no communication". Not sure where the original DockerNAT vEthernet adapter is hiding, and causing the creation of vEthernet (DockerNAT) 2"

[19:47:54.364][Program ][Info ] Version: 1.12.1-beta24
[19:47:54.365][Program ][Info ] Starting on: 8/31/2016 7:47:54 PM
[19:47:54.365][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[19:47:54.366][Program ][Info ] OS: Windows 10 Pro
[19:47:54.366][Program ][Info ] Edition: Professional
[19:47:54.366][Program ][Info ] Id: 1607
[19:47:54.367][Program ][Info ] Build: 14393
[19:47:54.367][Program ][Info ] BuildLabName: 14393.103.amd64fre.rs1_release_inmarket.160819-1924
[19:47:54.368][Program ][Info ] Sha1: 7e2d389a5065149cffacbc889658405e3da67b6f
[19:47:54.368][Program ][Info ] You can send feedback, including this log file, at https://github.com/docker/for-win/issues
[19:47:54.815][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[19:47:54.837][Mixpanel ][Info ] Id: 6F013C72-7104-4907-BC8B-D7F1D42EE211
[19:47:54.848][DiskEventStorer][Info ] loaded 0 pending events
[19:47:54.867][Mixpanel ][Info ] Usage Statistic: appLaunched
[19:47:55.058][NamedPipeClient][Info ] Sending Version()...
[19:47:55.062][NamedPipeClient][Info ] Received response for Version
[19:47:55.065][Mixpanel ][Info ] Usage Statistic: heartbeat
[19:47:28.278][BackendServer ][Info ] Started
[19:47:55.061][NamedPipeServer][Info ] Version()
[19:47:55.061][NamedPipeServer][Info ] Version done.
[19:47:55.328][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)...
[19:47:55.330][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[19:47:55.331][Updater ][Info ] Checking for updates on channel Beta...
[19:47:55.331][HyperVGuids ][Info ] Installing GUIDs...
[19:47:55.338][HyperVGuids ][Info ] GUIDs installed
[19:47:55.341][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[19:47:55.465][Updater ][Info ] Local build 6563 is as good as the remote 6563 on channel Beta
[19:47:56.162][Firewall ][Info ] Ports are opened
[19:47:56.163][HyperV ][Info ] Create
[19:47:56.171][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...
[19:47:59.708][HyperV ][Info ] Using existing Switch: DockerNAT
[19:48:00.119][HyperV ][Info ] Using existing Switch IP address
[19:48:01.375][HyperV ][Info ] Using existing: MobyLinuxVM (state = Running)
[19:48:01.386][DataKit ][Info ] Starting...
[19:48:01.388][PowerShell ][Info ] Run script...
[19:48:01.535][DataKit ][Info ] Started
[19:48:01.537][VpnKit ][Info ] Starting...
[19:48:01.540][PowerShell ][Info ] Run script...
[19:48:01.575][DataKit ][Info ]
[19:48:01.576][DataKit ][Info ] +3673us Datakit Starting com.docker.db.exe 09081b4 ...
[19:48:01.838][VpnKit ][Info ] Started
[19:48:01.842][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[19:48:01.842][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[19:48:01.842][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[19:48:01.843][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[19:48:01.843][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[19:48:01.843][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[19:48:01.843][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[19:48:01.843][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[19:48:01.844][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[19:48:01.870][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[19:48:01.870][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[19:48:01.871][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[19:48:01.871][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c max_connections:None vsock_path:
[19:48:01.871][VpnKit ][Info ] com.docker.slirp.exe: connecting to 6f28e6f1-cb92-4c87-89df-15a3fbe5042c:0B95756A-9985-48AD-9470-78E060895BE7
[19:48:01.871][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c
[19:48:01.872][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[19:48:01.872][VpnKit ][Info ] com.docker.slirp.exe: connecting to 6f28e6f1-cb92-4c87-89df-15a3fbe5042c:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[19:48:01.872][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c
[19:48:03.150][DataKit ][Info ]
[19:48:03.150][DataKit ][Info ] +1580133us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[19:48:03.154][Database ][Info ] Successfully wrote 9 value(s)
[19:48:03.155][HyperV ][Info ] Start
[19:48:03.155][PowerShell ][Info ] Run script with parameters: -Start True...
[19:48:03.184][HyperV ][Info ] Starting VM MobyLinuxVM...
[19:48:03.362][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:04.376][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:05.388][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:06.406][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:07.417][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:08.564][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:09.576][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:10.589][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:11.606][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:12.618][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:13.747][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:14.758][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:15.770][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:16.788][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:17.800][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:18.935][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:19.946][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:20.957][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:21.974][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:22.986][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:24.118][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:25.130][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:26.143][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:27.159][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:28.171][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:29.308][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:30.319][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:31.331][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:32.348][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:33.359][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:34.486][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:35.497][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:36.509][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:37.527][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:38.539][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:39.667][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:40.677][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:41.689][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:42.706][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:43.718][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:44.844][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:45.855][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:46.868][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:47.885][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:48.897][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:50.024][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:51.051][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:52.077][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:53.115][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:54.128][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:55.293][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:56.308][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:57.334][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:58.371][HyperV ][Info ] Wait for the VM to get an IP address
[19:48:59.397][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:00.549][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:01.579][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:02.592][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:03.634][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:05.537][HyperV ][Info ] Hyper-V is running
[19:49:05.537][HyperV ][Info ] Create
[19:49:05.537][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...
[19:49:05.812][HyperV ][Info ] Using existing Switch: DockerNAT
[19:49:05.938][HyperV ][Info ] Using existing Switch IP address
[19:49:05.957][HyperV ][Info ] Using existing: MobyLinuxVM (state = Running)
[19:49:05.957][HyperV ][Info ] Stop
[19:49:05.958][PowerShell ][Info ] Run script with parameters: -Stop True...
[19:49:05.998][HyperV ][Info ] Stopping VM MobyLinuxVM...
[19:49:09.030][HyperV ][Info ] Turn Off VM ...
[19:49:09.081][HyperV ][Info ] Kill VM MobyLinuxVM process...
[19:49:10.101][HyperV ][Info ] Kill VM MobyLinuxVM process...
[19:49:11.116][HyperV ][Info ] VM MobyLinuxVM killed. Waiting for state to change
[19:49:11.127][HyperV ][Info ] Killed VM MobyLinuxVM is off
[19:49:11.137][VpnKit ][Info ] Killing existing com.docker.slirp with PID 11064
[19:49:11.142][DataKit ][Info ] Killing existing com.docker.db with PID 11188
[19:49:11.144][HyperVGuids ][Info ] Installing GUIDs...
[19:49:11.145][HyperVGuids ][Info ] GUIDs installed
[19:49:11.145][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[19:49:11.872][Firewall ][Info ] Ports are opened
[19:49:11.873][HyperV ][Info ] Create
[19:49:11.873][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...
[19:49:12.088][HyperV ][Info ] Using existing Switch: DockerNAT
[19:49:12.128][HyperV ][Info ] Using existing Switch IP address
[19:49:12.139][HyperV ][Info ] Using existing: MobyLinuxVM (state = Off)
[19:49:12.195][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[19:49:12.196][DataKit ][Info ] Starting...
[19:49:12.197][PowerShell ][Info ] Run script...
[19:49:12.286][DataKit ][Info ] Started
[19:49:12.288][VpnKit ][Info ] Starting...
[19:49:12.288][PowerShell ][Info ] Run script...
[19:49:12.308][DataKit ][Info ]
[19:49:12.309][DataKit ][Info ] +4464us Datakit Starting com.docker.db.exe 09081b4 ...
[19:49:12.391][VpnKit ][Info ] Started
[19:49:12.391][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[19:49:12.391][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[19:49:12.392][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[19:49:12.392][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[19:49:12.392][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[19:49:12.392][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[19:49:12.393][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[19:49:12.393][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[19:49:12.393][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[19:49:12.416][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[19:49:12.416][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[19:49:12.416][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[19:49:12.417][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c max_connections:None vsock_path:
[19:49:12.417][VpnKit ][Info ] com.docker.slirp.exe: connecting to 6f28e6f1-cb92-4c87-89df-15a3fbe5042c:0B95756A-9985-48AD-9470-78E060895BE7
[19:49:12.417][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c
[19:49:12.417][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[19:49:12.418][VpnKit ][Info ] com.docker.slirp.exe: connecting to 6f28e6f1-cb92-4c87-89df-15a3fbe5042c:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[19:49:12.418][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://6f28e6f1-cb92-4c87-89df-15a3fbe5042c
[19:49:13.524][DataKit ][Info ]
[19:49:13.524][DataKit ][Info ] +1219881us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[19:49:13.524][Database ][Info ] Successfully wrote 9 value(s)
[19:49:13.526][HyperV ][Info ] Start
[19:49:13.527][PowerShell ][Info ] Run script with parameters: -Start True...
[19:49:13.566][HyperV ][Info ] Starting VM MobyLinuxVM...
[19:49:14.009][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:15.026][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:16.039][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:17.066][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:18.091][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:19.348][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:20.365][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:21.377][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:22.388][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:23.404][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:24.609][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:25.626][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:26.638][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:27.651][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:28.663][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:29.876][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:30.900][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:31.913][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:32.935][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:33.948][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:35.156][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:36.180][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:37.207][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:38.230][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:39.256][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:40.490][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:41.526][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:42.552][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:43.577][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:44.597][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:45.812][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:46.829][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:47.856][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:48.881][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:49.906][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:51.118][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:52.138][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:53.165][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:54.190][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:55.201][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:56.407][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:57.448][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:58.474][HyperV ][Info ] Wait for the VM to get an IP address
[19:49:59.485][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:00.501][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:01.728][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:02.763][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:03.788][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:04.814][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:05.834][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:07.063][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:08.104][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:09.130][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:10.152][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:11.176][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:12.405][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:13.442][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:14.455][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:15.479][HyperV ][Info ] Wait for the VM to get an IP address
[19:50:16.497][NamedPipeServer][Error ] Unable to execute Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322 at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.LinuxContainersEngine.DoStart(Settings settings)
at Docker.Backend.LinuxContainersEngine.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)
[19:50:16.508][NamedPipeClient][Error ] Unable to send Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322
[19:50:16.509][Notifications ][Error ] Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322
[19:50:16.518][NamedPipeClient][Info ] Sending GetDebugInfo()...
[19:50:16.519][NamedPipeServer][Info ] GetDebugInfo()
[19:50:28.171][NamedPipeServer][Info ] GetDebugInfo done.
[19:50:28.175][NamedPipeClient][Info ] Received response for GetDebugInfo
[19:50:28.186][Program ][Info ] Sending Bugsnag report aeaa6317-72cb-499b-bf15-cfb91a7095bc...
[19:50:29.150][Program ][Info ] Bugsnag report aeaa6317-72cb-499b-bf15-cfb91a7095bc sent
[19:50:29.166][NamedPipeClient][Info ] Sending DownloadVmLogs()...
[19:50:29.167][NamedPipeServer][Info ] DownloadVmLogs()
[19:50:29.168][PowerShell ][Info ] Run script...
[19:50:59.256][HyperV ][Warning] Unable to download logs: 2016/08/31 19:50:59 Failed to Dial 6f28e6f1-cb92-4c87-89df-15a3fbe5042c 445ba2cb-e69b-4912-8b42-d7f494d007ea : A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.

[19:50:59.257][NamedPipeServer][Info ] DownloadVmLogs done.
[19:50:59.257][NamedPipeClient][Info ] Received response for DownloadVmLogs
[19:51:28.524][ErrorReportWindow][Info ] Open logs

@rn
Copy link
Contributor

rn commented Sep 1, 2016

Collating the reports from #17 into this issue: This was the report from @maxday:
Hi,
First, thanks for your work, I've seen a great demo at DevoxxFR and I can't wait to use it :)

I'm running Docker for Windows Version: 1.12.0-beta21 on a WIN10 VM.
Hyper-V is activated and Windows services are OK.
The MobyLinuxVM is well created but Docker fails to start because it cannot get an IP for the VM.

The DNS server radio button configuration is set to "automatic".
I've tried to reboot, reset to factory, uninstall/reinstall,
I've also tried to set the a fixed DNS.

Error :
Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries
at Docker.Core.Pipe.NamedPipeClient.TrySend(String action, Object[] parameters)
at Docker.Core.Pipe.NamedPipeClient.Send(String action, Object[] parameters)
at Docker.Core.Backend.BackendClient.Start(Settings settings)
at Docker.Actions.DoStart(Settings settings, Boolean showWelcomeWindow)
at Docker.Core.TaskQueue.<.ctor>b__16_0()

Diagnostic id :
5C73F6A7-06A7-40B5-A2F9-9EF5214B83F2/2016-08-02_09-22-49

@rn
Copy link
Contributor

rn commented Sep 1, 2016

Collating the reports from #17 into this issue: This was the report from @TaylorBarrick:
I have the problem describe above and here is a log file and diagnostic ID

A diagnostic was uploaded with id: F92E5AB4-A9AA-4269-A7D6-8FEB6F6E3D34/2016-08-01_22-30-16
Windows 10.0.14393

[22:26:32.349][ApiProxy ][Info ] dns server: ready
[22:26:32.353][ApiProxy ][Info ] docker proxy: ready
[22:26:32.356][ApiProxy ][Info ] listen tcp 10.0.75.1:53: bind: The requested address is not valid in its context.
[22:26:32.359][ApiProxy ][Info ] listen udp 10.0.75.1:53: bind: The requested address is not valid in its context.
[22:26:32.363][ApiProxy ][Info ] Waiting for the port/IP to become available
[22:26:32.367][ApiProxy ][Info ] Waiting for the port/IP to become available
[22:26:32.551][Database ][Info ] Successfully removed 3 value(s)
[22:26:32.555][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[22:26:32.553][DataKit ][Info ]
[22:26:32.562][DataKit ][Info ] +524521us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[22:26:32.559][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[22:26:32.570][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[22:26:32.574][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[22:26:32.579][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[22:26:32.583][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[22:26:32.586][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[22:26:32.591][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[22:26:32.595][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[22:26:33.537][Database ][Info ] Successfully wrote 9 value(s)
[22:26:33.541][HyperV ][Info ] Start
[22:26:33.547][PowerShell ][Info ] Run script with parameters: -Start True...
[22:26:33.537][DataKit ][Info ]
[22:26:33.561][DataKit ][Info ] +1508337us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[22:26:33.631][HyperV ][Info ] Starting VM MobyLinuxVM...
[22:26:34.140][HyperV ][Info ] Wait for the VM to get an IP address
[22:26:35.155][HyperV ][Info ] Wait for the VM to get an IP address
[22:26:36.174][HyperV ][Info ] Wait for the VM to get an IP address
[22:26:37.434][HyperV ][Info ] Wait for the VM to get an IP address
...
..
[22:27:37.803][NamedPipeServer][Error ] Unable to execute Start: Failed to start VM "MobyLinuxVM": The VM couldn't get an IP address after 60 tries at System.Management.Automation.Runspaces.AsyncResult.EndInvoke()
at System.Management.Automation.PowerShell.EndInvoke(IAsyncResult asyncResult)
at Docker.Core.PowerShell.Run(String script, Dictionary2 parameters, Action1 lineReceived)
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)

@rn
Copy link
Contributor

rn commented Sep 1, 2016

Collating the reports from #17 into this issue: This was the report from @kukjevov
I have same problem.

It looks like problem of Windows 10 Redstone 1 update (build 14393) with combination of latest stable docker version.

I have enabled windows features like "Containers", "Hyper-V" and "Windows subsystem for linux (beta)".
I have restarted computer. I tried to reinstall docker, reset to factory defaults.

Nothing helped.

And according this it looks like problem of Redstone 1. https://forums.docker.com/t/failed-to-start-vm-mobylinuxvm-the-vm-couldnt-get-an-ip-address-after-60-tries/19551/7

Have you solved this somehow ?
Thank you

@rn
Copy link
Contributor

rn commented Sep 1, 2016

I had a look at your logs and there seem to be a number of different issues:

  • @maxday: You are running Docker for Windows on Windows 10 Pro build 10586 (1511) in a VM. Unfortunately we do not support this configuration. Nested virtualisation is required for this and depending on your underlying hypervisor it may be broken in subtle ways. Nested virtualisation also changes the timing a lot depending on your host, so it's really difficult for us to cover and support these cases.
  • @tonercart You are also running build 10586 but your issue seems to be that the MobyLinuxVM takes a very long time too boot. I can see it booting just about when our code decides that it took to long. Are you using some oldish hardware for this?
  • @kukjevov could you supply a diagnostics ID?

As for @cuikangyi @runner7532 @Irieish @TaylorBarrick from the logs it appears like you all experience the same issue. The MobyLinuxVM boots fine, but then early during boot, it will try to contact the host via a Hyper-V technology called Hyper-V sockets but that connection never succeeds. I'm a little puzzled as to wy this is not working as I have several 14393 machines (and have tracked various insider builds over the past months) and have never seen this. Unfortunately, there is very little debugging support for this.

Could you try updating with the latest windows updates and un-install and install Beta24 again?

@Irieish
Copy link

Irieish commented Sep 1, 2016

Thanks rneugeba. Will post back after uninstall/reinstall of Beta24. Believe Windows is current (installed latest update released 8/31 before posting). Windows 10 Pro (Version 1607 / Build 14393.105).

@Irieish
Copy link

Irieish commented Sep 2, 2016

Same error after reinstall of Beta24.
Diagnostic ID: 6F013C72-7104-4907-BC8B-D7F1D42EE211/2016-09-01_18-05-55

Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322
at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.LinuxContainersEngine.DoStart(Settings settings)
at Docker.Backend.LinuxContainersEngine.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)

Error log follows:
[18:02:57.074][Program ][Info ] Version: 1.12.1-beta24
[18:02:57.076][Program ][Info ] Starting on: 9/1/2016 6:02:57 PM
[18:02:57.076][Program ][Info ] Resources: C:\Program Files\Docker\Docker\Resources
[18:02:57.077][Program ][Info ] OS: Windows 10 Pro
[18:02:57.077][Program ][Info ] Edition: Professional
[18:02:57.078][Program ][Info ] Id: 1607
[18:02:57.078][Program ][Info ] Build: 14393
[18:02:57.078][Program ][Info ] BuildLabName: 14393.103.amd64fre.rs1_release_inmarket.160819-1924
[18:02:57.079][Program ][Info ] Sha1: 7e2d389a5065149cffacbc889658405e3da67b6f
[18:02:57.081][Program ][Info ] You can send feedback, including this log file, at https://github.com/docker/for-win/issues
[18:02:57.504][TrackingStatus ][Info ] Crash report and usage statistics are enabled
[18:02:57.545][Mixpanel ][Info ] Id: 6F013C72-7104-4907-BC8B-D7F1D42EE211
[18:02:57.587][DiskEventStorer][Info ] loaded 0 pending events
[18:02:57.593][Mixpanel ][Info ] Usage Statistic: appLaunched
[18:02:57.800][NamedPipeClient][Info ] Sending Version()...
[18:02:57.807][NamedPipeClient][Info ] Received response for Version
[18:02:57.810][Mixpanel ][Info ] Usage Statistic: heartbeat
[18:02:53.966][BackendServer ][Info ] Started
[18:02:57.805][NamedPipeServer][Info ] Version()
[18:02:57.805][NamedPipeServer][Info ] Version done.
[18:02:58.335][Notifications ][Info ] Docker is starting...
[18:02:58.337][Updater ][Info ] Checking for updates on channel Beta...
[18:02:58.352][NamedPipeClient][Info ] Sending Start(Docker.Core.Settings)...
[18:02:58.355][NamedPipeServer][Info ] Start(Docker.Core.Settings)
[18:02:58.356][HyperVGuids ][Info ] Installing GUIDs...
[18:02:58.358][HyperVGuids ][Info ] GUIDs installed
[18:02:58.368][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[18:02:58.478][Updater ][Info ] Local build 6563 is as good as the remote 6563 on channel Beta
[18:02:59.306][Firewall ][Info ] Ports are opened
[18:02:59.307][HyperV ][Info ] Create
[18:02:59.313][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...
[18:03:02.276][HyperV ][Info ] Creating Switch: DockerNAT...
[18:03:04.261][HyperV ][Info ] Switch created.
[18:03:04.999][HyperV ][Info ] Set IP address on switch
[18:03:06.216][HyperV ][Info ] Creating dynamic VHD: C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks\MobyLinuxVM.vhdx
[18:03:07.326][HyperV ][Info ] Creating VM MobyLinuxVM...
[18:03:09.706][HyperV ][Info ] VM created.
[18:03:09.712][DataKit ][Info ] Starting...
[18:03:09.715][PowerShell ][Info ] Run script...
[18:03:10.191][DataKit ][Info ] Started
[18:03:10.195][VpnKit ][Info ] Starting...
[18:03:10.200][PowerShell ][Info ] Run script...
[18:03:10.233][DataKit ][Info ]
[18:03:10.233][DataKit ][Info ] +9112us Datakit Starting com.docker.db.exe 09081b4 ...
[18:03:10.554][VpnKit ][Info ] Started
[18:03:10.560][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[18:03:10.561][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[18:03:10.561][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[18:03:10.561][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[18:03:10.561][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[18:03:10.562][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[18:03:10.562][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[18:03:10.562][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[18:03:10.562][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[18:03:10.589][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[18:03:10.590][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[18:03:10.590][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[18:03:10.590][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962 max_connections:None vsock_path:
[18:03:10.591][VpnKit ][Info ] com.docker.slirp.exe: connecting to 089efca5-ea10-4f65-bbed-4ef3b9e79962:0B95756A-9985-48AD-9470-78E060895BE7
[18:03:10.591][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962
[18:03:10.592][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[18:03:10.592][VpnKit ][Info ] com.docker.slirp.exe: connecting to 089efca5-ea10-4f65-bbed-4ef3b9e79962:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[18:03:10.592][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962
[18:03:11.536][Database ][Info ] Successfully wrote 9 value(s)
[18:03:11.536][DataKit ][Info ]
[18:03:11.536][DataKit ][Info ] +1314311us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[18:03:11.537][HyperV ][Info ] Start
[18:03:11.537][PowerShell ][Info ] Run script with parameters: -Start True...
[18:03:11.574][HyperV ][Info ] Starting VM MobyLinuxVM...
[18:03:12.051][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:13.074][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:14.117][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:15.417][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:16.435][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:17.473][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:18.497][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:19.544][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:20.879][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:21.898][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:22.949][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:23.970][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:25.015][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:26.314][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:27.331][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:28.371][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:29.398][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:30.439][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:31.730][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:32.748][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:33.788][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:34.806][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:35.851][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:37.132][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:38.147][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:39.182][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:40.199][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:41.288][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:42.602][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:43.619][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:44.652][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:45.667][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:46.702][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:47.991][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:49.019][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:50.052][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:51.068][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:52.112][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:53.395][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:54.410][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:55.447][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:56.463][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:57.503][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:58.749][HyperV ][Info ] Wait for the VM to get an IP address
[18:03:59.763][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:00.796][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:01.811][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:02.849][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:04.072][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:05.088][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:06.120][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:07.136][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:08.183][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:09.411][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:10.426][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:11.456][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:12.473][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:13.510][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:14.735][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:16.714][HyperV ][Info ] Hyper-V is running
[18:04:16.715][HyperV ][Info ] Create
[18:04:16.716][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...
[18:04:17.081][HyperV ][Info ] Using existing Switch: DockerNAT
[18:04:17.143][HyperV ][Info ] Using existing Switch IP address
[18:04:17.179][HyperV ][Info ] Using existing: MobyLinuxVM (state = Running)
[18:04:17.183][HyperV ][Info ] Stop
[18:04:17.183][PowerShell ][Info ] Run script with parameters: -Stop True...
[18:04:17.229][HyperV ][Info ] Stopping VM MobyLinuxVM...
[18:04:18.296][HyperV ][Info ] Turn Off VM ...
[18:04:18.324][HyperV ][Info ] Kill VM MobyLinuxVM process...
[18:04:19.346][HyperV ][Info ] Kill VM MobyLinuxVM process...
[18:04:20.388][HyperV ][Info ] VM MobyLinuxVM killed. Waiting for state to change
[18:04:22.399][HyperV ][Info ] Killed VM MobyLinuxVM is off
[18:04:22.404][VpnKit ][Info ] Killing existing com.docker.slirp with PID 12144
[18:04:22.409][DataKit ][Info ] Killing existing com.docker.db with PID 12748
[18:04:22.411][HyperVGuids ][Info ] Installing GUIDs...
[18:04:22.412][HyperVGuids ][Info ] GUIDs installed
[18:04:22.412][Firewall ][Info ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[18:04:23.578][Firewall ][Info ] Ports are opened
[18:04:23.578][HyperV ][Info ] Create
[18:04:23.579][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...
[18:04:23.868][HyperV ][Info ] Using existing Switch: DockerNAT
[18:04:23.927][HyperV ][Info ] Using existing Switch IP address
[18:04:23.947][HyperV ][Info ] Using existing: MobyLinuxVM (state = Off)
[18:04:24.172][HyperV ][Info ] Setting MobyLinuxVM vm CPUs to 2 and Memory to 2048 MB
[18:04:24.175][DataKit ][Info ] Starting...
[18:04:24.175][PowerShell ][Info ] Run script...
[18:04:24.304][DataKit ][Info ] Started
[18:04:24.307][VpnKit ][Info ] Starting...
[18:04:24.307][PowerShell ][Info ] Run script...
[18:04:24.337][DataKit ][Info ]
[18:04:24.337][DataKit ][Info ] +6196us Datakit Starting com.docker.db.exe 09081b4 ...
[18:04:24.467][VpnKit ][Info ] Started
[18:04:24.468][Database ][Info ] Writing com.docker.driver.amd64-linux/net/config=static
[18:04:24.468][Database ][Info ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[18:04:24.468][Database ][Info ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[18:04:24.469][Database ][Info ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[18:04:24.469][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/resolv.conf=nameserver 10.0.75.1
[18:04:24.469][Database ][Info ] Writing com.docker.driver.amd64-linux/network=hybrid
[18:04:24.469][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[],"debug":false}
[18:04:24.470][Database ][Info ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[18:04:24.470][Database ][Info ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[18:04:24.498][VpnKit ][Info ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[18:04:24.498][VpnKit ][Info ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[18:04:24.499][VpnKit ][Info ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[18:04:24.499][VpnKit ][Info ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962 max_connections:None vsock_path:
[18:04:24.499][VpnKit ][Info ] com.docker.slirp.exe: connecting to 089efca5-ea10-4f65-bbed-4ef3b9e79962:0B95756A-9985-48AD-9470-78E060895BE7
[18:04:24.499][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962
[18:04:24.500][VpnKit ][Warning] com.docker.slirp.exe: no database: using hardcoded network configuration values
[18:04:24.500][VpnKit ][Info ] com.docker.slirp.exe: connecting to 089efca5-ea10-4f65-bbed-4ef3b9e79962:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[18:04:24.500][VpnKit ][Debug ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://089efca5-ea10-4f65-bbed-4ef3b9e79962
[18:04:25.651][Database ][Info ] Successfully wrote 9 value(s)
[18:04:25.652][HyperV ][Info ] Start
[18:04:25.652][PowerShell ][Info ] Run script with parameters: -Start True...
[18:04:25.653][DataKit ][Info ]
[18:04:25.653][DataKit ][Info ] +1322345us fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[18:04:25.680][HyperV ][Info ] Starting VM MobyLinuxVM...
[18:04:26.130][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:27.146][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:28.165][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:29.410][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:30.425][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:31.488][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:32.504][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:33.519][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:34.739][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:35.756][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:36.830][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:37.844][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:38.864][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:40.115][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:41.132][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:42.194][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:43.212][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:44.231][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:45.552][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:46.569][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:47.641][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:48.657][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:49.672][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:50.924][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:51.941][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:53.005][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:54.020][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:55.034][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:56.267][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:57.285][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:58.405][HyperV ][Info ] Wait for the VM to get an IP address
[18:04:59.421][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:00.437][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:01.653][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:02.668][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:03.729][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:04.745][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:05.761][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:07.005][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:08.020][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:09.077][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:10.092][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:11.109][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:12.377][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:13.394][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:14.512][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:15.529][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:16.548][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:17.829][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:18.847][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:19.919][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:20.937][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:21.955][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:23.223][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:24.240][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:25.310][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:26.328][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:27.346][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:28.600][HyperV ][Info ] Wait for the VM to get an IP address
[18:05:29.621][NamedPipeServer][Error ] Unable to execute Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322 at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.LinuxContainersEngine.DoStart(Settings settings)
at Docker.Backend.LinuxContainersEngine.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)
[18:05:29.637][NamedPipeClient][Error ] Unable to send Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322
[18:05:29.639][Notifications ][Error ] Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, : line 313
at Start-MobyLinuxVM, : line 233
at , : line 322
[18:05:29.651][NamedPipeClient][Info ] Sending GetDebugInfo()...
[18:05:29.652][NamedPipeServer][Info ] GetDebugInfo()
[18:05:54.993][NamedPipeServer][Info ] GetDebugInfo done.
[18:05:54.997][NamedPipeClient][Info ] Received response for GetDebugInfo
[18:05:55.007][Program ][Info ] Sending Bugsnag report dfdc4d77-776b-48b2-8ec9-ff88635fb7a5...
[18:05:55.997][Program ][Info ] Bugsnag report dfdc4d77-776b-48b2-8ec9-ff88635fb7a5 sent
[18:05:56.016][NamedPipeClient][Info ] Sending DownloadVmLogs()...
[18:05:56.017][NamedPipeServer][Info ] DownloadVmLogs()
[18:05:56.019][PowerShell ][Info ] Run script...
[18:06:26.317][HyperV ][Warning] Unable to download logs: 2016/09/01 18:06:26 Failed to Dial 089efca5-ea10-4f65-bbed-4ef3b9e79962 445ba2cb-e69b-4912-8b42-d7f494d007ea : A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond.

[18:06:26.317][NamedPipeServer][Info ] DownloadVmLogs done.
[18:06:26.317][NamedPipeClient][Info ] Received response for DownloadVmLogs
[18:07:16.008][ErrorReportWindow][Info ] Open logs

@kukjevov
Copy link

kukjevov commented Sep 2, 2016

Hi.

I have reported that there is problem with Redstone 1. I have multiple Windows installations and i tried it also on Windows 10 10586 and it worked without any problem. Then i tried it in work where i also have Windows 10 14393 Redstone 1 and it also worked.

Only difference that i see now is that Redstone 1 where it is not working was upgraded from Insider Preview build and Redstone 1 where it is working was upgraded from stable 10586. I`m going to fully reinstall that one Windows to see if it helps.

@rneugeba Before i reinstall i will gather requested informations.

@cuikangyi
Copy link
Author

Hi, @kukjevov
For me that is the case.
On my PC, it canot work on Windows 10 14393, but it can work on Windows 10 10586.
And them are both fully reinstall.

@tonercart
Copy link

@rneugeba I managed to get working by first manually starting Docker and after that the next few re-boots were also fine. This morning however I had another failure on startup due to a different reason diagnostic is E29FCE66-DA9C-40C9-85CC-AD63831ADA03/2016-09-02_08-20-32

Again I can get going by manually starting Docker which is fine for my purposes.

FYI - Hardware is only a couple of years old - Dell laptop with i7-4510U

@sstodd7532
Copy link

sstodd7532 commented Sep 2, 2016

Unable to create: The running command stopped because the preference variable "ErrorActionPreference" or common parameter is set to Stop: Modifying the properties of the device 'Memory' failed.

The operation cannot be performed while the object is in its current state.
at New-MobyLinuxVM, : line 184
at , : line 321
at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.LinuxContainersEngine.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)

my most recent crash report: AD4A52AE-5334-4B98-8678-7191A2331558/2016-09-02_07-51-21

After the error above, I went into task manager and stopped all tasks I found for hyper-v and docker. I then opened hyper-v manager to verify that none of the VMs were running. I then opened Docker for Windows from the start menu. Doing this I was able to get the VM to boot and get the IP successfully. It appears to work correctly but does not. I then get the following error when trying to pull down a container:

[7:49:17 AM] H:> docker pull centos/powershell
Using default tag: latest
Pulling repository docker.io/centos/powershell
Error while pulling image: Get https://index.docker.io/v1/repositories/centos/powershell/images: dial tcp: lookup index.docker.io on 192.168.65.4:53: server misbehaving

Here is a copy of my log file, and a diagnostic was uploaded with id:
AD4A52AE-5334-4B98-8678-7191A2331558/2016-09-02_08-41-59

log-2016-09-02_08-33-50.txt

As a test, I am setting my docker to not start when I login, but to allow me to start manually. The only issue that I have now is the DNS issue mentioned above.

@cuikangyi
Copy link
Author

Hi, @rneugeba
I have same error after reinstall of Beta24.
Diagnostic ID: 0EACCFD5-7AEF-41DC-AF11-4F1C524B72F5/2016-09-03_08-56-48

@kukjevov
Copy link

kukjevov commented Sep 3, 2016

Hi @rneugeba
Here is my diagnostic ID: FB5CEB8E-709F-4A8F-8F18-DE39D01B068D/2016-09-03_07-24-39

@mustikkakeitto
Copy link

Hi, here is my diagnostic ID for this problem:
61006024-AE02-4DD7-84D6-8C9EE6A824AF/2016-09-07_10-13-35

@Hobby-Student
Copy link

Hobby-Student commented Sep 7, 2016

[EDIT]
Did several combinations of "reset to factory defaults" and restarts. It's now working again.
[/EDIT]

I now have the same problem. yesterday everything was working normal... but today docker is not getting its IP.
Diagnostic ID: 2908EE57-E9BE-4162-A362-8B6927FFDEF2/2016-09-07_11-14-48

Surface Pro 3 (128GB & 4GB RAM)
Windows 10 Pro x64 with all updates available (no Insider)

(Have another Windows 10 Pro x64 as VM on ESXi 6 and it never worked as it never gets an IP. I know, that nested virtualization is probably not the most important thing on docker for windows... but I would use it ;) )

@TaylorBarrick
Copy link

I had some success for a while, but a recent reboot triggered the old behavior.
Current win build is 14915.rs_prerelease.160826-1902

Diagnostic ID: 0D363E23-6B8A-4A91-AB4F-C0283E39F2B2/2016-09-07_15-43-48

@Irieish
Copy link

Irieish commented Sep 7, 2016

Hi @rneugeba. Just installed beta25. Win10 Pro (1607 - build 14393.105). Slightly different error, but still not getting an IP address. Logs show everything pointing to DockerNAT, but the only adapter showing in devmgmt.msc, (including hidden) is vEthernet (DockerNAT) 2.

Get-VMSwitch reveals DockerNAT as an Internal Switch, and Hyper-V shows MobyLinuxVM using DockerNAT as the connection, but with no IP and a status of "no communication".

I was able to locate the mystery/ghosted DockerNAT ID within the debuginfo file, so could track down those entries in the registry, but wanted to roll this up first to see if there might be any better troubleshooting ideas. Thanks in advance for any help.

Below is a copy of the log file, the Diagnostic ID, and error message.
log-2016-09-07_15-06-38.txt

Diagnostic ID: 6F013C72-7104-4907-BC8B-D7F1D42EE211/2016-09-07_15-09-29

Unable to start: The VM couldn't get an IP address after 60 tries
at , : line 332
at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
at Docker.Backend.ContainerEngine.Linux.DoStart(Settings settings)
at Docker.Backend.ContainerEngine.Linux.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)

@st1971
Copy link

st1971 commented Sep 8, 2016

Have the same issue, clean install of Win 10 Pro 1607 build 14393.105.

The VM never starts up have left it for 60+ minutes without it responding.

Diagnostic ID: 21623F99-9384-43D1-99D9-97FD9FFA1CF3/2016-09-08_09-24-19

Get the same behaviour with the latest beta and the stable build.

@Gmanweb
Copy link

Gmanweb commented Nov 10, 2016

I was running Docker 1.12.1 with no issues, got the Error and done your fixes and it still not starting. This is the error now:

Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, <No file>: line 353
at Start-MobyLinuxVM, <No file>: line 267
at <ScriptBlock>, <No file>: line 363
   at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
   at Docker.Backend.ContainerEngine.Linux.DoStart(Settings settings)
   at Docker.Backend.ContainerEngine.Linux.Start(Settings settings)
   at Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass8_0.<Register>b__0(Object[] parameters)
   at Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)

Here are my logs


Version: 1.12.3 (8488)
Channel: Stable
Sha1: d63ec34a49019d2e0b29d7706e57ccfc721ddb8f
Started on: 2016/11/10 12:06:20.927
Resources: C:\Program Files\Docker\Docker\Resources
OS: Windows 10 Pro
Edition: Professional
Id: 1607
Build: 14393
BuildLabName: 14393.447.amd64fre.rs1_release_inmarket.161102-0100
You can send feedback, including this log file, at https://github.com/docker/for-win/issues
[12:06:20.561][Tracking       ][Info   ] Crash report and usage statistics are enabled
[12:06:21.067][SegmentApi     ][Info   ] Usage statistic: appLaunched
[12:06:21.831][NamedPipeClient][Info   ] Sending Version()...
[12:06:22.005][NamedPipeClient][Info   ] Received response for Version
[12:06:22.036][SegmentApi     ][Info   ] Usage statistic: heartbeat
[11:50:09.767][BackendServer  ][Info   ] Started
[11:50:09.783][Moby           ][Info   ] Connecting...
[12:06:21.915][NamedPipeServer][Info   ] Version()
[12:06:21.971][NamedPipeServer][Info   ] Version done.
[12:06:23.036][NamedPipeClient][Info   ] Sending Start(Docker.Core.Settings)...
[12:06:23.037][Updater        ][Info   ] Checking for updates on channel Stable...
[12:06:23.100][NamedPipeServer][Info   ] Start(Docker.Core.Settings)
[12:06:23.178][HyperVGuids    ][Info   ] Installing GUIDs...
[12:06:23.222][HyperVGuids    ][Info   ] GUIDs installed
[12:06:23.398][Updater        ][Info   ] Local build 8488 is as good as the remote 8488 on channel Stable
[12:06:23.538][Firewall       ][Info   ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[12:06:39.788][Firewall       ][Info   ] Ports are opened
[12:06:39.820][HyperV         ][Info   ] Create
[12:06:39.960][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...
[12:07:06.710][HyperV         ][Info   ] Using existing Switch: DockerNAT
[12:07:09.382][HyperV         ][Info   ] Using existing Switch IP address
[12:07:14.570][HyperV         ][Info   ] Setting CPUs to 2 and Memory to 2048 MB
[12:07:21.507][HyperV         ][Info   ] Connect Internal Switch DockerNAT
[12:07:29.078][HyperV         ][Info   ] VM created.
[12:07:29.179][PowerShell     ][Info   ] Run script...
[12:07:29.580][DataKit        ][Info   ] Starting C:\Program Files\Docker\Docker\Resources\com.docker.db.exe --url hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39,\\.\pipe\dockerDataBase --color never
[12:07:30.862][DataKit        ][Info   ] Started
[12:07:30.940][PowerShell     ][Info   ] Run script...
[12:07:31.299][DataKit        ][Info   ] 
[12:07:31.346][DataKit        ][Info   ] +53768us    Datakit  Starting com.docker.db.exe 09081b4 ...
[12:07:31.362][VpnKit         ][Info   ] Starting C:\Program Files\Docker\Docker\Resources\com.docker.slirp.exe --ethernet hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 --port hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 --db \\.\pipe\dockerDataBase --debug
[12:07:31.971][VpnKit         ][Info   ] Started
[12:07:32.080][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/config=static
[12:07:32.127][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[12:07:32.174][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[12:07:32.205][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[12:07:32.252][VpnKit         ][Info   ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[12:07:32.299][VpnKit         ][Info   ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[12:07:32.252][Database       ][Info   ] Writing com.docker.driver.amd64-linux/network=hybrid
[12:07:32.330][VpnKit         ][Info   ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[12:07:32.409][VpnKit         ][Info   ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 vsock_path:
[12:07:32.377][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[]}
[12:07:32.455][VpnKit         ][Info   ] com.docker.slirp.exe: connecting to 82105d08-aeee-42bb-9ba2-d918ab806b39:0B95756A-9985-48AD-9470-78E060895BE7
[12:07:32.487][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[12:07:32.580][Database       ][Info   ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[12:07:32.534][VpnKit         ][Debug  ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39
[12:07:32.659][VpnKit         ][Info   ] com.docker.slirp.exe: connecting to 82105d08-aeee-42bb-9ba2-d918ab806b39:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[12:07:32.705][VpnKit         ][Debug  ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39
[12:07:32.752][VpnKit         ][Info   ] com.docker.slirp.exe: hosts file has bindings for 
[12:07:34.535][Database       ][Info   ] Successfully wrote 8 value(s)
[12:07:34.535][DataKit        ][Info   ] 
[12:07:34.613][DataKit        ][Info   ] +3291323us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:34.582][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/exclude
[12:07:34.707][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/http
[12:07:34.753][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/https
[12:07:35.192][Database       ][Info   ] Successfully removed 3 value(s)
[12:07:35.207][DataKit        ][Info   ] 
[12:07:35.270][DataKit        ][Info   ] +3960909us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:35.473][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/ssl/certs/ca-certificates.crt=C:\WINDOWS\TEMP\tmp3B50.tmp
[12:07:36.017][Database       ][Info   ] Successfully wrote value
[12:07:36.032][DataKit        ][Info   ] 
[12:07:36.111][DataKit        ][Info   ] +4786412us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:36.126][PowerShell     ][Info   ] Run script '$(Find-NetRoute -RemoteIPAddress 8.8.8.8).InterfaceIndex[0]'...
[12:07:37.173][Database       ][Info   ] Writing com.docker.driver.amd64-linux/slirp/dns=nameserver 10.60.101.57
nameserver 57.6.189.34
search ams.ltr.eng.sita.aero
[12:07:37.655][DataKit        ][Info   ] 
[12:07:37.655][Database       ][Info   ] Successfully wrote 1 value(s)
[12:07:37.686][DataKit        ][Info   ] +6408003us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:07:37.733][HyperV         ][Info   ] Start
[12:07:37.811][PowerShell     ][Info   ] Run script with parameters: -Start True...
[12:07:38.077][HyperV         ][Info   ] Starting VM MobyLinuxVM...
[12:07:41.686][Moby           ][Info   ] Connected
[12:07:42.905][Moby           ][Info   ] early console in extract_kernel
[12:07:42.936][Moby           ][Info   ] input_data: 0x0000000001eb23b4
[12:07:42.983][Moby           ][Info   ] input_len: 0x000000000061ed6a
[12:07:43.030][Moby           ][Info   ] output: 0x0000000001000000
[12:07:43.077][Moby           ][Info   ] output_len: 0x00000000014be910
[12:07:43.123][Moby           ][Info   ] kernel_total_size: 0x0000000001174000
[12:07:43.170][Moby           ][Info   ] 
[12:07:44.311][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:45.592][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:46.733][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:48.748][Moby           ][Info   ] Decompressing Linux... Parsing ELF... done.
[12:07:48.795][Moby           ][Info   ] Booting the kernel.
[12:07:48.858][Moby           ][Info   ] Linux version 4.8.5-moby (root@1d811a9194c4) (gcc version 5.3.0 (Alpine 5.3.0) ) #1 SMP Sun Oct 30 09:31:44 UTC 2016
[12:07:48.905][Moby           ][Info   ] Command line: earlyprintk=serial console=ttyS0 mobyplatform=windows
[12:07:48.952][Moby           ][Info   ] 
[12:07:48.998][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[12:07:49.045][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[12:07:49.077][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[12:07:49.123][Moby           ][Info   ] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[12:07:49.170][Moby           ][Info   ] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[12:07:49.217][Moby           ][Info   ] x86/fpu: Using 'eager' FPU context switches.
[12:07:49.264][Moby           ][Info   ] e820: BIOS-provided physical RAM map:
[12:07:49.311][Moby           ][Info   ] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[12:07:49.342][Moby           ][Info   ] BIOS-e820: [mem 0x0000000000100000-0x000000007eee5fff] usable
[12:07:49.389][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eee6000-0x000000007eee6fff] ACPI data
[12:07:49.436][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eee7000-0x000000007eeedfff] reserved
[12:07:49.483][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eeee000-0x000000007eef1fff] ACPI data
[12:07:49.514][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eef2000-0x000000007ef1afff] reserved
[12:07:49.561][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ef1b000-0x000000007ff9afff] usable
[12:07:49.608][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ff9b000-0x000000007fff2fff] reserved
[12:07:49.639][Moby           ][Info   ] BIOS-e820: [mem 0x000000007fff3000-0x000000007fffafff] ACPI data
[12:07:49.686][Moby           ][Info   ] BIOS-e820: [mem 0x000000007fffb000-0x000000007fffefff] ACPI NVS
[12:07:49.733][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ffff000-0x000000007fffffff] usable
[12:07:49.764][Moby           ][Info   ] bootconsole [earlyser0] enabled
[12:07:49.811][Moby           ][Info   ] ERROR: earlyprintk= earlyser already used
[12:07:49.873][Moby           ][Info   ] NX (Execute Disable) protection: active
[12:07:49.952][Moby           ][Info   ] efi: EFI v2.31 by EDK II
[12:07:49.998][Moby           ][Info   ] efi:  ACPI=0x7fffa000  ACPI 2.0=0x7fffa014  SMBIOS=0x7fff2000  PROP=0x419a3d8  MEMATTR=0x7f4d6d18 
[12:07:50.061][Moby           ][Info   ] SMBIOS 2.4 present.
[12:07:50.123][Moby           ][Info   ] Hypervisor detected: Microsoft HyperV
[12:07:50.187][Moby           ][Info   ] HyperV: features 0x2e7f, hints 0x2c2c
[12:07:50.248][Moby           ][Info   ] HyperV: LAPIC Timer Frequency: 0x1e8480
[12:07:50.295][Moby           ][Info   ] clocksource: hyperv_clocksource: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[12:07:50.358][Moby           ][Info   ] tsc: Marking TSC unstable due to running on Hyper-V
[12:07:50.420][Moby           ][Info   ] e820: last_pfn = 0x80000 max_arch_pfn = 0x400000000
[12:07:50.483][Moby           ][Info   ] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[12:07:50.530][Moby           ][Info   ] Using GB pages for direct mapping
[12:07:50.592][Moby           ][Info   ] RAMDISK: [mem 0x77eaa000-0x7b3abfff]
[12:07:50.655][Moby           ][Info   ] ACPI: Early table checksum verification disabled
[12:07:50.717][Moby           ][Info   ] ACPI: RSDP 0x000000007FFFA014 000024 (v02 VRTUAL)
[12:07:50.764][Moby           ][Info   ] ACPI: XSDT 0x000000007FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:50.827][Moby           ][Info   ] ACPI: FACP 0x000000007FFF7000 0000F4 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:50.889][Moby           ][Info   ] ACPI: DSDT 0x000000007EEEF000 00256F (v02 MSFTVM DSDT01   00000001 MSFT 04000000)
[12:07:50.967][Moby           ][Info   ] ACPI: FACS 0x000000007FFFE000 000040
[12:07:51.014][Moby           ][Info   ] ACPI: APIC 0x000000007FFF8000 0007D2 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:50.936][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:51.077][Moby           ][Info   ] ACPI: OEM0 0x000000007FFF6000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:51.186][Moby           ][Info   ] ACPI: WAET 0x000000007FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:51.233][Moby           ][Info   ] ACPI: OEM1 0x000000007FFF4000 00009E (v02 MSFTVM UARTS    00000001 MSFT 04000000)
[12:07:51.280][Moby           ][Info   ] ACPI: OEM2 0x000000007FFF3000 0002E2 (v02 MSFTVM VTPM2    00000001 MSFT 04000000)
[12:07:51.342][Moby           ][Info   ] ACPI: SRAT 0x000000007EEEE000 000190 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:51.389][Moby           ][Info   ] ACPI: BGRT 0x000000007EEE6000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:07:51.420][Moby           ][Info   ] Zone ranges:
[12:07:51.467][Moby           ][Info   ]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[12:07:51.530][Moby           ][Info   ]   DMA32    [mem 0x0000000001000000-0x000000007fffffff]
[12:07:51.592][Moby           ][Info   ]   Normal   empty
[12:07:51.655][Moby           ][Info   ] Movable zone start for each node
[12:07:51.686][Moby           ][Info   ] Early memory node ranges
[12:07:51.748][Moby           ][Info   ]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
[12:07:51.795][Moby           ][Info   ]   node   0: [mem 0x0000000000100000-0x000000007eee5fff]
[12:07:51.858][Moby           ][Info   ]   node   0: [mem 0x000000007ef1b000-0x000000007ff9afff]
[12:07:51.905][Moby           ][Info   ]   node   0: [mem 0x000000007ffff000-0x000000007fffffff]
[12:07:51.952][Moby           ][Info   ] Initmem setup node 0 [mem 0x0000000000001000-0x000000007fffffff]
[12:07:52.014][Moby           ][Info   ] ACPI: PM-Timer IO Port: 0x408
[12:07:52.061][Moby           ][Info   ] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[12:07:52.123][Moby           ][Info   ] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23
[12:07:52.170][Moby           ][Info   ] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[12:07:52.217][Moby           ][Info   ] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[12:07:52.264][Moby           ][Info   ] Using ACPI (MADT) for SMP configuration information
[12:07:52.311][Moby           ][Info   ] smpboot: 240 Processors exceeds NR_CPUS limit of 4
[12:07:52.217][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:52.358][Moby           ][Info   ] smpboot: Allowing 4 CPUs, 2 hotplug CPUs
[12:07:52.436][Moby           ][Info   ] e820: [mem 0x80000000-0xffffffff] available for PCI devices
[12:07:52.467][Moby           ][Info   ] Booting paravirtualized kernel on bare hardware
[12:07:52.514][Moby           ][Info   ] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[12:07:52.561][Moby           ][Info   ] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[12:07:52.592][Moby           ][Info   ] percpu: Embedded 35 pages/cpu @ffff88007f000000 s104984 r8192 d30184 u524288
[12:07:52.639][Moby           ][Info   ] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 515791
[12:07:52.686][Moby           ][Info   ] Kernel command line: earlyprintk=serial console=ttyS0 mobyplatform=windows
[12:07:52.733][Moby           ][Info   ] 
[12:07:52.764][Moby           ][Info   ] PID hash table entries: 4096 (order: 3, 32768 bytes)
[12:07:52.811][Moby           ][Info   ] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[12:07:52.858][Moby           ][Info   ] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[12:07:52.905][Moby           ][Info   ] Memory: 1971912K/2096152K available (8571K kernel code, 1397K rwdata, 2964K rodata, 1380K init, 572K bss, 124240K reserved, 0K cma-reserved)
[12:07:52.952][Moby           ][Info   ] Hierarchical RCU implementation.
[12:07:52.983][Moby           ][Info   ]    Build-time adjustment of leaf fanout to 64.
[12:07:53.030][Moby           ][Info   ] Using NULL legacy PIC
[12:07:53.077][Moby           ][Info   ] NR_IRQS:4352 nr_irqs:456 0
[12:07:53.123][Moby           ][Info   ] Console: colour dummy device 80x25
[12:07:53.155][Moby           ][Info   ] console [ttyS0] enabled
[12:07:53.202][Moby           ][Info   ] console [ttyS0] enabled
[12:07:53.248][Moby           ][Info   ] bootconsole [earlyser0] disabled
[12:07:53.295][Moby           ][Info   ] bootconsole [earlyser0] disabled
[12:07:53.342][Moby           ][Info   ] tsc: Fast TSC calibration failed
[12:07:53.389][Moby           ][Info   ] tsc: Unable to calibrate against PIT
[12:07:53.436][Moby           ][Info   ] tsc: using PMTIMER reference calibration
[12:07:53.483][Moby           ][Info   ] tsc: Detected 2783.650 MHz processor
[12:07:53.530][Moby           ][Info   ] Calibrating delay loop (skipped), value calculated using timer frequency.. 5567.30 BogoMIPS (lpj=27836500)
[12:07:53.592][Moby           ][Info   ] pid_max: default: 32768 minimum: 301
[12:07:53.639][Moby           ][Info   ] ACPI: Core revision 20160422
[12:07:53.686][Moby           ][Info   ] ACPI: 1 ACPI AML tables successfully acquired and loaded
[12:07:53.717][Moby           ][Info   ] 
[12:07:53.764][Moby           ][Info   ] Security Framework initialized
[12:07:53.811][Moby           ][Info   ] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[12:07:53.858][Moby           ][Info   ] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[12:07:53.905][Moby           ][Info   ] CPU: Physical Processor ID: 0
[12:07:53.952][Moby           ][Info   ] CPU: Processor Core ID: 0
[12:07:53.999][Moby           ][Info   ] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[12:07:54.030][Moby           ][Info   ] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[12:07:54.077][Moby           ][Info   ] Freeing SMP alternatives memory: 24K (ffffffff820b8000 - ffffffff820be000)
[12:07:54.139][Moby           ][Info   ] ftrace: allocating 37960 entries in 149 pages
[12:07:54.248][Moby           ][Info   ] smpboot: APIC(0) Converting physical 0 to logical package 0
[12:07:54.296][Moby           ][Info   ] smpboot: Max logical packages: 120
[12:07:54.342][Moby           ][Info   ] smpboot: CPU0: Intel(R) Core(TM) i7-4810MQ CPU @ 2.80GHz (family: 0x6, model: 0x3c, stepping: 0x3)
[12:07:54.389][Moby           ][Info   ] Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
[12:07:54.436][Moby           ][Info   ] NMI watchdog: disabled (cpu0): hardware events not enabled
[12:07:54.436][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:54.483][Moby           ][Info   ] NMI watchdog: Shutting down hard lockup detector on all cpus
[12:07:54.577][Moby           ][Info   ] x86: Booting SMP configuration:
[12:07:54.623][Moby           ][Info   ] .... node  #0, CPUs:      #1
[12:07:54.670][Moby           ][Info   ] x86: Booted up 1 node, 2 CPUs
[12:07:55.217][Moby           ][Info   ] smpboot: Total of 2 processors activated (12935.26 BogoMIPS)
[12:07:55.405][Moby           ][Info   ] devtmpfs: initialized
[12:07:55.608][Moby           ][Info   ] x86/mm: Memory block size: 128MB
[12:07:56.373][Moby           ][Info   ] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[12:07:56.780][Moby           ][Info   ] NET: Registered protocol family 16
[12:07:57.373][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:57.592][Moby           ][Info   ] cpuidle: using governor ladder
[12:07:57.670][Moby           ][Info   ] cpuidle: using governor menu
[12:07:57.717][Moby           ][Info   ] ACPI: bus type PCI registered
[12:07:57.764][Moby           ][Info   ] PCI: Fatal: No config space access function found
[12:07:57.847][Moby           ][Info   ] HugeTLB registered 1 GB page size, pre-allocated 0 pages
[12:07:58.220][Moby           ][Info   ] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[12:07:58.561][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:07:58.592][Moby           ][Info   ] ACPI: Added _OSI(Module Device)
[12:07:58.748][Moby           ][Info   ] ACPI: Added _OSI(Processor Device)
[12:07:58.920][Moby           ][Info   ] ACPI: Added _OSI(3.0 _SCP Extensions)
[12:07:59.092][Moby           ][Info   ] ACPI: Added _OSI(Processor Aggregator Device)
[12:07:59.295][Moby           ][Info   ] ACPI: Executed 1 blocks of module-level executable AML code
[12:07:59.420][Moby           ][Info   ] ACPI: Dynamic OEM Table Load:
[12:07:59.858][Moby           ][Info   ] ACPI: OEM1 0x0000000000000000 00009E (v02 MSFTVM UARTS    00000001 MSFT 04000000)
[12:08:00.077][Moby           ][Info   ] ACPI: Interpreter enabled
[12:08:00.186][Moby           ][Info   ] ACPI: (supports S0 S5)
[12:08:00.405][Moby           ][Info   ] ACPI: Using IOAPIC for interrupt routing
[12:08:00.592][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:00.842][Moby           ][Info   ] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[12:08:01.186][Moby           ][Info   ] ACPI: Enabled 1 GPEs in block 00 to 0F
[12:08:01.264][Moby           ][Info   ] vgaarb: loaded
[12:08:01.389][Moby           ][Info   ] SCSI subsystem initialized
[12:08:01.530][Moby           ][Info   ] pps_core: LinuxPPS API ver. 1 registered
[12:08:01.748][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:01.920][Moby           ][Info   ] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[12:08:02.014][Moby           ][Info   ] PTP clock support registered
[12:08:02.108][Moby           ][Info   ] wmi: Mapper loaded
[12:08:02.702][Moby           ][Info   ] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[12:08:03.061][Moby           ][Info   ] hv_vmbus: Hyper-V Host Build:14393-10.0-0-0.206; Vmbus version:4.0
[12:08:03.202][Moby           ][Info   ] PCI: Using ACPI for IRQ routing
[12:08:03.389][Moby           ][Info   ] PCI: System does not support PCI
[12:08:03.545][Moby           ][Info   ] NetLabel: Initializing
[12:08:03.795][Moby           ][Info   ] NetLabel:  domain hash size = 128
[12:08:04.170][Moby           ][Info   ] NetLabel:  protocols = UNLABELED CIPSOv4
[12:08:04.420][Moby           ][Info   ] NetLabel:  unlabeled traffic allowed by default
[12:08:04.733][Moby           ][Info   ] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[12:08:04.873][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:04.983][Moby           ][Info   ] FS-Cache: Loaded
[12:08:05.123][Moby           ][Info   ] CacheFiles: Loaded
[12:08:05.202][Moby           ][Info   ] pnp: PnP ACPI init
[12:08:05.358][Moby           ][Info   ] pnp: PnP ACPI: found 3 devices
[12:08:05.686][Moby           ][Info   ] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[12:08:06.061][Moby           ][Info   ] NET: Registered protocol family 2
[12:08:06.358][Moby           ][Info   ] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[12:08:06.608][Moby           ][Info   ] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[12:08:06.842][Moby           ][Info   ] TCP: Hash tables configured (established 16384 bind 16384)
[12:08:06.936][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:07.092][Moby           ][Info   ] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[12:08:07.358][Moby           ][Info   ] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[12:08:07.545][Moby           ][Info   ] NET: Registered protocol family 1
[12:08:07.795][Moby           ][Info   ] RPC: Registered named UNIX socket transport module.
[12:08:07.936][Moby           ][Info   ] RPC: Registered udp transport module.
[12:08:08.061][Moby           ][Info   ] RPC: Registered tcp transport module.
[12:08:08.077][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:08.280][Moby           ][Info   ] RPC: Registered tcp NFSv4.1 backchannel transport module.
[12:08:08.373][Moby           ][Info   ] Unpacking initramfs...
[12:08:09.186][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:09.686][Moby           ][Info   ] random: fast init done
[12:08:12.155][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:14.311][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:15.498][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:16.639][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:19.436][Moby           ][Info   ] Freeing initrd memory: 54280K (ffff880077eaa000 - ffff88007b3ac000)
[12:08:19.467][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:19.748][Moby           ][Info   ] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 10737418240 ms ovfl timer
[12:08:19.920][Moby           ][Info   ] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules
[12:08:20.092][Moby           ][Info   ] RAPL PMU: hw unit of domain package 2^-0 Joules
[12:08:20.248][Moby           ][Info   ] RAPL PMU: hw unit of domain dram 2^-0 Joules
[12:08:20.405][Moby           ][Info   ] RAPL PMU: hw unit of domain pp1-gpu 2^-0 Joules
[12:08:20.780][Moby           ][Info   ] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x281feb402e7, max_idle_ns: 440795242103 ns
[12:08:21.030][Moby           ][Info   ] futex hash table entries: 1024 (order: 4, 65536 bytes)
[12:08:21.295][Moby           ][Info   ] workingset: timestamp_bits=46 max_order=19 bucket_order=0
[12:08:21.389][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:22.014][Moby           ][Info   ] FS-Cache: Netfs 'nfs' registered for caching
[12:08:22.248][Moby           ][Info   ] NFS: Registering the id_resolver key type
[12:08:22.389][Moby           ][Info   ] Key type id_resolver registered
[12:08:22.530][Moby           ][Info   ] Key type id_legacy registered
[12:08:22.547][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:22.733][Moby           ][Info   ] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[12:08:22.936][Moby           ][Info   ] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[12:08:23.311][Moby           ][Info   ] FS-Cache: Netfs 'cifs' registered for caching
[12:08:23.608][Moby           ][Info   ] ntfs: driver 2.1.32 [Flags: R/O].
[12:08:23.686][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:23.811][Moby           ][Info   ] fuse init (API version 7.25)
[12:08:24.139][Moby           ][Info   ] 9p: Installing v9fs 9p2000 file system support
[12:08:24.358][Moby           ][Info   ] FS-Cache: Netfs '9p' registered for caching
[12:08:24.577][Moby           ][Info   ] aufs 4.8-20161010
[12:08:24.717][Moby           ][Info   ] Key type big_key registered
[12:08:24.920][Moby           ][Info   ] NET: Registered protocol family 38
[12:08:25.045][Moby           ][Info   ] Key type asymmetric registered
[12:08:25.217][Moby           ][Info   ] Asymmetric key parser 'x509' registered
[12:08:25.545][Moby           ][Info   ] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[12:08:25.670][Moby           ][Info   ] io scheduler noop registered
[12:08:25.873][Moby           ][Info   ] io scheduler deadline registered (default)
[12:08:25.967][Moby           ][Info   ] io scheduler cfq registered
[12:08:26.077][Moby           ][Info   ] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[12:08:26.327][Moby           ][Info   ] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[12:08:26.483][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:26.592][Moby           ][Info   ] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[12:08:26.717][Moby           ][Info   ] hv_vmbus: registering driver hyperv_fb
[12:08:26.967][Moby           ][Info   ] hyperv_fb: Screen resolution: 1152x864, Color depth: 32
[12:08:27.248][Moby           ][Info   ] Console: switching to colour frame buffer device 144x54
[12:08:27.342][Moby           ][Info   ] efifb: probing for efifb
[12:08:27.545][Moby           ][Info   ] efifb: cannot reserve video memory at 0xf8000000
[12:08:27.764][Moby           ][Info   ] efifb: framebuffer at 0xf8000000, using 3072k, total 3072k
[12:08:27.936][Moby           ][Info   ] efifb: mode is 1024x768x32, linelength=4096, pages=1
[12:08:28.045][Moby           ][Info   ] efifb: scrolling: redraw
[12:08:28.264][Moby           ][Info   ] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[12:08:28.389][Moby           ][Info   ] fb1: EFI VGA frame buffer device
[12:08:28.405][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:28.514][Moby           ][Info   ] GHES: HEST is not enabled!
[12:08:28.733][Moby           ][Info   ] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[12:08:29.530][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:29.608][Moby           ][Info   ] 00:01: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[12:08:30.530][Moby           ][Info   ] 00:02: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[12:08:30.655][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:30.686][Moby           ][Info   ] Initializing Nozomi driver 2.1d
[12:08:30.811][Moby           ][Info   ] Non-volatile memory driver v1.3
[12:08:31.155][Moby           ][Info   ] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[12:08:31.295][Moby           ][Info   ] [drm] Initialized drm 1.1.0 20060810
[12:08:31.405][Moby           ][Info   ] loop: module loaded
[12:08:31.530][Moby           ][Info   ] nbd: registered device at major 43
[12:08:31.702][Moby           ][Info   ] VMware PVSCSI driver - version 1.0.6.0-k
[12:08:31.827][Moby           ][Info   ] hv_vmbus: registering driver hv_storvsc
[12:08:31.967][Moby           ][Info   ] scsi host0: storvsc_host_t
[12:08:32.311][Moby           ][Info   ] scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[12:08:32.733][Moby           ][Info   ] scsi 0:0:0:1: CD-ROM            Msft     Virtual DVD-ROM  1.0  PQ: 0 ANSI: 0
[12:08:33.092][Moby           ][Info   ] sd 0:0:0:0: [sda] 125829120 512-byte logical blocks: (64.4 GB/60.0 GiB)
[12:08:33.295][Moby           ][Info   ] sd 0:0:0:0: [sda] 4096-byte physical blocks
[12:08:33.327][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:33.483][Moby           ][Info   ] sr 0:0:0:1: [sr0] scsi3-mmc drive: 0x/0x caddy
[12:08:33.670][Moby           ][Info   ] cdrom: Uniform CD-ROM driver Revision: 3.20
[12:08:33.858][Moby           ][Info   ] sd 0:0:0:0: Attached scsi generic sg0 type 0
[12:08:34.045][Moby           ][Info   ] sr 0:0:0:1: Attached scsi generic sg1 type 5
[12:08:34.264][Moby           ][Info   ] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
[12:08:34.436][Moby           ][Info   ] tun: Universal TUN/TAP device driver, 1.6
[12:08:34.639][Moby           ][Info   ] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[12:08:34.920][Moby           ][Info   ] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[12:08:35.139][Moby           ][Info   ] e1000: Copyright (c) 1999-2006 Intel Corporation.
[12:08:35.264][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:35.311][Moby           ][Info   ] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[12:08:35.514][Moby           ][Info   ] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[12:08:35.842][Moby           ][Info   ] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 3.2.2-k
[12:08:36.061][Moby           ][Info   ] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation.
[12:08:36.202][Moby           ][Info   ] PPP generic driver version 2.4.2
[12:08:36.311][Moby           ][Info   ] PPP BSD Compression module registered
[12:08:36.405][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:36.467][Moby           ][Info   ] PPP Deflate Compression module registered
[12:08:36.623][Moby           ][Info   ] PPP MPPE Compression module registered
[12:08:36.780][Moby           ][Info   ] NET: Registered protocol family 24
[12:08:36.889][Moby           ][Info   ] PPTP driver version 0.8.5
[12:08:37.092][Moby           ][Info   ] VMware vmxnet3 virtual NIC driver - version 1.4.a.0-k-NAPI
[12:08:37.248][Moby           ][Info   ] hv_vmbus: registering driver hv_netvsc
[12:08:37.452][Moby           ][Info   ] hv_netvsc: hv_netvsc channel opened successfully
[12:08:37.545][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:37.748][Moby           ][Info   ] hv_netvsc vmbus_14: Send section size: 6144, Section count:2560
[12:08:37.983][Moby           ][Info   ] hv_netvsc vmbus_14: Device MAC 00:15:5d:4b:01:06 link state up
[12:08:38.155][Moby           ][Info   ] sd 0:0:0:0: [sda] Write Protect is off
[12:08:38.280][Moby           ][Info   ] Fusion MPT base driver 3.04.20
[12:08:38.452][Moby           ][Info   ] Copyright (c) 1999-2008 LSI Corporation
[12:08:38.608][Moby           ][Info   ] Fusion MPT SPI Host driver 3.04.20
[12:08:38.748][Moby           ][Info   ] aoe: AoE v85 initialised.
[12:08:38.967][Moby           ][Info   ] i8042: PNP: No PS/2 controller found. Probing ports directly.
[12:08:39.405][Moby           ][Info   ] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[12:08:40.311][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:40.827][Moby           ][Info   ] i8042: No controller found
[12:08:41.030][Moby           ][Info   ] hv_vmbus: registering driver hyperv_keyboard
[12:08:41.233][Moby           ][Info   ] mousedev: PS/2 mouse device common for all mice
[12:08:41.311][Moby           ][Info   ]  sda: sda1 sda2
[12:08:41.498][Moby           ][Info   ] sd 0:0:0:0: [sda] Attached SCSI disk
[12:08:41.952][Moby           ][Info   ] input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/vmbus_4/serio0/input/input0
[12:08:42.186][Moby           ][Info   ] input: PC Speaker as /devices/platform/pcspkr/input/input1
[12:08:42.264][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:42.358][Moby           ][Info   ] rtc_cmos 00:00: RTC can wake from S4
[12:08:42.592][Moby           ][Info   ] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[12:08:42.827][Moby           ][Info   ] rtc_cmos 00:00: alarms up to one month, 114 bytes nvram
[12:08:42.920][Moby           ][Info   ] i2c /dev entries driver
[12:08:43.077][Moby           ][Info   ] EFI Variables Facility v0.08 2004-May-17
[12:08:43.202][Moby           ][Info   ] pstore: using zlib compression
[12:08:43.389][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:43.389][Moby           ][Info   ] pstore: Registered efi as persistent store backend
[12:08:43.545][Moby           ][Info   ] hv_utils: Registering HyperV Utility Driver
[12:08:43.702][Moby           ][Info   ] hv_vmbus: registering driver hv_util
[12:08:43.827][Moby           ][Info   ] oprofile: using timer interrupt.
[12:08:43.905][Moby           ][Info   ] GACT probability on
[12:08:44.014][Moby           ][Info   ] Mirror/redirect action on
[12:08:44.123][Moby           ][Info   ] Simple TC action Loaded
[12:08:44.186][Moby           ][Info   ] netem: version 1.3
[12:08:44.248][Moby           ][Info   ] u32 classifier
[12:08:44.405][Moby           ][Info   ]     Performance counters on
[12:08:44.514][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:44.561][Moby           ][Info   ]     input device check on
[12:08:44.655][Moby           ][Info   ]     Actions configured
[12:08:44.811][Moby           ][Info   ] Netfilter messages via NETLINK v0.30.
[12:08:44.967][Moby           ][Info   ] nfnl_acct: registering with nfnetlink.
[12:08:45.233][Moby           ][Info   ] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[12:08:45.467][Moby           ][Info   ] ctnetlink v0.93: registering with nfnetlink.
[12:08:45.702][Moby           ][Info   ] nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net>
[12:08:45.967][Moby           ][Info   ] nf_tables_compat: (c) 2012 Pablo Neira Ayuso <pablo@netfilter.org>
[12:08:46.170][Moby           ][Info   ] xt_time: kernel timezone is -0000
[12:08:46.280][Moby           ][Info   ] ip_set: protocol 6
[12:08:46.483][Moby           ][Info   ] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
[12:08:46.795][Moby           ][Info   ] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[12:08:46.952][Moby           ][Info   ] IPVS: Creating netns size=2104 id=0
[12:08:47.061][Moby           ][Info   ] IPVS: ipvs loaded.
[12:08:47.202][Moby           ][Info   ] IPVS: [rr] scheduler registered.
[12:08:47.264][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:47.358][Moby           ][Info   ] IPVS: [wrr] scheduler registered.
[12:08:47.483][Moby           ][Info   ] IPVS: [lc] scheduler registered.
[12:08:47.639][Moby           ][Info   ] IPVS: [wlc] scheduler registered.
[12:08:47.780][Moby           ][Info   ] IPVS: [fo] scheduler registered.
[12:08:47.920][Moby           ][Info   ] IPVS: [ovf] scheduler registered.
[12:08:48.061][Moby           ][Info   ] IPVS: [lblc] scheduler registered.
[12:08:48.170][Moby           ][Info   ] IPVS: [lblcr] scheduler registered.
[12:08:48.311][Moby           ][Info   ] IPVS: [dh] scheduler registered.
[12:08:48.530][Moby           ][Info   ] IPVS: [sh] scheduler registered.
[12:08:48.780][Moby           ][Info   ] IPVS: [sed] scheduler registered.
[12:08:48.983][Moby           ][Info   ] IPVS: [nq] scheduler registered.
[12:08:49.123][Moby           ][Info   ] IPVS: ftp: loaded support on port[0] = 21
[12:08:49.311][Moby           ][Info   ] ipip: IPv4 and MPLS over IPv4 tunneling driver
[12:08:49.342][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:49.467][Moby           ][Info   ] gre: GRE over IPv4 demultiplexor driver
[12:08:49.608][Moby           ][Info   ] ip_gre: GRE over IPv4 tunneling driver
[12:08:49.748][Moby           ][Info   ] IPv4 over IPsec tunneling driver
[12:08:49.889][Moby           ][Info   ] ip_tables: (C) 2000-2006 Netfilter Core Team
[12:08:50.108][Moby           ][Info   ] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[12:08:50.280][Moby           ][Info   ] arp_tables: arp_tables: (C) 2002 David S. Miller
[12:08:50.405][Moby           ][Info   ] Initializing XFRM netlink socket
[12:08:50.467][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:50.686][Moby           ][Info   ] NET: Registered protocol family 10
[12:08:50.780][Moby           ][Info   ] mip6: Mobile IPv6
[12:08:50.905][Moby           ][Info   ] ip6_tables: (C) 2000-2006 Netfilter Core Team
[12:08:51.092][Moby           ][Info   ] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[12:08:51.264][Moby           ][Info   ] ip6_gre: GRE over IPv6 tunneling driver
[12:08:51.373][Moby           ][Info   ] NET: Registered protocol family 17
[12:08:51.483][Moby           ][Info   ] NET: Registered protocol family 15
[12:08:51.608][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:51.936][Moby           ][Info   ] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[12:08:52.061][Moby           ][Info   ] Bridge firewalling registered
[12:08:52.155][Moby           ][Info   ] Ebtables v2.0 registered
[12:08:52.311][Moby           ][Info   ] l2tp_core: L2TP core driver, V2.0
[12:08:52.452][Moby           ][Info   ] l2tp_ppp: PPPoL2TP kernel driver, V2.0
[12:08:52.592][Moby           ][Info   ] 8021q: 802.1Q VLAN Support v1.8
[12:08:52.748][Moby           ][Info   ] 9pnet: Installing 9P2000 support
[12:08:52.889][Moby           ][Info   ] Key type dns_resolver registered
[12:08:53.077][Moby           ][Info   ] openvswitch: Open vSwitch switching datapath
[12:08:53.217][Moby           ][Info   ] hv_vmbus: registering driver hv_sock
[12:08:53.358][Moby           ][Info   ] NET: Registered protocol family 43
[12:08:53.483][Moby           ][Info   ] mpls_gso: MPLS GSO support
[12:08:53.670][Moby           ][Info   ] microcode: sig=0x306c3, pf=0x1, revision=0xffffffff
[12:08:54.045][Moby           ][Info   ] microcode: Microcode Update Driver: v2.01 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[12:08:54.217][Moby           ][Info   ] AVX2 version of gcm_enc/dec engaged.
[12:08:54.327][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:54.373][Moby           ][Info   ] AES CTR mode by8 optimization enabled
[12:08:54.514][Moby           ][Info   ] registered taskstats version 1
[12:08:54.670][Moby           ][Info   ] Key type encrypted registered
[12:08:55.014][Moby           ][Info   ] rtc_cmos 00:00: setting system clock to 2016-11-10 12:08:53 UTC (1478779733)
[12:08:55.358][Moby           ][Info   ] Freeing unused kernel memory: 1380K (ffffffff81f5f000 - ffffffff820b8000)
[12:08:55.608][Moby           ][Info   ] Write protecting the kernel read-only data: 14336k
[12:08:55.920][Moby           ][Info   ] Freeing unused kernel memory: 1652K (ffff880001863000 - ffff880001a00000)
[12:08:56.295][Moby           ][Info   ] Freeing unused kernel memory: 1132K (ffff880001ce5000 - ffff880001e00000)
[12:08:56.295][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:56.436][Moby           ][Info   ] ------------[ cut here ]------------
[12:08:56.733][Moby           ][Info   ] WARNING: CPU: 1 PID: 1 at arch/x86/mm/dump_pagetables.c:225 note_page+0xcf/0x56b
[12:08:57.077][Moby           ][Info   ] x86/mm: Found insecure W+X mapping at address ffffc90000020000/0xffffc90000020000
[12:08:57.155][Moby           ][Info   ] Modules linked in:
[12:08:57.327][Moby           ][Info   ] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.8.5-moby #1
[12:08:57.436][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:57.780][Moby           ][Info   ] Hardware name: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v1.0 11/26/2012
[12:08:58.123][Moby           ][Info   ]  0000000000000286 00000000e15d7293 ffffffff8140ede2 ffff88007c56fde8
[12:08:58.420][Moby           ][Info   ]  0000000000000000 ffffffff810e0bc3 0000000000000000 ffff88007c56fe48
[12:08:58.577][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:08:58.717][Moby           ][Info   ]  ffff88007c498000 ffff88007c499000 0000c90000000000 0000000000000004
[12:08:58.780][Moby           ][Info   ] Call Trace:
[12:08:58.952][Moby           ][Info   ]  [<ffffffff8140ede2>] ? dump_stack+0x5a/0x6f
[12:08:59.108][Moby           ][Info   ]  [<ffffffff810e0bc3>] ? __warn+0xd2/0xeb
[12:08:59.342][Moby           ][Info   ]  [<ffffffff810e0c36>] ? warn_slowpath_fmt+0x5a/0x76
[12:08:59.514][Moby           ][Info   ]  [<ffffffff81119c9b>] ? vprintk_emit+0x34e/0x35f
[12:08:59.717][Moby           ][Info   ]  [<ffffffff8104e1ca>] ? note_page+0xcf/0x56b
[12:08:59.967][Moby           ][Info   ]  [<ffffffff8104e907>] ? ptdump_walk_pgd_level_core+0x2a1/0x3a4
[12:09:00.186][Moby           ][Info   ]  [<ffffffff81850759>] ? kernel_init+0x22/0xeb
[12:09:00.436][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:09:00.623][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:09:00.811][Moby           ][Info   ] ---[ end trace 14b37d0a74b3a14f ]---
[12:09:01.202][Moby           ][Info   ] x86/mm: Checked W+X mappings: FAILED, 1 W+X pages found.
[12:09:01.373][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:02.264][Moby           ][Info   ] 
[12:09:02.498][Moby           ][Info   ]    OpenRC 0.21.d6c52bd is starting up Linux 4.8.5-moby (x86_64)
[12:09:02.530][Moby           ][Info   ] 
[12:09:02.670][Moby           ][Info   ]  * Mounting /proc ... [ ok ]
[12:09:02.905][Moby           ][Info   ]  * Mounting /run ... * /run/openrc: creating directory
[12:09:03.061][Moby           ][Info   ]  * /run/lock: creating directory
[12:09:03.155][Moby           ][Info   ]  * /run/lock: correcting owner
[12:09:03.420][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:04.139][Moby           ][Info   ]  * Caching service dependencies ... [ ok ]
[12:09:04.389][Moby           ][Info   ]  * Mounting /sys ... [ ok ]
[12:09:04.545][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:04.577][Moby           ][Info   ]  * Mounting security filesystem ... [ ok ]
[12:09:04.764][Moby           ][Info   ]  * Mounting debug filesystem ... [ ok ]
[12:09:04.998][Moby           ][Info   ]  * Mounting fuse control filesystem ... [ ok ]
[12:09:05.248][Moby           ][Info   ]  * Mounting persistent storage (pstore) filesystem ... [ ok ]
[12:09:05.498][Moby           ][Info   ]  * Mounting cgroup filesystem ... [ ok ]
[12:09:05.670][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:06.092][Moby           ][Info   ]  * Mounting devtmpfs on /dev ... [ ok ]
[12:09:06.264][Moby           ][Info   ]  * Mounting /dev/mqueue ... [ ok ]
[12:09:06.420][Moby           ][Info   ]  * Mounting /dev/pts ... [ ok ]
[12:09:06.608][Moby           ][Info   ]  * Mounting /dev/shm ... [ ok ]
[12:09:07.748][Moby           ][Info   ]  * Starting busybox mdev ...modprobe: module fbcon not found in modules.dep
[12:09:08.045][Moby           ][Info   ]  [ ok ]
[12:09:08.436][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:09.795][Moby           ][Info   ]  * Loading hardware drivers ...modprobe: module fbcon not found in modules.dep
[12:09:09.889][Moby           ][Info   ]  [ ok ]
[12:09:10.186][Moby           ][Info   ]  * Checking local filesystems  ... [ ok ]
[12:09:10.389][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:10.514][Moby           ][Info   ]  * Remounting filesystems ... [ ok ]
[12:09:10.795][Moby           ][Info   ]  * Mounting local filesystems ... [ ok ]
[12:09:11.170][Moby           ][Info   ]  * Setting system clock using the hardware clock [UTC] ... [ ok ]
[12:09:11.483][Moby           ][Info   ]  * Setting hostname ... [ ok ]
[12:09:11.514][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:11.780][Moby           ][Info   ]  * sysklogd -> start: syslogd ... [ ok ]
[12:09:12.623][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:13.420][Moby           ][Info   ]  * sysklogd -> start: klogd ... [ ok ]
[12:09:13.702][Moby           ][Info   ]  * Starting busybox crond ... [ ok ]
[12:09:14.030][Moby           ][Info   ]  * Starting rngd ... [ ok ]
[12:09:14.295][Moby           ][Info   ]  * Setting sysfs variables ... [ ok ]
[12:09:14.577][Moby           ][Info   ]  * Starting local ... [ ok ]
[12:09:15.280][Moby           ][Info   ]  * Configuring host block device ... [ ok ]
[12:09:15.358][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:15.608][Moby           ][Info   ]  * Activating swap devices ... [ ok ]
[12:09:16.217][Moby           ][Info   ]  * Configuring host settings from database .../sbin/9pmount-vsock: starting in listening mode with serviceid=C378280D-DA14-42C8-A24E-0DE92A1028E2, tag=db, path=/Database
[12:09:16.686][Moby           ][Info   ] /sbin/9pmount-vsock: Connect from: 00000000-0000-0000-0000-000000000000:a343a0a2-a73b-11e6-a922-5c93a289f222
[12:09:17.045][Moby           ][Info   ] /sbin/9pmount-vsock: mount successful for serviceid=C378280D-DA14-42C8-A24E-0DE92A1028E2 tag=db path=/Database
[12:09:17.373][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:18.280][Moby           ][Info   ]  [ ok ]
[12:09:18.498][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:18.608][Moby           ][Info   ]  * Configuring kernel parameters ... [ ok ]
[12:09:19.124][Moby           ][Info   ]  * Creating user login records ... [ ok ]
[12:09:19.342][Moby           ][Info   ]  * Wiping /tmp directory ... [ ok ]
[12:09:19.623][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:19.717][Moby           ][Info   ]  * Starting vsock proxy ... [ ok ]
[12:09:20.092][Moby           ][Info   ]  * sysklogd -> start: syslogd ... [ ok ]
[12:09:21.936][Moby           ][Info   ]  * sysklogd -> start: klogd ... [ ok ]
[12:09:22.233][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:22.514][Moby           ][Info   ]  * Starting VPN proxy ... [ ok ]
[12:09:24.108][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:24.217][VpnKit         ][Info   ] com.docker.slirp.exe: hvsock connected successfully
[12:09:24.248][VpnKit         ][Info   ] com.docker.slirp.exe: attempting to reconnect to database
[12:09:24.295][VpnKit         ][Info   ] com.docker.slirp.exe: reconnected transport layer
[12:09:24.327][VpnKit         ][Info   ] com.docker.slirp.exe: remove connection limit
[12:09:24.358][VpnKit         ][Info   ] com.docker.slirp.exe: updating search domains to ams.ltr.eng.sita.aero
[12:09:24.389][VpnKit         ][Info   ] com.docker.slirp.exe: updating resolvers to nameserver 10.60.101.57#53
[12:09:24.436][VpnKit         ][Info   ] nameserver 57.6.189.34#53
[12:09:24.467][VpnKit         ][Info   ] com.docker.slirp.exe: using DNS forwarders on 10.60.101.57#53; 57.6.189.34#53
[12:09:24.498][VpnKit         ][Info   ] com.docker.slirp.exe: allowing binds to any IP addresses
[12:09:24.545][VpnKit         ][Info   ] com.docker.slirp.exe: Creating slirp server pcap_settings:disabled peer_ip:192.168.65.2 local_ip:192.168.65.1 domain_search:ams.ltr.eng.sita.aero
[12:09:24.593][VpnKit         ][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"))
[12:09:24.623][VpnKit         ][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")
[12:09:24.655][VpnKit         ][Info   ] com.docker.slirp.exe: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee))
[12:09:24.686][VpnKit         ][Debug  ] com.docker.slirp.exe: accepted vmnet connection
[12:09:24.733][VpnKit         ][Debug  ] com.docker.slirp.exe: Disabling any active packet capture
[12:09:24.764][VpnKit         ][Info   ] com.docker.slirp.exe: PPP.listen: called a second time: doing nothing
[12:09:24.733][Moby           ][Info   ]  * Starting DHCP Client Daemon ... [ ok ]
[12:09:24.795][VpnKit         ][Info   ] com.docker.slirp.exe: TCP/IP ready
[12:09:24.873][VpnKit         ][Info   ] com.docker.slirp.exe: stack connected
[12:09:24.905][VpnKit         ][Info   ] com.docker.slirp.exe: starting introspection intrspection_url:
[12:09:24.952][VpnKit         ][Error  ] com.docker.slirp.exe: start_introspection_server: caught Uwt.Uwt_error(Uwt.EACCES, "pipe_bind", "")
[12:09:24.998][VpnKit         ][Debug  ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 345
[12:09:25.030][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[12:09:25.061][VpnKit         ][Info   ] com.docker.slirp.exe: BOOTREQUEST from fa:37:7c:28:aa:f9
[12:09:25.108][VpnKit         ][Info   ] com.docker.slirp.exe: BOOTREPLY to fa:37:7c:28:aa:f9 yiddr 192.168.65.2 siddr 192.168.65.1 dns 192.168.65.1, 192.168.65.10, 192.168.65.9, 192.168.65.8, 192.168.65.7, 192.168.65.6, 192.168.65.5, 192.168.65.4, 192.168.65.3 router 192.168.65.1 domain local
[12:09:25.155][VpnKit         ][Debug  ] com.docker.slirp.exe: UDP 0.0.0.0:68 -> 255.255.255.255:67 len 355
[12:09:25.202][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:0.0.0.0:68-255.255.255.255:67: ignoring broadcast packet
[12:09:25.217][Moby           ][Info   ]  * Starting networking ... *   lo ... [ ok ]
[12:09:25.264][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:25.764][Moby           ][Info   ]  * Starting busybox crond ... [ ok ]
[12:09:26.092][Moby           ][Info   ]  * Starting rngd ... [ ok ]
[12:09:26.389][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:26.780][Moby           ][Info   ]  * Starting VPN proxy ... [ ok ]
[12:09:27.108][Moby           ][Info   ]  * Starting busybox acpid ... [ ok ]
[12:09:27.842][Moby           ][Info   ]  * Setting up proxy port service .../sbin/9pmount-vsock: starting in listening mode with serviceid=0B95756A-9985-48AD-9470-78E060895BE7, tag=port, path=/port
[12:09:28.248][VpnKit         ][Info   ] com.docker.slirp.exe: hvsock connected successfully
[12:09:28.295][VpnKit         ][Info   ] com.docker.slirp.exe: Using protocol TwoThousand msize 4096
[12:09:28.655][Moby           ][Info   ] /sbin/9pmount-vsock: Connect from: 00000000-0000-0000-0000-000000000000:a343a0ac-a73b-11e6-a922-5c93a289f222
[12:09:29.061][Moby           ][Info   ] /sbin/9pmount-vsock: mount successful for serviceid=0B95756A-9985-48AD-9470-78E060895BE7 tag=port path=/port
[12:09:29.108][Moby           ][Info   ]  [ ok ]
[12:09:29.108][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:09:31.170][PowerShell     ][Info   ] Run script 'Import-Module Dism; Get-WindowsOptionalFeature -Online | where FeatureName -like "*Hyper-V*" | where State -ne Enabled'...
[12:09:31.499][Moby           ][Info   ]  * Starting Docker ... [ ok ]
[12:09:32.123][Moby           ][Info   ]  * Starting VPN proxy ... [ ok ]
[12:09:32.514][Moby           ][Info   ]  * Configuring binfmt misc ... [ ok ]
[12:09:33.077][Moby           ][Info   ]  * Running system containerd ... [ ok ]
[12:09:33.420][Moby           ][Info   ]  * Checking system state ...
[12:09:33.592][Moby           ][Info   ] ? Drive found: sda
[12:09:33.920][Moby           ][Info   ] ? Drive mounted: /dev/sda2 on /var type ext4 (rw,relatime,data=ordered)
[12:09:34.280][Moby           ][Info   ] ? Network connected:           inet addr:192.168.65.2  Bcast:192.168.65.15  Mask:255.255.255.240
[12:09:34.498][Moby           ][Info   ] ? Process tap-vsockd running
[12:09:35.061][Moby           ][Info   ] ? Process dockerd running: /usr/bin/dockerd --pidfile=/run/docker.pid -H unix:///var/run/docker.sock --swarm-default-advertise-addr=eth0 --debug --storage-driver overlay2
[12:09:35.952][Moby           ][Info   ] ? Process containerd running: docker-containerd -l unix:///var/run/docker/libcontainerd/docker-containerd.sock --shim docker-containerd-shim --metrics-interval=0 --start-timeout 2m --state-dir /var/run/docker/libcontainerd/containerd --runtime docker-runc --debug
[12:09:38.952][Moby           ][Info   ] ? Docker daemon working: Docker version 1.12.3, build 6b644ec, experimental
[12:09:39.248][Moby           ][Info   ] ? Diagnostics server running: /usr/bin/diagnostics-server -hvsock
[12:09:39.514][Moby           ][Info   ] ? System containerd server running: /usr/bin/containerd
[12:09:40.014][Moby           ][Info   ] ? System containerd working
[12:09:40.358][VpnKit         ][Debug  ] com.docker.slirp.exe: ARP responding to: who-has 192.168.65.1?
[12:09:40.405][VpnKit         ][Debug  ] com.docker.slirp.exe: ARP responding to: who-has 192.168.65.10?
[12:09:40.436][VpnKit         ][Debug  ] com.docker.slirp.exe: ARP responding to: who-has 192.168.65.9?
[12:09:40.483][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] 192.168.65.2:32895 -> 192.168.65.1 a226 Query:0 na:c:r:rn 0 <qs:moby.ams.ltr.eng.sita.aero. <A|IN>> <an:> <au:> <ad:>
[12:09:40.530][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] Forwarding to 10.60.101.57 (0)
[12:09:40.452][Moby           ][Info   ]  * Starting Hyper-V daemon: hv_kvp_daemon ... [ ok ]
[12:09:40.561][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:32895-10.60.101.57:53 (DNS[a226]): creating UDP NAT rule
[12:09:40.623][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] 192.168.65.2:32895 -> 192.168.65.10 a226 Query:0 na:c:r:rn 0 <qs:moby.ams.ltr.eng.sita.aero. <A|IN>> <an:> <au:> <ad:>
[12:09:40.670][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] Forwarding to 57.6.189.34 (1)
[12:09:40.702][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:32895-10.60.101.57:53 (DNS[a226]): expiring UDP NAT rule immediately
[12:09:40.733][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] 192.168.65.2:32895 <- 1 (a226 Response:0 a:c:r:ra 3 <qs:moby.ams.ltr.eng.sita.aero. <A|IN>> <an:> <au:ams.ltr.eng.sita.aero <IN|3600> [SOA (devsvr2008.ams.ltr.eng.sita.aero,hostmaster.ams.ltr.eng.sita.aero, 20738,900,600,86400,3600)]> <ad:>)
[12:09:40.764][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] 192.168.65.2:32895 -> 192.168.65.9 a226 Query:0 na:c:r:rn 0 <qs:moby.ams.ltr.eng.sita.aero. <A|IN>> <an:> <au:> <ad:>
[12:09:40.811][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] Forwarding to 10.60.101.57 (2)
[12:09:40.842][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:32895-10.60.101.57:53 (DNS[a226]): creating UDP NAT rule
[12:09:40.889][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:32895-10.60.101.57:53 (DNS[a226]): expiring UDP NAT rule immediately
[12:09:40.936][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[a226] 192.168.65.2:32895 <- 2 (a226 Response:0 a:c:r:ra 3 <qs:moby.ams.ltr.eng.sita.aero. <A|IN>> <an:> <au:ams.ltr.eng.sita.aero <IN|3600> [SOA (devsvr2008.ams.ltr.eng.sita.aero,hostmaster.ams.ltr.eng.sita.aero, 20738,900,600,86400,3600)]> <ad:>)
[12:09:40.967][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.1 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:40.998][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (0)
[12:09:40.920][Moby           ][Info   ]  * Starting Hyper-V daemon: hv_vss_daemon ... [ ok ]
[12:09:41.045][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:41.108][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.10 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:41.139][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 57.6.189.34 (1)
[12:09:41.186][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:41.233][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:41.264][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:41.311][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:41.342][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:41.389][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:41.420][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:41.467][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:41.358][Moby           ][Info   ]  * Adjusting oom killer settings ... [ ok ]
[12:09:41.514][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:41.577][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:41.608][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:41.639][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:41.686][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:41.592][Moby           ][Info   ] 
[12:09:41.733][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:41.764][Moby           ][Info   ] Welcome to Moby
[12:09:41.858][Moby           ][Info   ] 
[12:09:41.889][Moby           ][Info   ]                         ## 
[12:09:42.889][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.1 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:42.920][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (0)
[12:09:42.967][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:42.998][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.10 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:43.030][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 57.6.189.34 (1)
[12:09:43.061][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:43.108][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:43.139][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:43.186][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:43.217][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:43.248][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:43.280][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:43.327][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:43.358][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:43.389][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 -> 192.168.65.9 32e6 Query:0 na:c:r:rn 0 <qs:moby. <A|IN>> <an:> <au:> <ad:>
[12:09:43.420][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] Forwarding to 10.60.101.57 (2)
[12:09:43.452][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram.input udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): creating UDP NAT rule
[12:09:43.483][VpnKit         ][Debug  ] com.docker.slirp.exe: Socket.Datagram udp:192.168.65.2:33320-10.60.101.57:53 (DNS[32e6]): expiring UDP NAT rule immediately
[12:09:43.514][VpnKit         ][Debug  ] com.docker.slirp.exe: DNS[32e6] 192.168.65.2:33320 <- 2 (32e6 Response:0 na:c:r:ra 2 <qs:moby. <A|IN>> <an:> <au:> <ad:>)
[12:09:43.545][VpnKit         ][Error  ] com.docker.slirp.exe: PPP.listen callback caught (Invalid_argument
[12:09:43.577][VpnKit         ][Info   ]   "invalid bounds in Cstructs.get_uint32 [2861,18](4096) off=16 len=4")
[12:09:54.358][HyperV         ][Info   ] Hyper-V is running
[12:09:54.405][HyperV         ][Info   ] Create
[12:09:54.483][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...
[12:09:58.155][HyperV         ][Info   ] Using existing Switch: DockerNAT
[12:09:59.795][HyperV         ][Info   ] Using existing Switch IP address
[12:10:00.108][HyperV         ][Info   ] VM MobyLinuxVM is Running. Cannot change its settings.
[12:10:00.139][HyperV         ][Info   ] Stop
[12:10:00.202][PowerShell     ][Info   ] Run script with parameters: -Stop True...
[12:10:00.545][HyperV         ][Info   ] Stopping VM MobyLinuxVM...
[12:10:05.655][SegmentApi     ][Info   ] Usage statistic: actionMenuSettings
[12:10:09.996][Moby           ][Info   ] / # �[6n * Stopping local ... [ ok ]
[12:10:10.761][Moby           ][Info   ]  * Stopping rngd ... [ ok ]
[12:10:11.112][Moby           ][Info   ]  * Saving random seed ... [ ok ]
[12:10:11.787][Moby           ][Info   ]  * Stopping Hyper-V daemon: hv_vss_daemon ... [ ok ]
[12:10:11.920][HyperV         ][Info   ] Kill VM MobyLinuxVM process...
[12:10:12.029][Moby           ][Info   ]  * Sto
[12:10:12.085][VpnKit         ][Error  ] com.docker.slirp.exe: Hvsock.read raised Unix.Unix_error(Unix.ECONNABORTED, "read", ""): returning `Eof
[12:10:12.150][VpnKit         ][Error  ] com.docker.slirp.exe: Hvsock.read raised Unix.Unix_error(Unix.ECONNABORTED, "read", ""): returning `Eof
[12:10:12.203][VpnKit         ][Info   ] com.docker.slirp.exe: PPP.listen: closing connection
[12:10:12.086][DataKit        ][Info   ] 
[12:10:12.297][DataKit        ][Info   ] +160838109us flow_lwt_hvsock [ERROR] Hvsock.read raised Unix.Unix_error(Unix.ECONNABORTED, "read", ""): returning `Eof
[12:10:13.134][HyperV         ][Info   ] Kill VM MobyLinuxVM process...
[12:10:14.264][HyperV         ][Info   ] VM MobyLinuxVM killed. Waiting for state to change
[12:10:17.379][HyperV         ][Info   ] Killed VM MobyLinuxVM is off
[12:10:17.435][VpnKit         ][Info   ] Killing existing com.docker.slirp with PID 7960
[12:10:17.484][DataKit        ][Info   ] 
[12:10:17.532][DataKit        ][Info   ] +166235972us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:10:17.502][DataKit        ][Info   ] Killing existing com.docker.db with PID 7936
[12:10:17.635][HyperVGuids    ][Info   ] Installing GUIDs...
[12:10:17.675][HyperVGuids    ][Info   ] GUIDs installed
[12:10:17.709][Firewall       ][Info   ] Opening ports for C:\Program Files\Docker\Docker\Resources\com.docker.proxy.exe...
[12:10:32.002][Firewall       ][Info   ] Ports are opened
[12:10:32.047][HyperV         ][Info   ] Create
[12:10:32.088][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...
[12:10:34.923][HyperV         ][Info   ] Using existing Switch: DockerNAT
[12:10:35.370][HyperV         ][Info   ] Using existing Switch IP address
[12:10:35.677][HyperV         ][Info   ] Setting CPUs to 2 and Memory to 2048 MB
[12:10:39.036][HyperV         ][Info   ] Connect Internal Switch DockerNAT
[12:10:43.942][HyperV         ][Info   ] VM created.
[12:10:43.988][PowerShell     ][Info   ] Run script...
[12:10:44.169][DataKit        ][Info   ] Starting C:\Program Files\Docker\Docker\Resources\com.docker.db.exe --url hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39,\\.\pipe\dockerDataBase --color never
[12:10:44.923][DataKit        ][Info   ] Started
[12:10:44.972][PowerShell     ][Info   ] Run script...
[12:10:45.136][VpnKit         ][Info   ] Starting C:\Program Files\Docker\Docker\Resources\com.docker.slirp.exe --ethernet hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 --port hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 --db \\.\pipe\dockerDataBase --debug
[12:10:45.236][DataKit        ][Info   ] 
[12:10:45.274][DataKit        ][Info   ] +51572us    Datakit  Starting com.docker.db.exe 09081b4 ...
[12:10:45.561][VpnKit         ][Info   ] Started
[12:10:45.616][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/config=static
[12:10:45.662][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/address=10.0.75.2
[12:10:45.705][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/netmask=255.255.255.0
[12:10:45.752][Database       ][Info   ] Writing com.docker.driver.amd64-linux/net/gateway=10.0.75.1
[12:10:45.799][Database       ][Info   ] Writing com.docker.driver.amd64-linux/network=hybrid
[12:10:45.775][VpnKit         ][Info   ] com.docker.slirp.exe: Logging to stdout (stdout:true DEBUG:false)
[12:10:45.892][VpnKit         ][Info   ] com.docker.slirp.exe: Setting handler to ignore all SIGPIPE signals
[12:10:45.926][VpnKit         ][Info   ] com.docker.slirp.exe: vpnkit version %VERSION% with hostnet version local %HOSTNET_PINNED% uwt version 0.0.3 hvsock version 0.10.0 %HVSOCK_PINNED%
[12:10:45.846][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/docker/daemon.json={"registry-mirrors":[],"insecure-registries":[]}
[12:10:46.010][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/sysctl.conf=
[12:10:46.054][Database       ][Info   ] Writing com.docker.driver.amd64-linux/native/port-forwarding=true
[12:10:45.967][VpnKit         ][Info   ] com.docker.slirp.exe: starting port_forwarding port_control_url:hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39 vsock_path:
[12:10:46.147][VpnKit         ][Info   ] com.docker.slirp.exe: connecting to 82105d08-aeee-42bb-9ba2-d918ab806b39:0B95756A-9985-48AD-9470-78E060895BE7
[12:10:46.185][VpnKit         ][Debug  ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39
[12:10:46.222][VpnKit         ][Info   ] com.docker.slirp.exe: connecting to 82105d08-aeee-42bb-9ba2-d918ab806b39:30D48B34-7D27-4B0B-AAAF-BBBED334DD59
[12:10:46.266][VpnKit         ][Debug  ] com.docker.slirp.exe: Waiting for connections on socket hyperv-connect://82105d08-aeee-42bb-9ba2-d918ab806b39
[12:10:46.299][VpnKit         ][Info   ] com.docker.slirp.exe: hosts file has bindings for 
[12:10:47.847][DataKit        ][Info   ] 
[12:10:47.895][DataKit        ][Info   ] +2662921us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:10:47.856][Database       ][Info   ] Successfully wrote 8 value(s)
[12:10:47.968][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/exclude
[12:10:48.000][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/http
[12:10:48.041][Database       ][Info   ] Removing com.docker.driver.amd64-linux/proxy/https
[12:10:48.445][DataKit        ][Info   ] 
[12:10:48.482][DataKit        ][Info   ] +3260381us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:10:48.454][Database       ][Info   ] Successfully removed 3 value(s)
[12:10:48.711][Database       ][Info   ] Writing com.docker.driver.amd64-linux/etc/ssl/certs/ca-certificates.crt=C:\WINDOWS\TEMP\tmp2E43.tmp
[12:10:49.273][DataKit        ][Info   ] 
[12:10:49.283][Database       ][Info   ] Successfully wrote value
[12:10:49.310][DataKit        ][Info   ] +4089030us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:10:49.347][PowerShell     ][Info   ] Run script '$(Find-NetRoute -RemoteIPAddress 8.8.8.8).InterfaceIndex[0]'...
[12:10:49.792][Database       ][Info   ] Writing com.docker.driver.amd64-linux/slirp/dns=nameserver 10.60.101.57
nameserver 57.6.189.34
search ams.ltr.eng.sita.aero
[12:10:50.270][DataKit        ][Info   ] 
[12:10:50.305][DataKit        ][Info   ] +5085915us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[12:10:50.280][Database       ][Info   ] Successfully wrote 1 value(s)
[12:10:50.392][HyperV         ][Info   ] Start
[12:10:50.426][PowerShell     ][Info   ] Run script with parameters: -Start True...
[12:10:50.617][HyperV         ][Info   ] Starting VM MobyLinuxVM...
[12:10:54.107][Moby           ][Info   ] Connected
[12:10:55.286][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:10:56.475][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:10:57.602][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:10:58.635][Moby           ][Info   ] Parsing ELF... done.
[12:10:58.678][Moby           ][Info   ] Booting the kernel.
[12:10:58.713][Moby           ][Info   ] Linux version 4.8.5-moby (root@1d811a9194c4) (gcc version 5.3.0 (Alpine 5.3.0) ) #1 SMP Sun Oct 30 09:31:44 UTC 2016
[12:10:58.753][Moby           ][Info   ] Command line: earlyprintk=serial console=ttyS0 mobyplatform=windows
[12:10:58.802][Moby           ][Info   ] 
[12:10:58.840][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[12:10:58.884][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[12:10:58.922][Moby           ][Info   ] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[12:10:58.956][Moby           ][Info   ] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[12:10:58.996][Moby           ][Info   ] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[12:10:59.035][Moby           ][Info   ] x86/fpu: Using 'eager' FPU context switches.
[12:10:59.077][Moby           ][Info   ] e820: BIOS-provided physical RAM map:
[12:10:59.121][Moby           ][Info   ] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[12:10:59.158][Moby           ][Info   ] BIOS-e820: [mem 0x0000000000100000-0x000000007eee5fff] usable
[12:10:59.197][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eee6000-0x000000007eee6fff] ACPI data
[12:10:59.240][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eee7000-0x000000007eeedfff] reserved
[12:10:59.272][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eeee000-0x000000007eef1fff] ACPI data
[12:10:59.306][Moby           ][Info   ] BIOS-e820: [mem 0x000000007eef2000-0x000000007ef1afff] reserved
[12:10:59.340][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ef1b000-0x000000007ff9afff] usable
[12:10:59.382][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ff9b000-0x000000007fff2fff] reserved
[12:10:59.420][Moby           ][Info   ] BIOS-e820: [mem 0x000000007fff3000-0x000000007fffafff] ACPI data
[12:10:59.454][Moby           ][Info   ] BIOS-e820: [mem 0x000000007fffb000-0x000000007fffefff] ACPI NVS
[12:10:59.491][Moby           ][Info   ] BIOS-e820: [mem 0x000000007ffff000-0x000000007fffffff] usable
[12:10:59.534][Moby           ][Info   ] bootconsole [earlyser0] enabled
[12:10:59.575][Moby           ][Info   ] ERROR: earlyprintk= earlyser already used
[12:10:59.613][Moby           ][Info   ] NX (Execute Disable) protection: active
[12:10:59.634][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:10:59.659][Moby           ][Info   ] efi: EFI v2.31 by EDK II
[12:10:59.744][Moby           ][Info   ] efi:  ACPI=0x7fffa000  ACPI 2.0=0x7fffa014  SMBIOS=0x7fff2000  PROP=0x419a3d8  MEMATTR=0x7f4d7d18 
[12:10:59.787][Moby           ][Info   ] SMBIOS 2.4 present.
[12:10:59.829][Moby           ][Info   ] Hypervisor detected: Microsoft HyperV
[12:10:59.881][Moby           ][Info   ] HyperV: features 0x2e7f, hints 0x2c2c
[12:10:59.923][Moby           ][Info   ] HyperV: LAPIC Timer Frequency: 0x1e8480
[12:10:59.965][Moby           ][Info   ] clocksource: hyperv_clocksource: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[12:11:00.014][Moby           ][Info   ] tsc: Marking TSC unstable due to running on Hyper-V
[12:11:00.071][Moby           ][Info   ] e820: last_pfn = 0x80000 max_arch_pfn = 0x400000000
[12:11:00.114][Moby           ][Info   ] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[12:11:00.157][Moby           ][Info   ] Using GB pages for direct mapping
[12:11:00.219][Moby           ][Info   ] RAMDISK: [mem 0x77eaa000-0x7b3abfff]
[12:11:00.269][Moby           ][Info   ] ACPI: Early table checksum verification disabled
[12:11:00.313][Moby           ][Info   ] ACPI: RSDP 0x000000007FFFA014 000024 (v02 VRTUAL)
[12:11:00.360][Moby           ][Info   ] ACPI: XSDT 0x000000007FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.409][Moby           ][Info   ] ACPI: FACP 0x000000007FFF7000 0000F4 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.458][Moby           ][Info   ] ACPI: DSDT 0x000000007EEEF000 00256F (v02 MSFTVM DSDT01   00000001 MSFT 04000000)
[12:11:00.508][Moby           ][Info   ] ACPI: FACS 0x000000007FFFE000 000040
[12:11:00.555][Moby           ][Info   ] ACPI: APIC 0x000000007FFF8000 0007D2 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.597][Moby           ][Info   ] ACPI: OEM0 0x000000007FFF6000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.641][Moby           ][Info   ] ACPI: WAET 0x000000007FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.686][Moby           ][Info   ] ACPI: OEM1 0x000000007FFF4000 00009E (v02 MSFTVM UARTS    00000001 MSFT 04000000)
[12:11:00.729][Moby           ][Info   ] ACPI: OEM2 0x000000007FFF3000 0002E2 (v02 MSFTVM VTPM2    00000001 MSFT 04000000)
[12:11:00.775][Moby           ][Info   ] ACPI: SRAT 0x000000007EEEE000 000190 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.822][Moby           ][Info   ] ACPI: BGRT 0x000000007EEE6000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
[12:11:00.884][Moby           ][Info   ] Zone ranges:
[12:11:00.933][Moby           ][Info   ]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[12:11:01.006][Moby           ][Info   ]   DMA32    [mem 0x0000000001000000-0x000000007fffffff]
[12:11:01.053][Moby           ][Info   ]   Normal   empty
[12:11:01.121][Moby           ][Info   ] Movable zone start for each node
[12:11:01.185][Moby           ][Info   ] Early memory node ranges
[12:11:01.239][Moby           ][Info   ]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
[12:11:01.300][Moby           ][Info   ]   node   0: [mem 0x0000000000100000-0x000000007eee5fff]
[12:11:01.362][Moby           ][Info   ]   node   0: [mem 0x000000007ef1b000-0x000000007ff9afff]
[12:11:01.429][Moby           ][Info   ]   node   0: [mem 0x000000007ffff000-0x000000007fffffff]
[12:11:01.481][Moby           ][Info   ] Initmem setup node 0 [mem 0x0000000000001000-0x000000007fffffff]
[12:11:01.544][Moby           ][Info   ] ACPI: PM-Timer IO Port: 0x408
[12:11:01.597][Moby           ][Info   ] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[12:11:01.638][Moby           ][Info   ] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23
[12:11:01.679][Moby           ][Info   ] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[12:11:01.729][Moby           ][Info   ] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[12:11:01.774][Moby           ][Info   ] Using ACPI (MADT) for SMP configuration information
[12:11:01.810][Moby           ][Info   ] smpboot: 240 Processors exceeds NR_CPUS limit of 4
[12:11:01.860][Moby           ][Info   ] smpboot: Allowing 4 CPUs, 2 hotplug CPUs
[12:11:01.907][Moby           ][Info   ] e820: [mem 0x80000000-0xffffffff] available for PCI devices
[12:11:01.944][Moby           ][Info   ] Booting paravirtualized kernel on bare hardware
[12:11:01.996][Moby           ][Info   ] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[12:11:02.037][Moby           ][Info   ] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[12:11:02.071][Moby           ][Info   ] percpu: Embedded 35 pages/cpu @ffff88007f000000 s104984 r8192 d30184 u524288
[12:11:02.116][Moby           ][Info   ] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 515791
[12:11:02.156][Moby           ][Info   ] Kernel command line: earlyprintk=serial console=ttyS0 mobyplatform=windows
[12:11:02.194][Moby           ][Info   ] 
[12:11:02.240][Moby           ][Info   ] PID hash table entries: 4096 (order: 3, 32768 bytes)
[12:11:02.283][Moby           ][Info   ] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[12:11:02.329][Moby           ][Info   ] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[12:11:02.377][Moby           ][Info   ] Memory: 1971912K/2096152K available (8571K kernel code, 1397K rwdata, 2964K rodata, 1380K init, 572K bss, 124240K reserved, 0K cma-reserved)
[12:11:02.422][Moby           ][Info   ] Hierarchical RCU implementation.
[12:11:02.467][Moby           ][Info   ]    Build-time adjustment of leaf fanout to 64.
[12:11:02.514][Moby           ][Info   ] Using NULL legacy PIC
[12:11:02.553][Moby           ][Info   ] NR_IRQS:4352 nr_irqs:456 0
[12:11:02.605][Moby           ][Info   ] Console: colour dummy device 80x25
[12:11:02.653][Moby           ][Info   ] console [ttyS0] enabled
[12:11:02.563][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:02.697][Moby           ][Info   ] console [ttyS0] enabled
[12:11:02.785][Moby           ][Info   ] bootconsole [earlyser0] disabled
[12:11:02.827][Moby           ][Info   ] bootconsole [earlyser0] disabled
[12:11:02.872][Moby           ][Info   ] tsc: Fast TSC calibration failed
[12:11:02.907][Moby           ][Info   ] tsc: Unable to calibrate against PIT
[12:11:02.943][Moby           ][Info   ] tsc: using PMTIMER reference calibration
[12:11:02.982][Moby           ][Info   ] tsc: Detected 2778.775 MHz processor
[12:11:03.018][Moby           ][Info   ] Calibrating delay loop (skipped), value calculated using timer frequency.. 5557.55 BogoMIPS (lpj=27787750)
[12:11:03.051][Moby           ][Info   ] pid_max: default: 32768 minimum: 301
[12:11:03.089][Moby           ][Info   ] ACPI: Core revision 20160422
[12:11:03.127][Moby           ][Info   ] ACPI: 1 ACPI AML tables successfully acquired and loaded
[12:11:03.160][Moby           ][Info   ] 
[12:11:03.202][Moby           ][Info   ] Security Framework initialized
[12:11:03.234][Moby           ][Info   ] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[12:11:03.278][Moby           ][Info   ] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[12:11:03.318][Moby           ][Info   ] CPU: Physical Processor ID: 0
[12:11:03.354][Moby           ][Info   ] CPU: Processor Core ID: 0
[12:11:03.391][Moby           ][Info   ] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[12:11:03.434][Moby           ][Info   ] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[12:11:03.478][Moby           ][Info   ] Freeing SMP alternatives memory: 24K (ffffffff820b8000 - ffffffff820be000)
[12:11:03.511][Moby           ][Info   ] ftrace: allocating 37960 entries in 149 pages
[12:11:03.552][Moby           ][Info   ] smpboot: APIC(0) Converting physical 0 to logical package 0
[12:11:03.595][Moby           ][Info   ] smpboot: Max logical packages: 120
[12:11:03.636][Moby           ][Info   ] smpboot: CPU0: Intel(R) Core(TM) i7-4810MQ CPU @ 2.80GHz (family: 0x6, model: 0x3c, stepping: 0x3)
[12:11:03.681][Moby           ][Info   ] Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
[12:11:03.723][Moby           ][Info   ] NMI watchdog: disabled (cpu0): hardware events not enabled
[12:11:03.773][Moby           ][Info   ] NMI watchdog: Shutting down hard lockup detector on all cpus
[12:11:03.818][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:03.819][Moby           ][Info   ] x86: Booting SMP configuration:
[12:11:03.903][Moby           ][Info   ] .... node  #0, CPUs:      #1
[12:11:03.941][Moby           ][Info   ] calibrate_delay_direct() dropping max bogoMips estimate 1 = 39933740
[12:11:04.006][Moby           ][Info   ] x86: Booted up 1 node, 2 CPUs
[12:11:04.251][Moby           ][Info   ] smpboot: Total of 2 processors activated (11276.69 BogoMIPS)
[12:11:04.356][Moby           ][Info   ] devtmpfs: initialized
[12:11:04.527][Moby           ][Info   ] x86/mm: Memory block size: 128MB
[12:11:05.102][Moby           ][Info   ] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[12:11:05.315][Moby           ][Info   ] NET: Registered protocol family 16
[12:11:05.522][Moby           ][Info   ] cpuidle: using governor ladder
[12:11:05.705][Moby           ][Info   ] cpuidle: using governor menu
[12:11:05.707][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:05.860][Moby           ][Info   ] ACPI: bus type PCI registered
[12:11:06.000][Moby           ][Info   ] PCI: Fatal: No config space access function found
[12:11:06.352][Moby           ][Info   ] HugeTLB registered 1 GB page size, pre-allocated 0 pages
[12:11:06.573][Moby           ][Info   ] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[12:11:06.836][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:06.866][Moby           ][Info   ] ACPI: Added _OSI(Module Device)
[12:11:07.028][Moby           ][Info   ] ACPI: Added _OSI(Processor Device)
[12:11:07.235][Moby           ][Info   ] ACPI: Added _OSI(3.0 _SCP Extensions)
[12:11:07.461][Moby           ][Info   ] ACPI: Added _OSI(Processor Aggregator Device)
[12:11:07.780][Moby           ][Info   ] ACPI: Executed 1 blocks of module-level executable AML code
[12:11:07.952][Moby           ][Info   ] ACPI: Dynamic OEM Table Load:
[12:11:08.326][Moby           ][Info   ] ACPI: OEM1 0x0000000000000000 00009E (v02 MSFTVM UARTS    00000001 MSFT 04000000)
[12:11:08.440][Moby           ][Info   ] ACPI: Interpreter enabled
[12:11:08.575][Moby           ][Info   ] ACPI: (supports S0 S5)
[12:11:08.750][Moby           ][Info   ] ACPI: Using IOAPIC for interrupt routing
[12:11:09.219][Moby           ][Info   ] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[12:11:09.509][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:09.567][Moby           ][Info   ] ACPI: Enabled 1 GPEs in block 00 to 0F
[12:11:09.658][Moby           ][Info   ] vgaarb: loaded
[12:11:09.829][Moby           ][Info   ] SCSI subsystem initialized
[12:11:10.042][Moby           ][Info   ] pps_core: LinuxPPS API ver. 1 registered
[12:11:10.454][Moby           ][Info   ] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[12:11:10.614][Moby           ][Info   ] PTP clock support registered
[12:11:10.754][Moby           ][Info   ] wmi: Mapper loaded
[12:11:11.334][Moby           ][Info   ] clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[12:11:11.431][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:11.681][Moby           ][Info   ] hv_vmbus: Hyper-V Host Build:14393-10.0-0-0.206; Vmbus version:4.0
[12:11:11.852][Moby           ][Info   ] PCI: Using ACPI for IRQ routing
[12:11:12.026][Moby           ][Info   ] PCI: System does not support PCI
[12:11:12.145][Moby           ][Info   ] NetLabel: Initializing
[12:11:12.316][Moby           ][Info   ] NetLabel:  domain hash size = 128
[12:11:12.517][Moby           ][Info   ] NetLabel:  protocols = UNLABELED CIPSOv4
[12:11:12.559][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:12.743][Moby           ][Info   ] NetLabel:  unlabeled traffic allowed by default
[12:11:13.061][Moby           ][Info   ] clocksource: Switched to clocksource hyperv_clocksource_tsc_page
[12:11:13.263][Moby           ][Info   ] FS-Cache: Loaded
[12:11:13.418][Moby           ][Info   ] CacheFiles: Loaded
[12:11:13.504][Moby           ][Info   ] pnp: PnP ACPI init
[12:11:13.669][Moby           ][Info   ] pnp: PnP ACPI: found 3 devices
[12:11:13.706][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:14.036][Moby           ][Info   ] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[12:11:14.404][Moby           ][Info   ] NET: Registered protocol family 2
[12:11:14.791][Moby           ][Info   ] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[12:11:15.092][Moby           ][Info   ] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[12:11:15.367][Moby           ][Info   ] TCP: Hash tables configured (established 16384 bind 16384)
[12:11:15.614][Moby           ][Info   ] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[12:11:15.918][Moby           ][Info   ] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[12:11:16.144][Moby           ][Info   ] NET: Registered protocol family 1
[12:11:16.396][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:16.416][Moby           ][Info   ] RPC: Registered named UNIX socket transport module.
[12:11:16.602][Moby           ][Info   ] RPC: Registered udp transport module.
[12:11:16.767][Moby           ][Info   ] RPC: Registered tcp transport module.
[12:11:17.026][Moby           ][Info   ] RPC: Registered tcp NFSv4.1 backchannel transport module.
[12:11:17.142][Moby           ][Info   ] Unpacking initramfs...
[12:11:18.319][Moby           ][Info   ] random: fast init done
[12:11:18.458][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:19.597][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:20.748][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:23.635][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:25.600][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:26.744][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:27.211][Moby           ][Info   ] Freeing initrd memory: 54280K (ffff880077eaa000 - ffff88007b3ac000)
[12:11:27.607][Moby           ][Info   ] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 10737418240 ms ovfl timer
[12:11:27.800][Moby           ][Info   ] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules
[12:11:27.883][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:28.050][Moby           ][Info   ] RAPL PMU: hw unit of domain package 2^-0 Joules
[12:11:28.276][Moby           ][Info   ] RAPL PMU: hw unit of domain dram 2^-0 Joules
[12:11:28.542][Moby           ][Info   ] RAPL PMU: hw unit of domain pp1-gpu 2^-0 Joules
[12:11:28.973][Moby           ][Info   ] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x280dee545fa, max_idle_ns: 440795246467 ns
[12:11:29.218][Moby           ][Info   ] futex hash table entries: 1024 (order: 4, 65536 bytes)
[12:11:29.488][Moby           ][Info   ] workingset: timestamp_bits=46 max_order=19 bucket_order=0
[12:11:30.286][Moby           ][Info   ] FS-Cache: Netfs 'nfs' registered for caching
[12:11:30.551][Moby           ][Info   ] NFS: Registering the id_resolver key type
[12:11:30.558][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:30.701][Moby           ][Info   ] Key type id_resolver registered
[12:11:30.849][Moby           ][Info   ] Key type id_legacy registered
[12:11:31.116][Moby           ][Info   ] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[12:11:31.365][Moby           ][Info   ] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[12:11:31.759][Moby           ][Info   ] FS-Cache: Netfs 'cifs' registered for caching
[12:11:32.013][Moby           ][Info   ] ntfs: driver 2.1.32 [Flags: R/O].
[12:11:32.263][Moby           ][Info   ] fuse init (API version 7.25)
[12:11:32.478][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:32.621][Moby           ][Info   ] 9p: Installing v9fs 9p2000 file system support
[12:11:32.898][Moby           ][Info   ] FS-Cache: Netfs '9p' registered for caching
[12:11:33.156][Moby           ][Info   ] aufs 4.8-20161010
[12:11:33.327][Moby           ][Info   ] Key type big_key registered
[12:11:33.585][Moby           ][Info   ] NET: Registered protocol family 38
[12:11:33.603][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:33.772][Moby           ][Info   ] Key type asymmetric registered
[12:11:33.995][Moby           ][Info   ] Asymmetric key parser 'x509' registered
[12:11:34.356][Moby           ][Info   ] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[12:11:34.514][Moby           ][Info   ] io scheduler noop registered
[12:11:34.727][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:34.742][Moby           ][Info   ] io scheduler deadline registered (default)
[12:11:34.853][Moby           ][Info   ] io scheduler cfq registered
[12:11:35.082][Moby           ][Info   ] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[12:11:35.379][Moby           ][Info   ] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[12:11:35.670][Moby           ][Info   ] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[12:11:35.891][Moby           ][Info   ] hv_vmbus: registering driver hyperv_fb
[12:11:36.166][Moby           ][Info   ] hyperv_fb: Screen resolution: 1152x864, Color depth: 32
[12:11:36.535][Moby           ][Info   ] Console: switching to colour frame buffer device 144x54
[12:11:36.687][Moby           ][Info   ] efifb: probing for efifb
[12:11:36.931][Moby           ][Info   ] efifb: cannot reserve video memory at 0xf8000000
[12:11:37.249][Moby           ][Info   ] efifb: framebuffer at 0xf8000000, using 3072k, total 3072k
[12:11:37.452][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:37.481][Moby           ][Info   ] efifb: mode is 1024x768x32, linelength=4096, pages=1
[12:11:37.615][Moby           ][Info   ] efifb: scrolling: redraw
[12:11:37.871][Moby           ][Info   ] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
[12:11:38.025][Moby           ][Info   ] fb1: EFI VGA frame buffer device
[12:11:38.164][Moby           ][Info   ] GHES: HEST is not enabled!
[12:11:38.411][Moby           ][Info   ] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[12:11:39.363][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:39.587][Moby           ][Info   ] 00:01: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[12:11:40.492][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:40.649][Moby           ][Info   ] 00:02: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[12:11:40.811][Moby           ][Info   ] Initializing Nozomi driver 2.1d
[12:11:40.979][Moby           ][Info   ] Non-volatile memory driver v1.3
[12:11:41.414][Moby           ][Info   ] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[12:11:41.597][Moby           ][Info   ] [drm] Initialized drm 1.1.0 20060810
[12:11:41.631][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:41.715][Moby           ][Info   ] loop: module loaded
[12:11:41.881][Moby           ][Info   ] nbd: registered device at major 43
[12:11:42.075][Moby           ][Info   ] VMware PVSCSI driver - version 1.0.6.0-k
[12:11:42.253][Moby           ][Info   ] hv_vmbus: registering driver hv_storvsc
[12:11:42.408][Moby           ][Info   ] scsi host0: storvsc_host_t
[12:11:42.811][Moby           ][Info   ] scsi 0:0:0:0: Direct-Access     Msft     Virtual Disk     1.0  PQ: 0 ANSI: 5
[12:11:43.254][Moby           ][Info   ] scsi 0:0:0:1: CD-ROM            Msft     Virtual DVD-ROM  1.0  PQ: 0 ANSI: 0
[12:11:43.647][Moby           ][Info   ] sd 0:0:0:0: [sda] 125829120 512-byte logical blocks: (64.4 GB/60.0 GiB)
[12:11:43.876][Moby           ][Info   ] sr 0:0:0:1: [sr0] scsi3-mmc drive: 0x/0x caddy
[12:11:44.126][Moby           ][Info   ] cdrom: Uniform CD-ROM driver Revision: 3.20
[12:11:44.315][Moby           ][Info   ] sd 0:0:0:0: Attached scsi generic sg0 type 0
[12:11:44.350][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:44.545][Moby           ][Info   ] sr 0:0:0:1: Attached scsi generic sg1 type 5
[12:11:44.791][Moby           ][Info   ] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
[12:11:45.006][Moby           ][Info   ] tun: Universal TUN/TAP device driver, 1.6
[12:11:45.236][Moby           ][Info   ] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[12:11:45.523][Moby           ][Info   ] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[12:11:45.785][Moby           ][Info   ] e1000: Copyright (c) 1999-2006 Intel Corporation.
[12:11:46.063][Moby           ][Info   ] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[12:11:46.298][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:46.350][Moby           ][Info   ] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[12:11:46.774][Moby           ][Info   ] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 3.2.2-k
[12:11:47.041][Moby           ][Info   ] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation.
[12:11:47.198][Moby           ][Info   ] PPP generic driver version 2.4.2
[12:11:47.408][Moby           ][Info   ] PPP BSD Compression module registered
[12:11:47.431][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:47.624][Moby           ][Info   ] PPP Deflate Compression module registered
[12:11:47.828][Moby           ][Info   ] PPP MPPE Compression module registered
[12:11:47.952][Moby           ][Info   ] NET: Registered protocol family 24
[12:11:48.069][Moby           ][Info   ] PPTP driver version 0.8.5
[12:11:48.363][Moby           ][Info   ] VMware vmxnet3 virtual NIC driver - version 1.4.a.0-k-NAPI
[12:11:48.561][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:48.570][Moby           ][Info   ] hv_vmbus: registering driver hv_netvsc
[12:11:48.778][Moby           ][Info   ] sd 0:0:0:0: [sda] 4096-byte physical blocks
[12:11:48.960][Moby           ][Info   ] sd 0:0:0:0: [sda] Write Protect is off
[12:11:49.362][Moby           ][Info   ] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[12:11:49.448][Moby           ][Info   ]  sda: sda1 sda2
[12:11:49.619][Moby           ][Info   ] sd 0:0:0:0: [sda] Attached SCSI disk
[12:11:51.206][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:53.109][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:54.233][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:55.358][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:57.984][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:11:59.875][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:00.994][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:02.116][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:04.689][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:06.589][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:07.714][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:08.835][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:11.390][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:13.293][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:14.415][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:15.539][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:18.120][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:20.035][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:21.156][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:22.276][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:24.875][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:26.768][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:27.896][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:29.019][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:31.604][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:33.494][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:34.616][HyperV         ][Info   ] Wait for the VM to get an IP address
[12:12:36.154][NamedPipeServer][Error  ] Unable to execute Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, <No file>: line 353
at Start-MobyLinuxVM, <No file>: line 267
at <ScriptBlock>, <No file>: line 363    at Docker.Backend.HyperV.RunScript(String action, Dictionary`2 parameters)
   at Docker.Backend.ContainerEngine.Linux.DoStart(Settings settings)
   at Docker.Backend.ContainerEngine.Linux.Start(Settings settings)
   at Docker.Core.Pipe.NamedPipeServer.<>c__DisplayClass8_0.<Register>b__0(Object[] parameters)
   at Docker.Core.Pipe.NamedPipeServer.RunAction(String action, Object[] parameters)
[12:12:36.305][NamedPipeClient][Error  ] Unable to send Start: Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, <No file>: line 353
at Start-MobyLinuxVM, <No file>: line 267
at <ScriptBlock>, <No file>: line 363
[12:12:36.428][Notifications  ][Error  ] Unable to start: The VM couldn't get an IP address after 60 tries
at Fatal, <No file>: line 353
at Start-MobyLinuxVM, <No file>: line 267
at <ScriptBlock>, <No file>: line 363
[12:13:36.134][Moby           ][Info   ] random: crng init done
[12:15:19.978][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:15:20.125][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:15:20.468][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:15:20.811][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:15:21.146][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:15:21.501][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:15:21.873][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:15:21.946][Moby           ][Info   ] Call Trace:
[12:15:22.155][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:15:22.384][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:15:22.643][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:15:22.898][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:15:23.172][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:15:23.438][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:15:23.687][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:15:23.885][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:15:24.142][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:15:24.363][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:15:24.605][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:15:24.876][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:15:25.103][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:15:25.390][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:15:25.627][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:15:25.870][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:15:26.106][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:15:26.370][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:15:26.612][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:15:26.859][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:15:27.101][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:15:27.339][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:15:27.533][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:15:27.752][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:15:27.962][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:15:28.170][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:15:28.414][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:15:28.675][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:15:28.862][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:15:29.077][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:15:29.292][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:17:33.097][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:17:33.259][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:17:33.601][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:17:33.859][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:17:34.192][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:17:34.537][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:17:34.868][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:17:34.929][Moby           ][Info   ] Call Trace:
[12:17:35.136][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:17:35.308][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:17:35.543][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:17:35.760][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:17:36.003][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:17:36.245][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:17:36.486][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:17:36.680][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:17:36.960][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:17:37.175][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:17:37.396][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:17:37.665][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:17:37.902][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:17:38.168][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:17:38.343][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:17:38.569][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:17:38.777][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:17:39.014][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:17:39.239][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:17:39.489][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:17:39.716][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:17:39.935][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:17:40.127][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:17:40.363][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:17:40.563][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:17:40.784][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:17:40.995][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:17:41.236][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:17:41.433][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:17:41.664][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:17:41.868][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:19:46.235][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:19:46.381][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:19:46.702][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:19:47.111][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:19:47.878][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:19:48.449][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:19:50.063][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:19:50.196][Moby           ][Info   ] Call Trace:
[12:19:50.819][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:19:51.432][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:19:52.035][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:19:52.589][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:19:53.320][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:19:53.804][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:19:54.359][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:19:54.838][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:19:55.306][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:19:55.569][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:19:55.920][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:19:56.304][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:19:56.707][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:19:57.243][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:19:57.495][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:19:57.757][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:19:58.032][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:19:58.396][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:19:58.654][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:19:58.960][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:19:59.205][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:19:59.467][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:19:59.670][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:19:59.912][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:20:00.109][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:20:00.383][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:20:00.656][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:20:00.983][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:20:01.221][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:20:01.482][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:20:01.900][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:22:04.776][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:22:05.054][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:22:05.419][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:22:05.801][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:22:06.221][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:22:06.616][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:22:07.020][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:22:07.104][Moby           ][Info   ] Call Trace:
[12:22:07.355][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:22:07.568][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:22:07.823][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:22:08.090][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:22:08.288][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:22:08.702][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:22:08.985][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:22:09.228][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:22:09.468][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:22:09.703][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:22:09.965][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:22:10.264][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:22:10.584][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:22:10.908][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:22:11.110][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:22:11.330][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:22:11.528][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:22:11.784][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:22:12.084][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:22:12.335][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:22:12.580][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:22:12.822][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:22:13.097][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:22:13.348][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:22:13.559][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:22:13.839][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:22:14.112][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:22:14.426][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:22:14.651][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:22:14.909][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:22:15.180][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:24:17.597][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:24:17.779][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:24:18.119][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:24:18.516][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:24:18.867][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:24:19.292][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:24:19.681][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:24:19.752][Moby           ][Info   ] Call Trace:
[12:24:19.985][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:24:20.209][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:24:20.468][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:24:20.663][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:24:20.898][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:24:21.159][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:24:21.378][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:24:21.590][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:24:21.858][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:24:22.095][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:24:22.338][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:24:22.593][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:24:22.882][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:24:23.220][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:24:23.493][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:24:23.793][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:24:24.026][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:24:24.312][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:24:24.583][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:24:24.888][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:24:25.244][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:24:25.475][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:24:25.681][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:24:25.931][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:24:26.134][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:24:26.374][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:24:26.669][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:24:26.895][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:24:27.129][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:24:27.359][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:24:27.613][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:26:30.710][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:26:30.892][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:26:31.170][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:26:31.490][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:26:31.836][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:26:32.183][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:26:32.568][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:26:32.648][Moby           ][Info   ] Call Trace:
[12:26:32.900][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:26:33.131][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:26:33.356][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:26:33.495][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:26:33.679][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:26:33.973][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:26:34.208][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:26:34.446][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:26:34.664][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:26:35.019][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:26:35.279][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:26:35.608][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:26:35.864][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:26:36.129][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:26:36.359][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:26:36.579][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:26:36.802][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:26:37.096][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:26:37.336][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:26:37.601][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:26:37.864][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:26:38.091][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:26:38.352][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:26:38.615][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:26:38.789][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:26:38.979][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:26:39.214][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:26:39.481][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:26:39.724][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:26:39.923][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:26:40.138][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:28:43.846][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:28:43.996][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:28:44.307][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:28:44.667][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:28:45.010][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:28:45.304][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:28:45.661][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:28:45.748][Moby           ][Info   ] Call Trace:
[12:28:45.983][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:28:46.182][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:28:46.401][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:28:46.632][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:28:46.907][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:28:47.126][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:28:47.389][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:28:47.562][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:28:47.855][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:28:48.064][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:28:48.322][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:28:48.595][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:28:48.874][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:28:49.179][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:28:49.401][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:28:49.617][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:28:49.783][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:28:50.086][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:28:50.325][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:28:50.584][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:28:50.825][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:28:51.063][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:28:51.291][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:28:51.504][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:28:51.712][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:28:51.972][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:28:52.203][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:28:52.446][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:28:52.670][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:28:52.887][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:28:53.116][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:30:57.005][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:30:57.186][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:30:57.528][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:30:57.863][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:30:58.227][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:30:58.586][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:30:58.916][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:30:58.978][Moby           ][Info   ] Call Trace:
[12:30:59.212][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:30:59.419][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:30:59.602][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:30:59.796][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:31:00.113][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:31:00.368][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:31:00.629][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:31:00.851][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:31:01.118][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:31:01.366][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:31:01.617][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:31:01.914][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:31:02.173][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:31:02.417][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:31:02.629][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:31:02.842][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:31:03.036][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:31:03.330][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:31:03.586][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:31:03.878][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:31:04.095][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:31:04.341][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:31:04.654][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:31:04.897][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:31:05.079][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:31:05.324][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:31:05.564][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:31:05.821][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:31:06.011][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:31:06.198][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:31:06.357][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:33:10.082][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:33:10.224][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:33:10.537][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:33:10.865][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:33:11.238][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:33:11.642][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:33:11.965][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:33:12.052][Moby           ][Info   ] Call Trace:
[12:33:12.312][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:33:12.494][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:33:12.761][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:33:12.948][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:33:13.209][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:33:13.470][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:33:13.724][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:33:13.953][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:33:14.230][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:33:14.436][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:33:14.695][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:33:14.968][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:33:15.213][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:33:15.497][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:33:15.724][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:33:15.975][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:33:16.166][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:33:16.432][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:33:16.674][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:33:16.899][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:33:17.061][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:33:17.293][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:33:17.476][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:33:17.740][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:33:17.985][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:33:18.229][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:33:18.467][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:33:18.739][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:33:18.915][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:33:19.121][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:33:19.335][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[12:35:23.163][Moby           ][Info   ] INFO: task swapper/0:1 blocked for more than 120 seconds.
[12:35:23.301][Moby           ][Info   ]       Not tainted 4.8.5-moby #1
[12:35:23.688][Moby           ][Info   ] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[12:35:24.003][Moby           ][Info   ] swapper/0       D ffff88007f098880     0     1      0 0x00000000
[12:35:24.381][Moby           ][Info   ]  ffff88007c566c80 0000000000000002 ffff88007c56c000 ffff88007c56bb50
[12:35:24.675][Moby           ][Info   ]  ffff88007aa1eea8 0000000000000002 ffffffff8185979b ffff88007c566c80
[12:35:25.048][Moby           ][Info   ]  ffffffff81857a93 7fffffffffffffff ffffffff818597de 00000000ee876484
[12:35:25.097][Moby           ][Info   ] Call Trace:
[12:35:25.323][Moby           ][Info   ]  [<ffffffff8185979b>] ? usleep_range+0x65/0x65
[12:35:25.553][Moby           ][Info   ]  [<ffffffff81857a93>] ? schedule+0x7d/0x8a
[12:35:25.792][Moby           ][Info   ]  [<ffffffff818597de>] ? schedule_timeout+0x43/0x100
[12:35:26.002][Moby           ][Info   ]  [<ffffffff81128ba3>] ? del_timer_sync+0x25/0x42
[12:35:26.255][Moby           ][Info   ]  [<ffffffff81859864>] ? schedule_timeout+0xc9/0x100
[12:35:26.413][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:35:26.644][Moby           ][Info   ]  [<ffffffff81858340>] ? __wait_for_common+0x155/0x190
[12:35:26.837][Moby           ][Info   ]  [<ffffffff811013f1>] ? wake_up_q+0x40/0x40
[12:35:27.102][Moby           ][Info   ]  [<ffffffff8163f672>] ? vmbus_teardown_gpadl+0xaa/0xe1
[12:35:27.314][Moby           ][Info   ]  [<ffffffff81640515>] ? vmbus_open+0x2d8/0x316
[12:35:27.592][Moby           ][Info   ]  [<ffffffff815ea853>] ? netvsc_device_add+0x102/0x65f
[12:35:27.883][Moby           ][Info   ]  [<ffffffff811d8a43>] ? slab_post_alloc_hook.isra.58+0xa/0x1a
[12:35:28.125][Moby           ][Info   ]  [<ffffffff811d94ee>] ? kmem_cache_alloc_trace+0x9c/0xab
[12:35:28.383][Moby           ][Info   ]  [<ffffffff815eb7d4>] ? rndis_filter_device_add+0x85/0x70e
[12:35:28.561][Moby           ][Info   ]  [<ffffffff811b1fe8>] ? pcpu_alloc+0x3fa/0x4ff
[12:35:28.795][Moby           ][Info   ]  [<ffffffff815e88cb>] ? netvsc_probe+0x284/0x34d
[12:35:29.007][Moby           ][Info   ]  [<ffffffff8163e099>] ? vmbus_probe+0x36/0x7e
[12:35:29.225][Moby           ][Info   ]  [<ffffffff8151bbe2>] ? driver_probe_device+0x10b/0x249
[12:35:29.435][Moby           ][Info   ]  [<ffffffff8151bd8a>] ? __driver_attach+0x6a/0x8a
[12:35:29.677][Moby           ][Info   ]  [<ffffffff8151bd20>] ? driver_probe_device+0x249/0x249
[12:35:29.883][Moby           ][Info   ]  [<ffffffff8151a06f>] ? bus_for_each_dev+0x73/0x9f
[12:35:30.091][Moby           ][Info   ]  [<ffffffff8151b289>] ? bus_add_driver+0xec/0x1d9
[12:35:30.285][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:35:30.512][Moby           ][Info   ]  [<ffffffff8151c5a1>] ? driver_register+0x84/0xbc
[12:35:30.749][Moby           ][Info   ]  [<ffffffff81fbe812>] ? netif_init+0x57/0x57
[12:35:31.011][Moby           ][Info   ]  [<ffffffff81fbe84c>] ? netvsc_drv_init+0x3a/0x50
[12:35:31.237][Moby           ][Info   ]  [<ffffffff810021b7>] ? do_one_initcall+0x98/0x12f
[12:35:31.488][Moby           ][Info   ]  [<ffffffff81f7a08b>] ? kernel_init_freeable+0x172/0x20d
[12:35:31.685][Moby           ][Info   ]  [<ffffffff81850741>] ? kernel_init+0xa/0xeb
[12:35:31.903][Moby           ][Info   ]  [<ffffffff8185a4bf>] ? ret_from_fork+0x1f/0x40
[12:35:32.115][Moby           ][Info   ]  [<ffffffff81850737>] ? rest_init+0x6e/0x6e
[13:06:22.082][SegmentApi     ][Info   ] Usage statistic: heartbeat
[13:59:47.082][ErrorReportWindow][Info   ] Open logs
[14:05:52.109][NamedPipeClient][Info   ] Sending GetDebugInfo()...
[14:05:52.157][NamedPipeServer][Info   ] GetDebugInfo()
[14:06:22.093][SegmentApi     ][Info   ] Usage statistic: heartbeat
[14:14:07.096][ErrorReportWindow][Info   ] Open logs


@rn
Copy link
Contributor

rn commented Nov 10, 2016

@Gmanweb thanks for your report. Your issue is different. Could you please open a separate issue and also upload diagnostics as this collect additional information not contained in the logs.
Thanks

@wclr
Copy link

wclr commented Nov 14, 2016

@rneugeba this too fool log start with this failure:
https://gist.github.com/whitecolor/a020e923ea4cfc96bbd5ee64b4b710e0

After I restarted I started ok, this is the log with failure and then successful start:
https://gist.github.com/whitecolor/5c1641d3d55cc114f5fa3140ed50f955

It seem to an old issue.

@glennsarti
Copy link

I recently uploaded a diagnostic report D19FFA1C-8D0F-4773-8630-F5D2D1E32799/2016-11-14_17-14-06

Log Extract

...
[17:09:24.107][DataKit        ][Info   ] +624166us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[17:09:24.240][Database       ][Info   ] Successfully removed 3 value(s)
[17:09:24.264][DataKit        ][Info   ] 
[17:09:24.264][DataKit        ][Info   ] +817059us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
...
[17:09:24.613][Database       ][Info   ] Successfully wrote 1 value(s)
[17:09:24.613][HyperV         ][Info   ] Start
[17:09:24.613][PowerShell     ][Info   ] Run script with parameters: -Start True...
[17:09:24.629][DataKit        ][Info   ] 
[17:09:24.633][DataKit        ][Info   ] +1181773us       fs9p [ERROR] dispatcher caught Unix.Unix_error(Unix.EPIPE, "bytes_read", ""): no more requests will be handled
[17:09:24.649][HyperV         ][Info   ] Starting VM MobyLinuxVM...
[17:09:24.920][HyperV         ][Info   ] Wait for the VM to get an IP address
...

Docker version

C:\Source> docker --version
Docker version 1.12.3, build 6b644ec
C:\Source>

Windows 10
OS Name: Microsoft Windows 10 Enterprise
OS Version: 10.0.14393 N/A Build 14393

The Docker Network is configured for a different network (192.168.201.0/24). I cannot see any UDP listener on port 67 (DHCP) for that NIC

I've previously tried removing all HyperV network switches and using the default Docker For Win settings too to no avail.

@glennsarti
Copy link

Out of curiosity, why should I be seeing references to Vmware VMXNET3 and PVSCSI drivers on a HyperV based Virtual Machine. Seems a bit odd.

@rn
Copy link
Contributor

rn commented Nov 21, 2016

An update: We now (again) have a fix in master which should ship with the next Beta (I'll close this issue once the Beta is out). Apologies that it took so long, but it required upstream Linux kernel changes, which took quite a while to track down.

@glennsarti you see reference to Vmware VMXNET3 and PVSCSI drivers because the drivers are compiled into the kernel. So on boot it tries to probe them and it fails. This doesn't happen when the drivers are compiled as modules (but that has other issues).

@glennsarti
Copy link

@rneugeba Thanks for the clarification!

@glennsarti
Copy link

@rneugeba Can you confirm that this is supposed to be in 1.12.3-beta30.1 (8711). I couldn't see anything obvious in the release notes, and I still cannot start docker due to "...VM Couldn't get and IP Address..."

@rn
Copy link
Contributor

rn commented Nov 28, 2016

@glennsarti we have not yet released a new version with the fix.

@glennsarti
Copy link

@rneugeba Ahh ok sorry for the confusion.

@rn
Copy link
Contributor

rn commented Nov 30, 2016

Finally, we released Beta31 https://download.docker.com/win/beta/InstallDocker.msi
This Beta has an update Linux kernel which should fix this issue, so I'm closing it.

If you still experience this issue it might be worth opening a new issue, since this one is quite long...

@glennsarti
Copy link

@rneugeba Confirmed, I can now start the Docker VM and it gets an IP Address. Thanks.

(Docker Version 1.13.0-rc2-beta31 (9123))

@MPJHorner
Copy link

I had the same issue, BETA working perfectly.

@Heshyo
Copy link

Heshyo commented Jan 18, 2017

Docker 1.12.6 wasn't working but 1.13.0 RC0 Beta37 works well.

@macronomicus
Copy link

oooooh yay!! I've been missing kalabox on my windows 10 box, have been using the built in win10-Ubuntu as my localhost, which is definitely sweet, but obviously not as streamlined nor as feature rich as kalabox is with pantheon and all its glory. Cant wait to test it out, really wish I had checked back in sooner lol.

@amd5 amd5 mentioned this issue Dec 3, 2019
@docker-robott
Copy link
Collaborator

Closed issues are locked after 30 days of inactivity.
This helps our team focus on active issues.

If you have found a problem that seems similar to this, please open a new issue.

Send feedback to Docker Community Slack channels #docker-for-mac or #docker-for-windows.
/lifecycle locked

@docker docker locked and limited conversation to collaborators Jun 18, 2020
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests