I'm not sure if the latest merged PR <a class="issue-link js-issue-link" data-error-te

<a class="user-mention notranslate" data-hovercard-type="user" data-hovercard-url="/us

Latest dependency bump of OpenGCS, Alpine, kernel, runc doesn't work,about linuxkit/lcow

Comments (15)

Andy2244 commented on June 19, 2024 2

More likely is that some code in the Linux kernel itself changed.

Ah ok thanks, but should master not be reverted back, to a working kernel (4.19.28) than, until this can be resolved?

from lcow.

Andy2244 commented on June 19, 2024 1

the linuxkit 4.19.27 must be missing some config option

4.19.27 actually works

Ok its a kernel problem, so i used latest lcow master and just changed the kernel image. The last image that works (don't hangs) is linuxkit/kernel:4.19.28 any kernel afterwards does not work.

So it must be a change in between 4.19.28 -> 4.19.29, since 4.20.15 -> 4.20.16 breaks in a similar way, so changes in those two commits seem to break the kernel for lcow:
linuxkit/linuxkit@cf5221e
linuxkit/linuxkit@0a8d501

PS: I also tested 5.1.6/5.0.20/4.19.47/4.14.122, which all hang.

from lcow.

Iristyle commented on June 19, 2024 1

👍 would be great if someone with commit rights could revert to a working kernel for now.

Note - I was also having problems with Docker nightly builds no longer working (filed separately as moby/moby#39227). Will revisit that ticket once I've got a known good latest LCOW image to run on

from lcow.

Iristyle commented on June 19, 2024

@rn / @jhowardmsft any comments on this one?

from lcow.

lowenna commented on June 19, 2024

Do have an isolated repro without compose in the picture. ie a simple docker run statement? If so, please run the daemon in debug mode (dockerd -D --experimental) and provide the daemon debug output. Can you also provide a link to the initrd.img and kernel files you are using.

from lcow.

Iristyle commented on June 19, 2024

Sorry if it wasn't clear, but a simple docker run does not work either.

It just hangs like this on the given kernel image:

PS C:\Windows\system32> docker --log-level debug run -it --rm alpine /bin/sh
828a15534f2fcf7254ed8a7cc086dda8fa7aeeeff829e4a8bc8749523e24b0dd

Binaries are available at https://puppet.box.com/s/17zos8hvr6mc0wsunwo7iu7in6b3irss
initrd.img MD5 d292f9ace75774f3578cc4faf5358f9d
kernel MD5 70361bf048872b5928875fed6ef47142

Initial Daemon startup

PS C:\Windows\system32> & $Env:ProgramFiles\docker\dockerd.exe --experimental --debug
time="2019-05-07T14:19:25.035010300-07:00" level=warning msg="Running experimental build"
time="2019-05-07T14:19:25.038017700-07:00" level=debug msg="Listener created for HTTP on npipe (//./pipe/docker_engine)"
time="2019-05-07T14:19:25.043011300-07:00" level=info msg="Windows default isolation mode: hyperv"
time="2019-05-07T14:19:25.044014300-07:00" level=debug msg="Stackdump - waiting signal at Global\\stackdump-2900"
time="2019-05-07T14:19:25.044014300-07:00" level=debug msg="Using default logging driver json-file"
time="2019-05-07T14:19:25.044014300-07:00" level=debug msg="[graphdriver] trying provided driver: windowsfilter"
time="2019-05-07T14:19:25.045012400-07:00" level=debug msg="WindowsGraphDriver InitFilter at C:\\ProgramData\\docker\\windowsfilter"
time="2019-05-07T14:19:25.045012400-07:00" level=debug msg="Initialized graph driver windowsfilter"
time="2019-05-07T14:19:25.072022900-07:00" level=debug msg="[graphdriver] trying provided driver: lcow"
time="2019-05-07T14:19:25.072022900-07:00" level=info msg="lcowdriver: init: dataRoot: C:\\ProgramData\\docker\\lcow globalMode: false"
time="2019-05-07T14:19:25.072022900-07:00" level=debug msg="Initialized graph driver lcow"
time="2019-05-07T14:19:25.635016300-07:00" level=debug msg="Max Concurrent Downloads: 3"
time="2019-05-07T14:19:25.635016300-07:00" level=debug msg="Max Concurrent Uploads: 5"
time="2019-05-07T14:19:25.635016300-07:00" level=info msg="Loading containers: start."
time="2019-05-07T14:19:25.638013900-07:00" level=debug msg="Loaded container e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc, isRunning: false"
time="2019-05-07T14:19:25.653013000-07:00" level=debug msg="restoring container" container=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc paused=false running=false
time="2019-05-07T14:19:25.654014300-07:00" level=debug msg="restore()" container=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc module=libcontainerd namespace=moby
time="2019-05-07T14:19:25.654014300-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc
time="2019-05-07T14:19:25.655013600-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc
time="2019-05-07T14:19:25.655013600-07:00" level=error msg="Failed to delete container e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc from containerd" error="no such container"
time="2019-05-07T14:19:25.655013600-07:00" level=debug msg="Option Experimental: true"
time="2019-05-07T14:19:25.655013600-07:00" level=debug msg="Option DefaultDriver: nat"
time="2019-05-07T14:19:25.656013200-07:00" level=debug msg="Option DefaultNetwork: nat"
time="2019-05-07T14:19:25.656013200-07:00" level=debug msg="Network Control Plane MTU: 1500"
time="2019-05-07T14:19:25.656013200-07:00" level=info msg="Restoring existing overlay networks from HNS into docker"
time="2019-05-07T14:19:25.656013200-07:00" level=debug msg="[GET]=>[/networks/] Request : "
time="2019-05-07T14:19:25.663014600-07:00" level=debug msg="Network Response : [{\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":1,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":49,\"Type\":\"nat\",\"Version\":38654705666},{\"ActivityId\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":3,\"GatewayMac\":\"00-15-5D-42-87-A7\",\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028548099431,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"IPv6\":false,\"IsolateSwitch\":true,\"LayeredOn\":\"D9F04F30-A524-4828-842E-A09EB5F786C3\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-42-8F-FF\",\"StartMacAddress\":\"00-15-5D-42-80-00\"}],\"MaxConcurrentEndpoints\":0,\"Name\":\"Default Switch\",\"NatName\":\"ICSE07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{E07CDD0A-18E2-44B3-A136-DAA9FD554DAB}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"6CEB0742-EB0C-4173-AFDB-D98AFE66C677\",\"EndpointPortGuid\":\"5402E83F-75BA-456F-A8A2-B7B114E690FB\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552224503},\"Hidden\":false,\"ID\":\"E7D1DB47-BE9E-4D68-96E4-76531FA6B2D4\",\"InterfaceGuid\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"86-15-C1-0B-68-A6\",\"ManagementPort\":true,\"NicFriendlyName\":\"Default Switch\",\"PreferredPortFriendlyName\":\"Container NIC e7d1db47\",\"State\":3,\"SwitchId\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":true,\"Dns\":true,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":2,\"DNSState\":2,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028561407630},\"ICSFlags\":0,\"ID\":\"EB438B1E-054C-47B3-83EC-9D98089B26A6\",\"IsPolicy\":false,\"Prefix\":28,\"PrivateInterfaceGUID\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"State\":3,\"SubnetIPAddress\":\"192.168.227.160\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552114537},\"ID\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"FD7D3751-6AFC-4DA2-969C-327916CBDE6B\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"192.168.227.160/28\",\"GatewayAddress\":\"192.168.227.161\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552134559},\"ID\":\"D2860A48-B4A4-4CED-9DAD-4CE90345C090\",\"Policies\":[],\"State\":0}],\"SwitchGuid\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"TotalEndpoints\":0,\"Type\":\"ICS\",\"Version\":38654705666}]"
time="2019-05-07T14:19:25.673017900-07:00" level=debug msg="Network  nat (074178d) restored"
time="2019-05-07T14:19:25.680017900-07:00" level=debug msg="Network  ics (13891b1) restored"
time="2019-05-07T14:19:25.689014900-07:00" level=debug msg="[GET]=>[/networks/] Request : "
time="2019-05-07T14:19:25.693017500-07:00" level=debug msg="Network Response : [{\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":1,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":49,\"Type\":\"nat\",\"Version\":38654705666},{\"ActivityId\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":3,\"GatewayMac\":\"00-15-5D-42-87-A7\",\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028548099431,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"IPv6\":false,\"IsolateSwitch\":true,\"LayeredOn\":\"D9F04F30-A524-4828-842E-A09EB5F786C3\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-42-8F-FF\",\"StartMacAddress\":\"00-15-5D-42-80-00\"}],\"MaxConcurrentEndpoints\":0,\"Name\":\"Default Switch\",\"NatName\":\"ICSE07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{E07CDD0A-18E2-44B3-A136-DAA9FD554DAB}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"6CEB0742-EB0C-4173-AFDB-D98AFE66C677\",\"EndpointPortGuid\":\"5402E83F-75BA-456F-A8A2-B7B114E690FB\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552224503},\"Hidden\":false,\"ID\":\"E7D1DB47-BE9E-4D68-96E4-76531FA6B2D4\",\"InterfaceGuid\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"86-15-C1-0B-68-A6\",\"ManagementPort\":true,\"NicFriendlyName\":\"Default Switch\",\"PreferredPortFriendlyName\":\"Container NIC e7d1db47\",\"State\":3,\"SwitchId\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":true,\"Dns\":true,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":2,\"DNSState\":2,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028561407630},\"ICSFlags\":0,\"ID\":\"EB438B1E-054C-47B3-83EC-9D98089B26A6\",\"IsPolicy\":false,\"Prefix\":28,\"PrivateInterfaceGUID\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"State\":3,\"SubnetIPAddress\":\"192.168.227.160\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552114537},\"ID\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"FD7D3751-6AFC-4DA2-969C-327916CBDE6B\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"192.168.227.160/28\",\"GatewayAddress\":\"192.168.227.161\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552134559},\"ID\":\"D2860A48-B4A4-4CED-9DAD-4CE90345C090\",\"Policies\":[],\"State\":0}],\"SwitchGuid\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"TotalEndpoints\":0,\"Type\":\"ICS\",\"Version\":38654705666}]"
time="2019-05-07T14:19:25.699018500-07:00" level=debug msg="Launching DNS server for network \"none\""
time="2019-05-07T14:19:25.714014800-07:00" level=debug msg="releasing IPv4 pools from network nat (074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa)"
time="2019-05-07T14:19:25.715019600-07:00" level=debug msg="ReleaseAddress(172.22.176.0/20, 172.22.176.1)"
time="2019-05-07T14:19:25.717019400-07:00" level=debug msg="ReleasePool(172.22.176.0/20)"
time="2019-05-07T14:19:25.723012300-07:00" level=debug msg="cleanupServiceDiscovery for network:074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa"
time="2019-05-07T14:19:25.732018900-07:00" level=debug msg="Allocating IPv4 pools for network nat (074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa)"
time="2019-05-07T14:19:25.733012200-07:00" level=debug msg="RequestPool(LocalDefault, 172.22.176.0/20, , map[], false)"
time="2019-05-07T14:19:25.733012200-07:00" level=debug msg="RequestAddress(172.22.176.0/20, 172.22.176.1, map[RequestAddressType:com.docker.network.gateway])"
time="2019-05-07T14:19:25.734014100-07:00" level=debug msg="[GET]=>[/endpoints/] Request : "
time="2019-05-07T14:19:25.739015800-07:00" level=debug msg="Network Response : []"
time="2019-05-07T14:19:25.744013000-07:00" level=debug msg="Launching DNS server for network \"nat\""
time="2019-05-07T14:19:25.745013100-07:00" level=debug msg="[GET]=>[/networks/F6116DEF-C9B7-45CF-9FE7-CB172A856106] Request : "
time="2019-05-07T14:19:25.750020500-07:00" level=debug msg="Network Response : {\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":1,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":49,\"Type\":\"nat\",\"Version\":38654705666}"
time="2019-05-07T14:19:25.752016600-07:00" level=debug msg="Binding a resolver on network nat gateway 172.22.176.1"
time="2019-05-07T14:19:25.754015700-07:00" level=debug msg="Resolver bound successfully for network nat"
time="2019-05-07T14:19:25.770017300-07:00" level=debug msg="releasing IPv4 pools from network Default Switch (13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2)"
time="2019-05-07T14:19:25.772020900-07:00" level=debug msg="ReleaseAddress(192.168.227.160/28, 192.168.227.161)"
time="2019-05-07T14:19:25.773013500-07:00" level=debug msg="ReleasePool(192.168.227.160/28)"
time="2019-05-07T14:19:25.778014500-07:00" level=debug msg="cleanupServiceDiscovery for network:13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2"
time="2019-05-07T14:19:25.788011900-07:00" level=debug msg="Allocating IPv4 pools for network Default Switch (13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2)"
time="2019-05-07T14:19:25.789021500-07:00" level=debug msg="RequestPool(LocalDefault, 192.168.227.160/28, , map[], false)"
time="2019-05-07T14:19:25.789021500-07:00" level=debug msg="RequestAddress(192.168.227.160/28, 192.168.227.161, map[RequestAddressType:com.docker.network.gateway])"
time="2019-05-07T14:19:25.790014600-07:00" level=debug msg="[GET]=>[/endpoints/] Request : "
time="2019-05-07T14:19:25.795018500-07:00" level=debug msg="Network Response : []"
time="2019-05-07T14:19:25.809016400-07:00" level=info msg="Loading containers: done."
time="2019-05-07T14:19:25.813017500-07:00" level=info msg="Docker daemon" commit=0c07dad graphdriver(s)="windowsfilter (windows), lcow (linux)" version=master-dockerproject-2019-04-28
time="2019-05-07T14:19:25.813017500-07:00" level=info msg="Daemon has completed initialization"
time="2019-05-07T14:19:25.822019800-07:00" level=debug msg="Registering routers"
time="2019-05-07T14:19:25.823012600-07:00" level=debug msg="Registering GET, /containers/{name:.*}/checkpoints"
time="2019-05-07T14:19:25.825020400-07:00" level=debug msg="Registering POST, /containers/{name:.*}/checkpoints"
time="2019-05-07T14:19:25.826015700-07:00" level=debug msg="Registering DELETE, /containers/{name}/checkpoints/{checkpoint}"
time="2019-05-07T14:19:25.827020400-07:00" level=debug msg="Registering HEAD, /containers/{name:.*}/archive"
time="2019-05-07T14:19:25.827020400-07:00" level=debug msg="Registering GET, /containers/json"
time="2019-05-07T14:19:25.830015500-07:00" level=debug msg="Registering GET, /containers/{name:.*}/export"
time="2019-05-07T14:19:25.833020200-07:00" level=debug msg="Registering GET, /containers/{name:.*}/changes"
time="2019-05-07T14:19:25.834018800-07:00" level=debug msg="Registering GET, /containers/{name:.*}/json"
time="2019-05-07T14:19:25.835014600-07:00" level=debug msg="Registering GET, /containers/{name:.*}/top"
time="2019-05-07T14:19:25.836013900-07:00" level=debug msg="Registering GET, /containers/{name:.*}/logs"
time="2019-05-07T14:19:25.838012300-07:00" level=debug msg="Registering GET, /containers/{name:.*}/stats"
time="2019-05-07T14:19:25.839012900-07:00" level=debug msg="Registering GET, /containers/{name:.*}/attach/ws"
time="2019-05-07T14:19:25.839012900-07:00" level=debug msg="Registering GET, /exec/{id:.*}/json"
time="2019-05-07T14:19:25.840023200-07:00" level=debug msg="Registering GET, /containers/{name:.*}/archive"
time="2019-05-07T14:19:25.841013000-07:00" level=debug msg="Registering POST, /containers/create"
time="2019-05-07T14:19:25.841013000-07:00" level=debug msg="Registering POST, /containers/{name:.*}/kill"
time="2019-05-07T14:19:25.843017800-07:00" level=debug msg="Registering POST, /containers/{name:.*}/pause"
time="2019-05-07T14:19:25.844013100-07:00" level=debug msg="Registering POST, /containers/{name:.*}/unpause"
time="2019-05-07T14:19:25.845012300-07:00" level=debug msg="Registering POST, /containers/{name:.*}/restart"
time="2019-05-07T14:19:25.845012300-07:00" level=debug msg="Registering POST, /containers/{name:.*}/start"
time="2019-05-07T14:19:25.846013800-07:00" level=debug msg="Registering POST, /containers/{name:.*}/stop"
time="2019-05-07T14:19:25.847011800-07:00" level=debug msg="Registering POST, /containers/{name:.*}/wait"
time="2019-05-07T14:19:25.847011800-07:00" level=debug msg="Registering POST, /containers/{name:.*}/resize"
time="2019-05-07T14:19:25.848013300-07:00" level=debug msg="Registering POST, /containers/{name:.*}/attach"
time="2019-05-07T14:19:25.849018700-07:00" level=debug msg="Registering POST, /containers/{name:.*}/copy"
time="2019-05-07T14:19:25.849018700-07:00" level=debug msg="Registering POST, /containers/{name:.*}/exec"
time="2019-05-07T14:19:25.850012600-07:00" level=debug msg="Registering POST, /exec/{name:.*}/start"
time="2019-05-07T14:19:25.851020900-07:00" level=debug msg="Registering POST, /exec/{name:.*}/resize"
time="2019-05-07T14:19:25.851020900-07:00" level=debug msg="Registering POST, /containers/{name:.*}/rename"
time="2019-05-07T14:19:25.854012900-07:00" level=debug msg="Registering POST, /containers/{name:.*}/update"
time="2019-05-07T14:19:25.854012900-07:00" level=debug msg="Registering POST, /containers/prune"
time="2019-05-07T14:19:25.855020300-07:00" level=debug msg="Registering POST, /commit"
time="2019-05-07T14:19:25.855020300-07:00" level=debug msg="Registering PUT, /containers/{name:.*}/archive"
time="2019-05-07T14:19:25.856013800-07:00" level=debug msg="Registering DELETE, /containers/{name:.*}"
time="2019-05-07T14:19:25.857012000-07:00" level=debug msg="Registering GET, /images/json"
time="2019-05-07T14:19:25.857012000-07:00" level=debug msg="Registering GET, /images/search"
time="2019-05-07T14:19:25.859013700-07:00" level=debug msg="Registering GET, /images/get"
time="2019-05-07T14:19:25.860015300-07:00" level=debug msg="Registering GET, /images/{name:.*}/get"
time="2019-05-07T14:19:25.864014800-07:00" level=debug msg="Registering GET, /images/{name:.*}/history"
time="2019-05-07T14:19:25.865017000-07:00" level=debug msg="Registering GET, /images/{name:.*}/json"
time="2019-05-07T14:19:25.866015700-07:00" level=debug msg="Registering POST, /images/load"
time="2019-05-07T14:19:25.867015500-07:00" level=debug msg="Registering POST, /images/create"
time="2019-05-07T14:19:25.868012600-07:00" level=debug msg="Registering POST, /images/{name:.*}/push"
time="2019-05-07T14:19:25.869012900-07:00" level=debug msg="Registering POST, /images/{name:.*}/tag"
time="2019-05-07T14:19:25.870022000-07:00" level=debug msg="Registering POST, /images/prune"
time="2019-05-07T14:19:25.871020300-07:00" level=debug msg="Registering DELETE, /images/{name:.*}"
time="2019-05-07T14:19:25.873018500-07:00" level=debug msg="Registering OPTIONS, /{anyroute:.*}"
time="2019-05-07T14:19:25.874014400-07:00" level=debug msg="Registering GET, /_ping"
time="2019-05-07T14:19:25.875021400-07:00" level=debug msg="Registering HEAD, /_ping"
time="2019-05-07T14:19:25.875021400-07:00" level=debug msg="Registering GET, /events"
time="2019-05-07T14:19:25.876016000-07:00" level=debug msg="Registering GET, /info"
time="2019-05-07T14:19:25.877012400-07:00" level=debug msg="Registering GET, /version"
time="2019-05-07T14:19:25.877012400-07:00" level=debug msg="Registering GET, /system/df"
time="2019-05-07T14:19:25.878017400-07:00" level=debug msg="Registering POST, /auth"
time="2019-05-07T14:19:25.879015800-07:00" level=debug msg="Registering GET, /volumes"
time="2019-05-07T14:19:25.880017800-07:00" level=debug msg="Registering GET, /volumes/{name:.*}"
time="2019-05-07T14:19:25.880017800-07:00" level=debug msg="Registering POST, /volumes/create"
time="2019-05-07T14:19:25.881015000-07:00" level=debug msg="Registering POST, /volumes/prune"
time="2019-05-07T14:19:25.882012000-07:00" level=debug msg="Registering DELETE, /volumes/{name:.*}"
time="2019-05-07T14:19:25.884017300-07:00" level=debug msg="Registering POST, /build"
time="2019-05-07T14:19:25.884017300-07:00" level=debug msg="Registering POST, /build/prune"
time="2019-05-07T14:19:25.885012800-07:00" level=debug msg="Registering POST, /build/cancel"
time="2019-05-07T14:19:25.886014400-07:00" level=debug msg="Registering POST, /session"
time="2019-05-07T14:19:25.886014400-07:00" level=debug msg="Registering POST, /swarm/init"
time="2019-05-07T14:19:25.887013300-07:00" level=debug msg="Registering POST, /swarm/join"
time="2019-05-07T14:19:25.887013300-07:00" level=debug msg="Registering POST, /swarm/leave"
time="2019-05-07T14:19:25.888013200-07:00" level=debug msg="Registering GET, /swarm"
time="2019-05-07T14:19:25.890015400-07:00" level=debug msg="Registering GET, /swarm/unlockkey"
time="2019-05-07T14:19:25.891015500-07:00" level=debug msg="Registering POST, /swarm/update"
time="2019-05-07T14:19:25.891015500-07:00" level=debug msg="Registering POST, /swarm/unlock"
time="2019-05-07T14:19:25.895020200-07:00" level=debug msg="Registering GET, /services"
time="2019-05-07T14:19:25.896017100-07:00" level=debug msg="Registering GET, /services/{id}"
time="2019-05-07T14:19:25.897015100-07:00" level=debug msg="Registering POST, /services/create"
time="2019-05-07T14:19:25.898014700-07:00" level=debug msg="Registering POST, /services/{id}/update"
time="2019-05-07T14:19:25.899014300-07:00" level=debug msg="Registering DELETE, /services/{id}"
time="2019-05-07T14:19:25.900014200-07:00" level=debug msg="Registering GET, /services/{id}/logs"
time="2019-05-07T14:19:25.901014900-07:00" level=debug msg="Registering GET, /nodes"
time="2019-05-07T14:19:25.902012200-07:00" level=debug msg="Registering GET, /nodes/{id}"
time="2019-05-07T14:19:25.902012200-07:00" level=debug msg="Registering DELETE, /nodes/{id}"
time="2019-05-07T14:19:25.903271900-07:00" level=debug msg="Registering POST, /nodes/{id}/update"
time="2019-05-07T14:19:25.905019000-07:00" level=debug msg="Registering GET, /tasks"
time="2019-05-07T14:19:25.906014500-07:00" level=debug msg="Registering GET, /tasks/{id}"
time="2019-05-07T14:19:25.907012400-07:00" level=debug msg="Registering GET, /tasks/{id}/logs"
time="2019-05-07T14:19:25.907012400-07:00" level=debug msg="Registering GET, /secrets"
time="2019-05-07T14:19:25.908013600-07:00" level=debug msg="Registering POST, /secrets/create"
time="2019-05-07T14:19:25.909011600-07:00" level=debug msg="Registering DELETE, /secrets/{id}"
time="2019-05-07T14:19:25.909011600-07:00" level=debug msg="Registering GET, /secrets/{id}"
time="2019-05-07T14:19:25.910013300-07:00" level=debug msg="Registering POST, /secrets/{id}/update"
time="2019-05-07T14:19:25.910013300-07:00" level=debug msg="Registering GET, /configs"
time="2019-05-07T14:19:25.911013300-07:00" level=debug msg="Registering POST, /configs/create"
time="2019-05-07T14:19:25.912011400-07:00" level=debug msg="Registering DELETE, /configs/{id}"
time="2019-05-07T14:19:25.912011400-07:00" level=debug msg="Registering GET, /configs/{id}"
time="2019-05-07T14:19:25.913013700-07:00" level=debug msg="Registering POST, /configs/{id}/update"
time="2019-05-07T14:19:25.914011200-07:00" level=debug msg="Registering GET, /plugins"
time="2019-05-07T14:19:25.914011200-07:00" level=debug msg="Registering GET, /plugins/{name:.*}/json"
time="2019-05-07T14:19:25.917015800-07:00" level=debug msg="Registering GET, /plugins/privileges"
time="2019-05-07T14:19:25.917015800-07:00" level=debug msg="Registering DELETE, /plugins/{name:.*}"
time="2019-05-07T14:19:25.918014400-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/enable"
time="2019-05-07T14:19:25.919020900-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/disable"
time="2019-05-07T14:19:25.919020900-07:00" level=debug msg="Registering POST, /plugins/pull"
time="2019-05-07T14:19:25.921019400-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/push"
time="2019-05-07T14:19:25.922019500-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/upgrade"
time="2019-05-07T14:19:25.924018300-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/set"
time="2019-05-07T14:19:25.927015600-07:00" level=debug msg="Registering POST, /plugins/create"
time="2019-05-07T14:19:25.928018100-07:00" level=debug msg="Registering GET, /distribution/{name:.*}/json"
time="2019-05-07T14:19:25.929014800-07:00" level=debug msg="Registering POST, /grpc"
time="2019-05-07T14:19:25.930016500-07:00" level=debug msg="Registering GET, /networks"
time="2019-05-07T14:19:25.931014700-07:00" level=debug msg="Registering GET, /networks/"
time="2019-05-07T14:19:25.932012000-07:00" level=debug msg="Registering GET, /networks/{id:.+}"
time="2019-05-07T14:19:25.932012000-07:00" level=debug msg="Registering POST, /networks/create"
time="2019-05-07T14:19:25.933014300-07:00" level=debug msg="Registering POST, /networks/{id:.*}/connect"
time="2019-05-07T14:19:25.933014300-07:00" level=debug msg="Registering POST, /networks/{id:.*}/disconnect"
time="2019-05-07T14:19:25.934012500-07:00" level=debug msg="Registering POST, /networks/prune"
time="2019-05-07T14:19:25.935018200-07:00" level=debug msg="Registering DELETE, /networks/{id:.*}"
time="2019-05-07T14:19:25.937014500-07:00" level=info msg="API listen on //./pipe/docker_engine"
time="2019-05-07T14:19:25.937014500-07:00" level=debug msg="Config reload - waiting signal at Global\\docker-daemon-config-2900"

Container request

time="2019-05-07T14:19:36.100890900-07:00" level=debug msg="Calling HEAD /_ping"
time="2019-05-07T14:19:36.109885500-07:00" level=debug msg="Calling POST /v1.40/containers/create"
time="2019-05-07T14:19:36.110882300-07:00" level=debug msg="form data: {\"AttachStderr\":false,\"AttachStdin\":false,\"AttachStdout\":false,\"Cmd\":[\"/bin/sh\"],\"Domainname\":\"\",\"Entrypoint\":null,\"Env\":[],\"HostConfig\":{\"AutoRemove\":true,\"Binds\":null,\"BlkioDeviceReadBps\":null,\"BlkioDeviceReadIOps\":null,\"BlkioDeviceWriteBps\":null,\"BlkioDeviceWriteIOps\":null,\"BlkioWeight\":0,\"BlkioWeightDevice\":[],\"CapAdd\":null,\"CapDrop\":null,\"Capabilities\":null,\"Cgroup\":\"\",\"CgroupParent\":\"\",\"ConsoleSize\":[80,134],\"ContainerIDFile\":\"\",\"CpuCount\":0,\"CpuPercent\":0,\"CpuPeriod\":0,\"CpuQuota\":0,\"CpuRealtimePeriod\":0,\"CpuRealtimeRuntime\":0,\"CpuShares\":0,\"CpusetCpus\":\"\",\"CpusetMems\":\"\",\"DeviceCgroupRules\":null,\"DeviceRequests\":null,\"Devices\":[],\"DiskQuota\":0,\"Dns\":[],\"DnsOptions\":[],\"DnsSearch\":[],\"ExtraHosts\":null,\"GroupAdd\":null,\"IOMaximumBandwidth\":0,\"IOMaximumIOps\":0,\"IpcMode\":\"\",\"Isolation\":\"\",\"KernelMemory\":0,\"KernelMemoryTCP\":0,\"Links\":null,\"LogConfig\":{\"Config\":{},\"Type\":\"\"},\"MaskedPaths\":null,\"Memory\":0,\"MemoryReservation\":0,\"MemorySwap\":0,\"MemorySwappiness\":-1,\"NanoCpus\":0,\"NetworkMode\":\"default\",\"OomKillDisable\":false,\"OomScoreAdj\":0,\"PidMode\":\"\",\"PidsLimit\":0,\"PortBindings\":{},\"Privileged\":false,\"PublishAllPorts\":false,\"ReadonlyPaths\":null,\"ReadonlyRootfs\":false,\"RestartPolicy\":{\"MaximumRetryCount\":0,\"Name\":\"no\"},\"SecurityOpt\":null,\"ShmSize\":0,\"UTSMode\":\"\",\"Ulimits\":null,\"UsernsMode\":\"\",\"VolumeDriver\":\"\",\"VolumesFrom\":null},\"Hostname\":\"\",\"Image\":\"alpine\",\"Labels\":{},\"NetworkingConfig\":{\"EndpointsConfig\":{}},\"OnBuild\":null,\"OpenStdin\":true,\"StdinOnce\":false,\"Tty\":true,\"User\":\"\",\"Volumes\":{},\"WorkingDir\":\"\"}"
time="2019-05-07T14:19:36.115888300-07:00" level=debug msg="lcowdriver: createreadwrite: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c"
time="2019-05-07T14:19:36.116883400-07:00" level=debug msg="lcowdriver: create: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c parent: 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424"
time="2019-05-07T14:19:36.116883400-07:00" level=debug msg="lcowdriver: getlayerchain: id 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424 json C:\\ProgramData\\docker\\lcow\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\\layerchain.json"
time="2019-05-07T14:19:36.117883800-07:00" level=debug msg="lcowdriver: exists: id 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424 true"
time="2019-05-07T14:19:36.118889000-07:00" level=debug msg="lcowdriver: create: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c: creating C:\\ProgramData\\docker\\lcow\\0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c"
time="2019-05-07T14:19:36.119883000-07:00" level=debug msg="lcowdriver: setlayerchain: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c json C:\\ProgramData\\docker\\lcow\\0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c\\layerchain.json"
time="2019-05-07T14:19:36.120885800-07:00" level=debug msg="lcowdriver: create: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c: success"
time="2019-05-07T14:19:36.123890600-07:00" level=debug msg="lcowdriver: createreadwrite: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c: locking cachedSandboxMutex"
time="2019-05-07T14:19:36.123890600-07:00" level=debug msg="lcowdriver: createreadwrite: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c: releasing cachedSandboxMutex"
time="2019-05-07T14:19:36.124884500-07:00" level=debug msg="lcowdriver: createreadwrite: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c: using cached sandbox to populate"
time="2019-05-07T14:19:36.171792000-07:00" level=debug msg="Calling POST /v1.40/containers/0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c/wait?condition=removed"
time="2019-05-07T14:19:36.172783400-07:00" level=debug msg="Calling POST /v1.40/containers/0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c/start"
time="2019-05-07T14:19:36.176785700-07:00" level=debug msg="Assigning addresses for endpoint naughty_chebyshev's interface on network nat"
time="2019-05-07T14:19:36.176785700-07:00" level=debug msg="RequestAddress(172.22.176.0/20, <nil>, map[])"
time="2019-05-07T14:19:36.177784100-07:00" level=debug msg="endpointStruct.EnableInternalDNS =[false]"
time="2019-05-07T14:19:36.178783100-07:00" level=debug msg="[POST]=>[/endpoints/] Request : {\"VirtualNetwork\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"EnableInternalDNS\":true}"
time="2019-05-07T14:19:36.187791400-07:00" level=debug msg="Network Response : {\"ActivityId\":\"A442DF8A-1EE0-4446-B05E-06FAB8EB19F5\",\"AdditionalParams\":{},\"CreateProcessingStartTime\":132017375761847877,\"DNSServerList\":\"172.22.176.1,10.240.0.10,10.240.1.10\",\"DNSSuffix\":\"delivery.puppetlabs.net\",\"EnableInternalDNS\":true,\"EnableLowInterfaceMetric\":true,\"EncapOverhead\":0,\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132017375761817863},\"ID\":\"00935D16-89D6-4BD3-8B0B-D27149F128EC\",\"IPAddress\":\"172.22.181.30\",\"MacAddress\":\"00-15-5D-3C-2B-66\",\"Name\":\"Ethernet\",\"Policies\":[],\"PrefixLength\":20,\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":0,\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132017375761817863},\"ID\":\"A442DF8A-1EE0-4446-B05E-06FAB8EB19F5\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\"},\"SharedContainers\":[],\"State\":1,\"Type\":\"nat\",\"Version\":38654705666,\"VirtualNetwork\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"VirtualNetworkName\":\"nat\"}"
time="2019-05-07T14:19:36.196793200-07:00" level=debug msg="Assigning addresses for endpoint naughty_chebyshev's interface on network nat"
time="2019-05-07T14:19:36.203785600-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c
time="2019-05-07T14:19:36.204784300-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c
time="2019-05-07T14:19:36.208784500-07:00" level=debug msg="Programming external connectivity on endpoint naughty_chebyshev (60c8d2fb7289556b8bed7776174ed7ed47317999359eece6704708719db59ba7)"
time="2019-05-07T14:19:36.208784500-07:00" level=debug msg="EnableService 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c START"
time="2019-05-07T14:19:36.209793700-07:00" level=debug msg="EnableService 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c DONE"
time="2019-05-07T14:19:36.213787800-07:00" level=debug msg="lcowdriver: getmetadata: id 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c"
time="2019-05-07T14:19:36.216784600-07:00" level=debug msg="Generated spec: {\"ociVersion\":\"1.0.1-dev\",\"process\":{\"terminal\":true,\"consoleSize\":{\"height\":80,\"width\":134},\"user\":{\"uid\":0,\"gid\":0},\"args\":[\"/bin/sh\"],\"env\":[\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\",\"HOSTNAME=0d3c28476194\",\"TERM=xterm\"],\"cwd\":\"/\",\"capabilities\":{\"bounding\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"effective\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"inheritable\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"permitted\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"]}},\"root\":{\"path\":\"rootfs\"},\"hostname\":\"0d3c28476194\",\"mounts\":[{\"destination\":\"/proc\",\"type\":\"proc\",\"source\":\"proc\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev\",\"type\":\"tmpfs\",\"source\":\"tmpfs\",\"options\":[\"nosuid\",\"strictatime\",\"mode=755\",\"size=65536k\"]},{\"destination\":\"/dev/pts\",\"type\":\"devpts\",\"source\":\"devpts\",\"options\":[\"nosuid\",\"noexec\",\"newinstance\",\"ptmxmode=0666\",\"mode=0620\",\"gid=5\"]},{\"destination\":\"/sys\",\"type\":\"sysfs\",\"source\":\"sysfs\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"ro\"]},{\"destination\":\"/sys/fs/cgroup\",\"type\":\"cgroup\",\"source\":\"cgroup\",\"options\":[\"ro\",\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/mqueue\",\"type\":\"mqueue\",\"source\":\"mqueue\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/shm\",\"type\":\"tmpfs\",\"source\":\"shm\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"mode=1777\"]}],\"linux\":{\"resources\":{},\"namespaces\":[{\"type\":\"mount\"},{\"type\":\"network\"},{\"type\":\"uts\"},{\"type\":\"pid\"},{\"type\":\"ipc\"}],\"maskedPaths\":[\"/proc/asound\",\"/proc/acpi\",\"/proc/kcore\",\"/proc/keys\",\"/proc/latency_stats\",\"/proc/timer_list\",\"/proc/timer_stats\",\"/proc/sched_debug\",\"/proc/scsi\",\"/sys/firmware\"],\"readonlyPaths\":[\"/proc/bus\",\"/proc/fs\",\"/proc/irq\",\"/proc/sys\",\"/proc/sysrq-trigger\"]},\"windows\":{\"layerFolders\":[\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\",\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c\"],\"hyperv\":{},\"network\":{\"endpointList\":[\"00935D16-89D6-4BD3-8B0B-D27149F128EC\"],\"allowUnqualifiedDNSQuery\":true}}}"
time="2019-05-07T14:19:36.218781100-07:00" level=debug msg="libcontainerd: createLinux(): containerId 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c "
time="2019-05-07T14:19:36.219783300-07:00" level=debug msg="hcsshim::NameToGuid" name=6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424
time="2019-05-07T14:19:36.220787000-07:00" level=debug msg="hcsshim::NameToGuid - succeeded" guid=b7dd4f52-64c9-59ea-b002-5a27a996f437 name=6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424
time="2019-05-07T14:19:36.220787000-07:00" level=debug msg="hcsshim::CreateComputeSystem - Begin Operation" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c
time="2019-05-07T14:19:36.221785500-07:00" level=debug msg="HCS ComputeSystem Document" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c json="{\"SystemType\":\"container\",\"Name\":\"0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c\",\"Owner\":\"docker\",\"LayerFolderPath\":\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c\",\"Layers\":[{\"ID\":\"b7dd4f52-64c9-59ea-b002-5a27a996f437\",\"Path\":\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\\\\layer.vhd\"}],\"HvPartition\":true,\"EndpointList\":[\"00935D16-89D6-4BD3-8B0B-D27149F128EC\"],\"HvRuntime\":{\"ImagePath\":\"C:\\\\Program Files\\\\Linux Containers\",\"LinuxInitrdFile\":\"initrd.img\",\"LinuxKernelFile\":\"kernel\"},\"AllowUnqualifiedDNSQuery\":true,\"ContainerType\":\"linux\",\"TerminateOnLastHandleClosed\":true}"

To compare against the previous image, here is the same debug output:

Initial Daemon Start

PS C:\Windows\system32> & $Env:ProgramFiles\docker\dockerd.exe --experimental --debug
time="2019-05-07T14:26:01.104285200-07:00" level=warning msg="Running experimental build"
time="2019-05-07T14:26:01.107291500-07:00" level=debug msg="Listener created for HTTP on npipe (//./pipe/docker_engine)"
time="2019-05-07T14:26:01.112283100-07:00" level=debug msg="Stackdump - waiting signal at Global\\stackdump-9372"
time="2019-05-07T14:26:01.112283100-07:00" level=info msg="Windows default isolation mode: hyperv"
time="2019-05-07T14:26:01.114283800-07:00" level=debug msg="Using default logging driver json-file"
time="2019-05-07T14:26:01.114283800-07:00" level=debug msg="[graphdriver] trying provided driver: windowsfilter"
time="2019-05-07T14:26:01.114283800-07:00" level=debug msg="WindowsGraphDriver InitFilter at C:\\ProgramData\\docker\\windowsfilter"
time="2019-05-07T14:26:01.115282500-07:00" level=debug msg="Initialized graph driver windowsfilter"
time="2019-05-07T14:26:01.139283700-07:00" level=debug msg="[graphdriver] trying provided driver: lcow"
time="2019-05-07T14:26:01.140283600-07:00" level=info msg="lcowdriver: init: dataRoot: C:\\ProgramData\\docker\\lcow globalMode: false"
time="2019-05-07T14:26:01.140283600-07:00" level=debug msg="Initialized graph driver lcow"
time="2019-05-07T14:26:01.681286300-07:00" level=debug msg="Max Concurrent Downloads: 3"
time="2019-05-07T14:26:01.681286300-07:00" level=debug msg="Max Concurrent Uploads: 5"
time="2019-05-07T14:26:01.681286300-07:00" level=info msg="Loading containers: start."
time="2019-05-07T14:26:01.684286900-07:00" level=debug msg="Loaded container e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc, isRunning: false"
time="2019-05-07T14:26:01.684286900-07:00" level=debug msg="Loaded container 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c, isRunning: false"
time="2019-05-07T14:26:01.702285100-07:00" level=debug msg="restoring container" container=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc paused=false running=false
time="2019-05-07T14:26:01.702285100-07:00" level=debug msg="restore()" container=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc module=libcontainerd namespace=moby
time="2019-05-07T14:26:01.703284500-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc
time="2019-05-07T14:26:01.703284500-07:00" level=debug msg="restoring container" container=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c paused=false running=false
time="2019-05-07T14:26:01.705282700-07:00" level=debug msg="restore()" container=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c module=libcontainerd namespace=moby
time="2019-05-07T14:26:01.705282700-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c
time="2019-05-07T14:26:01.705282700-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc
time="2019-05-07T14:26:01.705282700-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c
time="2019-05-07T14:26:01.706308800-07:00" level=error msg="Failed to delete container e9b618d4817eecc4bf9f5972098a36e676779ee0f9cfec31d0fdfd11a58fcabc from containerd" error="no such container"
time="2019-05-07T14:26:01.706308800-07:00" level=error msg="Failed to delete container 0d3c284761944bcb2b8f6d62894fd596b4aa7236aab860099943ad9bc4772d9c from containerd" error="no such container"
time="2019-05-07T14:26:01.706308800-07:00" level=debug msg="Option Experimental: true"
time="2019-05-07T14:26:01.706308800-07:00" level=debug msg="Option DefaultDriver: nat"
time="2019-05-07T14:26:01.706308800-07:00" level=debug msg="Option DefaultNetwork: nat"
time="2019-05-07T14:26:01.707282000-07:00" level=debug msg="Network Control Plane MTU: 1500"
time="2019-05-07T14:26:01.707282000-07:00" level=info msg="Restoring existing overlay networks from HNS into docker"
time="2019-05-07T14:26:01.707282000-07:00" level=debug msg="[GET]=>[/networks/] Request : "
time="2019-05-07T14:26:01.713290800-07:00" level=debug msg="Network Response : [{\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":2,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":52,\"Type\":\"nat\",\"Version\":38654705666},{\"ActivityId\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":3,\"GatewayMac\":\"00-15-5D-42-87-A7\",\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028548099431,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"IPv6\":false,\"IsolateSwitch\":true,\"LayeredOn\":\"D9F04F30-A524-4828-842E-A09EB5F786C3\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-42-8F-FF\",\"StartMacAddress\":\"00-15-5D-42-80-00\"}],\"MaxConcurrentEndpoints\":0,\"Name\":\"Default Switch\",\"NatName\":\"ICSE07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{E07CDD0A-18E2-44B3-A136-DAA9FD554DAB}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"6CEB0742-EB0C-4173-AFDB-D98AFE66C677\",\"EndpointPortGuid\":\"5402E83F-75BA-456F-A8A2-B7B114E690FB\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552224503},\"Hidden\":false,\"ID\":\"E7D1DB47-BE9E-4D68-96E4-76531FA6B2D4\",\"InterfaceGuid\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"86-15-C1-0B-68-A6\",\"ManagementPort\":true,\"NicFriendlyName\":\"Default Switch\",\"PreferredPortFriendlyName\":\"Container NIC e7d1db47\",\"State\":3,\"SwitchId\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":true,\"Dns\":true,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":2,\"DNSState\":2,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028561407630},\"ICSFlags\":0,\"ID\":\"EB438B1E-054C-47B3-83EC-9D98089B26A6\",\"IsPolicy\":false,\"Prefix\":28,\"PrivateInterfaceGUID\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"State\":3,\"SubnetIPAddress\":\"192.168.227.160\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552114537},\"ID\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"FD7D3751-6AFC-4DA2-969C-327916CBDE6B\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"192.168.227.160/28\",\"GatewayAddress\":\"192.168.227.161\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552134559},\"ID\":\"D2860A48-B4A4-4CED-9DAD-4CE90345C090\",\"Policies\":[],\"State\":0}],\"SwitchGuid\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"TotalEndpoints\":0,\"Type\":\"ICS\",\"Version\":38654705666}]"
time="2019-05-07T14:26:01.724288300-07:00" level=debug msg="Network  nat (074178d) restored"
time="2019-05-07T14:26:01.727283200-07:00" level=debug msg="Network  ics (13891b1) restored"
time="2019-05-07T14:26:01.736293100-07:00" level=debug msg="[GET]=>[/networks/] Request : "
time="2019-05-07T14:26:01.740289700-07:00" level=debug msg="Network Response : [{\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":2,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":52,\"Type\":\"nat\",\"Version\":38654705666},{\"ActivityId\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":3,\"GatewayMac\":\"00-15-5D-42-87-A7\",\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028548099431,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"IPv6\":false,\"IsolateSwitch\":true,\"LayeredOn\":\"D9F04F30-A524-4828-842E-A09EB5F786C3\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-42-8F-FF\",\"StartMacAddress\":\"00-15-5D-42-80-00\"}],\"MaxConcurrentEndpoints\":0,\"Name\":\"Default Switch\",\"NatName\":\"ICSE07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{E07CDD0A-18E2-44B3-A136-DAA9FD554DAB}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"6CEB0742-EB0C-4173-AFDB-D98AFE66C677\",\"EndpointPortGuid\":\"5402E83F-75BA-456F-A8A2-B7B114E690FB\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552224503},\"Hidden\":false,\"ID\":\"E7D1DB47-BE9E-4D68-96E4-76531FA6B2D4\",\"InterfaceGuid\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"86-15-C1-0B-68-A6\",\"ManagementPort\":true,\"NicFriendlyName\":\"Default Switch\",\"PreferredPortFriendlyName\":\"Container NIC e7d1db47\",\"State\":3,\"SwitchId\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":true,\"Dns\":true,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":2,\"DNSState\":2,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028561407630},\"ICSFlags\":0,\"ID\":\"EB438B1E-054C-47B3-83EC-9D98089B26A6\",\"IsPolicy\":false,\"Prefix\":28,\"PrivateInterfaceGUID\":\"E07CDD0A-18E2-44B3-A136-DAA9FD554DAB\",\"State\":3,\"SubnetIPAddress\":\"192.168.227.160\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552114537},\"ID\":\"E69B9F96-874E-4D71-B31E-0DFA22003BDD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"FD7D3751-6AFC-4DA2-969C-327916CBDE6B\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"192.168.227.160/28\",\"GatewayAddress\":\"192.168.227.161\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028552134559},\"ID\":\"D2860A48-B4A4-4CED-9DAD-4CE90345C090\",\"Policies\":[],\"State\":0}],\"SwitchGuid\":\"C08CB7B8-9B3C-408E-8E30-5E16A3AEB444\",\"TotalEndpoints\":0,\"Type\":\"ICS\",\"Version\":38654705666}]"
time="2019-05-07T14:26:01.746287900-07:00" level=debug msg="Launching DNS server for network \"none\""
time="2019-05-07T14:26:01.761287400-07:00" level=debug msg="releasing IPv4 pools from network nat (074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa)"
time="2019-05-07T14:26:01.762291500-07:00" level=debug msg="ReleaseAddress(172.22.176.0/20, 172.22.176.1)"
time="2019-05-07T14:26:01.763289900-07:00" level=debug msg="ReleasePool(172.22.176.0/20)"
time="2019-05-07T14:26:01.768285000-07:00" level=debug msg="cleanupServiceDiscovery for network:074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa"
time="2019-05-07T14:26:01.777286000-07:00" level=debug msg="Allocating IPv4 pools for network nat (074178d5bbd971d3fa834f2266f4de2243049b2d095272b22c906adff82740aa)"
time="2019-05-07T14:26:01.778283700-07:00" level=debug msg="RequestPool(LocalDefault, 172.22.176.0/20, , map[], false)"
time="2019-05-07T14:26:01.778283700-07:00" level=debug msg="RequestAddress(172.22.176.0/20, 172.22.176.1, map[RequestAddressType:com.docker.network.gateway])"
time="2019-05-07T14:26:01.779283600-07:00" level=debug msg="[GET]=>[/endpoints/] Request : "
time="2019-05-07T14:26:01.783285800-07:00" level=debug msg="Network Response : []"
time="2019-05-07T14:26:01.789282100-07:00" level=debug msg="Launching DNS server for network \"nat\""
time="2019-05-07T14:26:01.790283200-07:00" level=debug msg="[GET]=>[/networks/F6116DEF-C9B7-45CF-9FE7-CB172A856106] Request : "
time="2019-05-07T14:26:01.795285400-07:00" level=debug msg="Network Response : {\"ActivityId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"AdditionalParams\":{},\"CurrentEndpointCount\":0,\"Extensions\":[{\"Id\":\"E7C3B2F0-F3C5-48DF-AF2B-10FED6D72E7A\",\"IsEnabled\":false,\"Name\":\"Microsoft Windows Filtering Platform\"},{\"Id\":\"E9B59CFA-2BE1-4B21-828F-B6FBDBDDC017\",\"IsEnabled\":false,\"Name\":\"Microsoft Azure VFP Switch Extension\"},{\"Id\":\"EA24CD6C-D17A-4348-9190-09F0D5BE83DD\",\"IsEnabled\":true,\"Name\":\"Microsoft NDIS Capture\"}],\"Flags\":0,\"Health\":{\"AddressNotificationMissedCount\":0,\"AddressNotificationSequenceNumber\":0,\"InterfaceNotificationMissedCount\":0,\"InterfaceNotificationSequenceNumber\":0,\"LastErrorCode\":0,\"LastUpdateTime\":132016028564863674,\"RouteNotificationMissedCount\":0,\"RouteNotificationSequenceNumber\":0},\"ID\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"IPv6\":false,\"LayeredOn\":\"E2666A04-27AE-4994-998F-2B33F745DBD4\",\"MacPools\":[{\"EndMacAddress\":\"00-15-5D-3C-2F-FF\",\"StartMacAddress\":\"00-15-5D-3C-20-00\"}],\"MaxConcurrentEndpoints\":2,\"Name\":\"nat\",\"NatName\":\"ICSA798D319-48AE-47D3-A1A8-405E7FCAF579\",\"Policies\":[],\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":2,\"Allocators\":[{\"AdapterNetCfgInstanceId\":\"{A798D319-48AE-47D3-A1A8-405E7FCAF579}\",\"AdditionalParams\":{},\"AllocationOrder\":0,\"CompartmendId\":0,\"Connected\":true,\"DevicelessNic\":false,\"EndpointNicGuid\":\"CD1781F4-A81D-4E8C-84C8-8AF84B815960\",\"EndpointPortGuid\":\"8F4D597F-D0BA-4133-B719-92BC90009C79\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"Hidden\":false,\"ID\":\"E48B0393-DA9C-4F64-8BCF-16CC91670E5F\",\"InterfaceGuid\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"IsPolicy\":false,\"IsolationId\":0,\"MacAddress\":\"00-15-5D-3C-23-BC\",\"ManagementPort\":true,\"NicFriendlyName\":\"nat\",\"PreferredPortFriendlyName\":\"Container NIC e48b0393\",\"State\":3,\"SwitchId\":\"C2DAC32C-0BB8-4A3F-84A6-E62BA2E49340\",\"Tag\":\"Host Vnic\",\"WaitForIpv6Interface\":false,\"nonPersistentPort\":false},{\"AdditionalParams\":{},\"AllocationOrder\":1,\"Dhcp\":false,\"Dns\":false,\"ExternalInterfaceConstraint\":0,\"Health\":{\"DHCPState\":1,\"DNSState\":1,\"ICSState\":2,\"LastErrorCode\":0,\"LastUpdateTime\":132016028597599650},\"ICSFlags\":0,\"ID\":\"6CEA8076-7585-4BE2-B255-1A479FC10A08\",\"IsPolicy\":false,\"Prefix\":20,\"PrivateInterfaceGUID\":\"A798D319-48AE-47D3-A1A8-405E7FCAF579\",\"State\":3,\"SubnetIPAddress\":\"172.22.176.0\",\"Tag\":\"ICS\"}],\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"91673930-4046-438B-8BAE-3B43A3831BFD\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"E07745CE-4289-4318-B351-76122C4CA07A\"},\"State\":1,\"Subnets\":[{\"AdditionalParams\":{},\"AddressPrefix\":\"172.22.176.0/20\",\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132016028593465956},\"ID\":\"140806C2-6690-49AC-AE9B-8F3A92EC1DDD\",\"Policies\":[],\"State\":0}],\"TotalEndpoints\":52,\"Type\":\"nat\",\"Version\":38654705666}"
time="2019-05-07T14:26:01.797285200-07:00" level=debug msg="Binding a resolver on network nat gateway 172.22.176.1"
time="2019-05-07T14:26:01.802286000-07:00" level=debug msg="Resolver bound successfully for network nat"
time="2019-05-07T14:26:01.818284700-07:00" level=debug msg="releasing IPv4 pools from network Default Switch (13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2)"
time="2019-05-07T14:26:01.820291000-07:00" level=debug msg="ReleaseAddress(192.168.227.160/28, 192.168.227.161)"
time="2019-05-07T14:26:01.821287900-07:00" level=debug msg="ReleasePool(192.168.227.160/28)"
time="2019-05-07T14:26:01.825284700-07:00" level=debug msg="cleanupServiceDiscovery for network:13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2"
time="2019-05-07T14:26:01.834284700-07:00" level=debug msg="Allocating IPv4 pools for network Default Switch (13891b1b6db49e3a9f7e3dd3e04c8c77d45600a6bfe53697b03118c01412cdd2)"
time="2019-05-07T14:26:01.834284700-07:00" level=debug msg="RequestPool(LocalDefault, 192.168.227.160/28, , map[], false)"
time="2019-05-07T14:26:01.835283800-07:00" level=debug msg="RequestAddress(192.168.227.160/28, 192.168.227.161, map[RequestAddressType:com.docker.network.gateway])"
time="2019-05-07T14:26:01.836282100-07:00" level=debug msg="[GET]=>[/endpoints/] Request : "
time="2019-05-07T14:26:01.840290400-07:00" level=debug msg="Network Response : []"
time="2019-05-07T14:26:01.854286800-07:00" level=info msg="Loading containers: done."
time="2019-05-07T14:26:01.857287700-07:00" level=info msg="Docker daemon" commit=0c07dad graphdriver(s)="windowsfilter (windows), lcow (linux)" version=master-dockerproject-2019-04-28
time="2019-05-07T14:26:01.858282900-07:00" level=info msg="Daemon has completed initialization"
time="2019-05-07T14:26:01.868285000-07:00" level=debug msg="Registering routers"
time="2019-05-07T14:26:01.868285000-07:00" level=debug msg="Registering GET, /containers/{name:.*}/checkpoints"
time="2019-05-07T14:26:01.869289500-07:00" level=debug msg="Registering POST, /containers/{name:.*}/checkpoints"
time="2019-05-07T14:26:01.870284500-07:00" level=debug msg="Registering DELETE, /containers/{name}/checkpoints/{checkpoint}"
time="2019-05-07T14:26:01.871281800-07:00" level=debug msg="Registering HEAD, /containers/{name:.*}/archive"
time="2019-05-07T14:26:01.871281800-07:00" level=debug msg="Registering GET, /containers/json"
time="2019-05-07T14:26:01.872284300-07:00" level=debug msg="Registering GET, /containers/{name:.*}/export"
time="2019-05-07T14:26:01.873282100-07:00" level=debug msg="Registering GET, /containers/{name:.*}/changes"
time="2019-05-07T14:26:01.874282600-07:00" level=debug msg="Registering GET, /containers/{name:.*}/json"
time="2019-05-07T14:26:01.875284700-07:00" level=debug msg="Registering GET, /containers/{name:.*}/top"
time="2019-05-07T14:26:01.876285300-07:00" level=debug msg="Registering GET, /containers/{name:.*}/logs"
time="2019-05-07T14:26:01.880290300-07:00" level=debug msg="Registering GET, /containers/{name:.*}/stats"
time="2019-05-07T14:26:01.881290100-07:00" level=debug msg="Registering GET, /containers/{name:.*}/attach/ws"
time="2019-05-07T14:26:01.882292900-07:00" level=debug msg="Registering GET, /exec/{id:.*}/json"
time="2019-05-07T14:26:01.883289600-07:00" level=debug msg="Registering GET, /containers/{name:.*}/archive"
time="2019-05-07T14:26:01.884289400-07:00" level=debug msg="Registering POST, /containers/create"
time="2019-05-07T14:26:01.884289400-07:00" level=debug msg="Registering POST, /containers/{name:.*}/kill"
time="2019-05-07T14:26:01.885281700-07:00" level=debug msg="Registering POST, /containers/{name:.*}/pause"
time="2019-05-07T14:26:01.885281700-07:00" level=debug msg="Registering POST, /containers/{name:.*}/unpause"
time="2019-05-07T14:26:01.886283000-07:00" level=debug msg="Registering POST, /containers/{name:.*}/restart"
time="2019-05-07T14:26:01.887281500-07:00" level=debug msg="Registering POST, /containers/{name:.*}/start"
time="2019-05-07T14:26:01.887281500-07:00" level=debug msg="Registering POST, /containers/{name:.*}/stop"
time="2019-05-07T14:26:01.890299400-07:00" level=debug msg="Registering POST, /containers/{name:.*}/wait"
time="2019-05-07T14:26:01.890299400-07:00" level=debug msg="Registering POST, /containers/{name:.*}/resize"
time="2019-05-07T14:26:01.891283100-07:00" level=debug msg="Registering POST, /containers/{name:.*}/attach"
time="2019-05-07T14:26:01.892282100-07:00" level=debug msg="Registering POST, /containers/{name:.*}/copy"
time="2019-05-07T14:26:01.892282100-07:00" level=debug msg="Registering POST, /containers/{name:.*}/exec"
time="2019-05-07T14:26:01.893282200-07:00" level=debug msg="Registering POST, /exec/{name:.*}/start"
time="2019-05-07T14:26:01.894281900-07:00" level=debug msg="Registering POST, /exec/{name:.*}/resize"
time="2019-05-07T14:26:01.894281900-07:00" level=debug msg="Registering POST, /containers/{name:.*}/rename"
time="2019-05-07T14:26:01.895294100-07:00" level=debug msg="Registering POST, /containers/{name:.*}/update"
time="2019-05-07T14:26:01.896285600-07:00" level=debug msg="Registering POST, /containers/prune"
time="2019-05-07T14:26:01.896285600-07:00" level=debug msg="Registering POST, /commit"
time="2019-05-07T14:26:01.897284400-07:00" level=debug msg="Registering PUT, /containers/{name:.*}/archive"
time="2019-05-07T14:26:01.898281800-07:00" level=debug msg="Registering DELETE, /containers/{name:.*}"
time="2019-05-07T14:26:01.900285300-07:00" level=debug msg="Registering GET, /images/json"
time="2019-05-07T14:26:01.900285300-07:00" level=debug msg="Registering GET, /images/search"
time="2019-05-07T14:26:01.901284300-07:00" level=debug msg="Registering GET, /images/get"
time="2019-05-07T14:26:01.902282000-07:00" level=debug msg="Registering GET, /images/{name:.*}/get"
time="2019-05-07T14:26:01.902282000-07:00" level=debug msg="Registering GET, /images/{name:.*}/history"
time="2019-05-07T14:26:01.903284300-07:00" level=debug msg="Registering GET, /images/{name:.*}/json"
time="2019-05-07T14:26:01.904282300-07:00" level=debug msg="Registering POST, /images/load"
time="2019-05-07T14:26:01.904282300-07:00" level=debug msg="Registering POST, /images/create"
time="2019-05-07T14:26:01.905284900-07:00" level=debug msg="Registering POST, /images/{name:.*}/push"
time="2019-05-07T14:26:01.906285300-07:00" level=debug msg="Registering POST, /images/{name:.*}/tag"
time="2019-05-07T14:26:01.907285500-07:00" level=debug msg="Registering POST, /images/prune"
time="2019-05-07T14:26:01.910287500-07:00" level=debug msg="Registering DELETE, /images/{name:.*}"
time="2019-05-07T14:26:01.911284900-07:00" level=debug msg="Registering OPTIONS, /{anyroute:.*}"
time="2019-05-07T14:26:01.912285000-07:00" level=debug msg="Registering GET, /_ping"
time="2019-05-07T14:26:01.913286100-07:00" level=debug msg="Registering HEAD, /_ping"
time="2019-05-07T14:26:01.914286000-07:00" level=debug msg="Registering GET, /events"
time="2019-05-07T14:26:01.915283300-07:00" level=debug msg="Registering GET, /info"
time="2019-05-07T14:26:01.916286100-07:00" level=debug msg="Registering GET, /version"
time="2019-05-07T14:26:01.917285500-07:00" level=debug msg="Registering GET, /system/df"
time="2019-05-07T14:26:01.918284700-07:00" level=debug msg="Registering POST, /auth"
time="2019-05-07T14:26:01.920288900-07:00" level=debug msg="Registering GET, /volumes"
time="2019-05-07T14:26:01.921289300-07:00" level=debug msg="Registering GET, /volumes/{name:.*}"
time="2019-05-07T14:26:01.921289300-07:00" level=debug msg="Registering POST, /volumes/create"
time="2019-05-07T14:26:01.922285900-07:00" level=debug msg="Registering POST, /volumes/prune"
time="2019-05-07T14:26:01.922285900-07:00" level=debug msg="Registering DELETE, /volumes/{name:.*}"
time="2019-05-07T14:26:01.923283800-07:00" level=debug msg="Registering POST, /build"
time="2019-05-07T14:26:01.924290900-07:00" level=debug msg="Registering POST, /build/prune"
time="2019-05-07T14:26:01.924290900-07:00" level=debug msg="Registering POST, /build/cancel"
time="2019-05-07T14:26:01.925283700-07:00" level=debug msg="Registering POST, /session"
time="2019-05-07T14:26:01.926282000-07:00" level=debug msg="Registering POST, /swarm/init"
time="2019-05-07T14:26:01.926282000-07:00" level=debug msg="Registering POST, /swarm/join"
time="2019-05-07T14:26:01.927283400-07:00" level=debug msg="Registering POST, /swarm/leave"
time="2019-05-07T14:26:01.928282100-07:00" level=debug msg="Registering GET, /swarm"
time="2019-05-07T14:26:01.928282100-07:00" level=debug msg="Registering GET, /swarm/unlockkey"
time="2019-05-07T14:26:01.929291400-07:00" level=debug msg="Registering POST, /swarm/update"
time="2019-05-07T14:26:01.931280300-07:00" level=debug msg="Registering POST, /swarm/unlock"
time="2019-05-07T14:26:01.932282200-07:00" level=debug msg="Registering GET, /services"
time="2019-05-07T14:26:01.932282200-07:00" level=debug msg="Registering GET, /services/{id}"
time="2019-05-07T14:26:01.933285400-07:00" level=debug msg="Registering POST, /services/create"
time="2019-05-07T14:26:01.933285400-07:00" level=debug msg="Registering POST, /services/{id}/update"
time="2019-05-07T14:26:01.934283400-07:00" level=debug msg="Registering DELETE, /services/{id}"
time="2019-05-07T14:26:01.935282100-07:00" level=debug msg="Registering GET, /services/{id}/logs"
time="2019-05-07T14:26:01.935282100-07:00" level=debug msg="Registering GET, /nodes"
time="2019-05-07T14:26:01.937285600-07:00" level=debug msg="Registering GET, /nodes/{id}"
time="2019-05-07T14:26:01.938285800-07:00" level=debug msg="Registering DELETE, /nodes/{id}"
time="2019-05-07T14:26:01.941289500-07:00" level=debug msg="Registering POST, /nodes/{id}/update"
time="2019-05-07T14:26:01.942289700-07:00" level=debug msg="Registering GET, /tasks"
time="2019-05-07T14:26:01.943287500-07:00" level=debug msg="Registering GET, /tasks/{id}"
time="2019-05-07T14:26:01.944290300-07:00" level=debug msg="Registering GET, /tasks/{id}/logs"
time="2019-05-07T14:26:01.945287300-07:00" level=debug msg="Registering GET, /secrets"
time="2019-05-07T14:26:01.946287400-07:00" level=debug msg="Registering POST, /secrets/create"
time="2019-05-07T14:26:01.947287800-07:00" level=debug msg="Registering DELETE, /secrets/{id}"
time="2019-05-07T14:26:01.947287800-07:00" level=debug msg="Registering GET, /secrets/{id}"
time="2019-05-07T14:26:01.948283400-07:00" level=debug msg="Registering POST, /secrets/{id}/update"
time="2019-05-07T14:26:01.949283400-07:00" level=debug msg="Registering GET, /configs"
time="2019-05-07T14:26:01.950282600-07:00" level=debug msg="Registering POST, /configs/create"
time="2019-05-07T14:26:01.952282100-07:00" level=debug msg="Registering DELETE, /configs/{id}"
time="2019-05-07T14:26:01.952282100-07:00" level=debug msg="Registering GET, /configs/{id}"
time="2019-05-07T14:26:01.953286200-07:00" level=debug msg="Registering POST, /configs/{id}/update"
time="2019-05-07T14:26:01.954286500-07:00" level=debug msg="Registering GET, /plugins"
time="2019-05-07T14:26:01.954286500-07:00" level=debug msg="Registering GET, /plugins/{name:.*}/json"
time="2019-05-07T14:26:01.955284300-07:00" level=debug msg="Registering GET, /plugins/privileges"
time="2019-05-07T14:26:01.956311500-07:00" level=debug msg="Registering DELETE, /plugins/{name:.*}"
time="2019-05-07T14:26:01.957281800-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/enable"
time="2019-05-07T14:26:01.957281800-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/disable"
time="2019-05-07T14:26:01.958286000-07:00" level=debug msg="Registering POST, /plugins/pull"
time="2019-05-07T14:26:01.959285800-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/push"
time="2019-05-07T14:26:01.959285800-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/upgrade"
time="2019-05-07T14:26:01.960284400-07:00" level=debug msg="Registering POST, /plugins/{name:.*}/set"
time="2019-05-07T14:26:01.962286600-07:00" level=debug msg="Registering POST, /plugins/create"
time="2019-05-07T14:26:01.963282000-07:00" level=debug msg="Registering GET, /distribution/{name:.*}/json"
time="2019-05-07T14:26:01.964283600-07:00" level=debug msg="Registering POST, /grpc"
time="2019-05-07T14:26:01.964283600-07:00" level=debug msg="Registering GET, /networks"
time="2019-05-07T14:26:01.965281900-07:00" level=debug msg="Registering GET, /networks/"
time="2019-05-07T14:26:01.965281900-07:00" level=debug msg="Registering GET, /networks/{id:.+}"
time="2019-05-07T14:26:01.966283200-07:00" level=debug msg="Registering POST, /networks/create"
time="2019-05-07T14:26:01.968285800-07:00" level=debug msg="Registering POST, /networks/{id:.*}/connect"
time="2019-05-07T14:26:01.969283400-07:00" level=debug msg="Registering POST, /networks/{id:.*}/disconnect"
time="2019-05-07T14:26:01.969283400-07:00" level=debug msg="Registering POST, /networks/prune"
time="2019-05-07T14:26:01.973285400-07:00" level=debug msg="Registering DELETE, /networks/{id:.*}"
time="2019-05-07T14:26:01.974285300-07:00" level=info msg="API listen on //./pipe/docker_engine"
time="2019-05-07T14:26:01.975285600-07:00" level=debug msg="Config reload - waiting signal at Global\\docker-daemon-config-9372"

Container request (up to point at which new image hangs)

time="2019-05-07T14:26:11.083097400-07:00" level=debug msg="Calling HEAD /_ping"
time="2019-05-07T14:26:11.091100900-07:00" level=debug msg="Calling POST /v1.40/containers/create"
time="2019-05-07T14:26:11.092098500-07:00" level=debug msg="form data: {\"AttachStderr\":true,\"AttachStdin\":true,\"AttachStdout\":true,\"Cmd\":[\"/bin/sh\"],\"Domainname\":\"\",\"Entrypoint\":null,\"Env\":[],\"HostConfig\":{\"AutoRemove\":true,\"Binds\":null,\"BlkioDeviceReadBps\":null,\"BlkioDeviceReadIOps\":null,\"BlkioDeviceWriteBps\":null,\"BlkioDeviceWriteIOps\":null,\"BlkioWeight\":0,\"BlkioWeightDevice\":[],\"CapAdd\":null,\"CapDrop\":null,\"Capabilities\":null,\"Cgroup\":\"\",\"CgroupParent\":\"\",\"ConsoleSize\":[80,134],\"ContainerIDFile\":\"\",\"CpuCount\":0,\"CpuPercent\":0,\"CpuPeriod\":0,\"CpuQuota\":0,\"CpuRealtimePeriod\":0,\"CpuRealtimeRuntime\":0,\"CpuShares\":0,\"CpusetCpus\":\"\",\"CpusetMems\":\"\",\"DeviceCgroupRules\":null,\"DeviceRequests\":null,\"Devices\":[],\"DiskQuota\":0,\"Dns\":[],\"DnsOptions\":[],\"DnsSearch\":[],\"ExtraHosts\":null,\"GroupAdd\":null,\"IOMaximumBandwidth\":0,\"IOMaximumIOps\":0,\"IpcMode\":\"\",\"Isolation\":\"\",\"KernelMemory\":0,\"KernelMemoryTCP\":0,\"Links\":null,\"LogConfig\":{\"Config\":{},\"Type\":\"\"},\"MaskedPaths\":null,\"Memory\":0,\"MemoryReservation\":0,\"MemorySwap\":0,\"MemorySwappiness\":-1,\"NanoCpus\":0,\"NetworkMode\":\"default\",\"OomKillDisable\":false,\"OomScoreAdj\":0,\"PidMode\":\"\",\"PidsLimit\":0,\"PortBindings\":{},\"Privileged\":false,\"PublishAllPorts\":false,\"ReadonlyPaths\":null,\"ReadonlyRootfs\":false,\"RestartPolicy\":{\"MaximumRetryCount\":0,\"Name\":\"no\"},\"SecurityOpt\":null,\"ShmSize\":0,\"UTSMode\":\"\",\"Ulimits\":null,\"UsernsMode\":\"\",\"VolumeDriver\":\"\",\"VolumesFrom\":null},\"Hostname\":\"\",\"Image\":\"alpine\",\"Labels\":{},\"NetworkingConfig\":{\"EndpointsConfig\":{}},\"OnBuild\":null,\"OpenStdin\":true,\"StdinOnce\":true,\"Tty\":true,\"User\":\"\",\"Volumes\":{},\"WorkingDir\":\"\"}"
time="2019-05-07T14:26:11.097100500-07:00" level=debug msg="lcowdriver: createreadwrite: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632"
time="2019-05-07T14:26:11.097100500-07:00" level=debug msg="lcowdriver: create: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 parent: 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424"
time="2019-05-07T14:26:11.098105600-07:00" level=debug msg="lcowdriver: getlayerchain: id 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424 json C:\\ProgramData\\docker\\lcow\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\\layerchain.json"
time="2019-05-07T14:26:11.099098100-07:00" level=debug msg="lcowdriver: exists: id 6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424 true"
time="2019-05-07T14:26:11.099098100-07:00" level=debug msg="lcowdriver: create: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: creating C:\\ProgramData\\docker\\lcow\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632"
time="2019-05-07T14:26:11.100099600-07:00" level=debug msg="lcowdriver: setlayerchain: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 json C:\\ProgramData\\docker\\lcow\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632\\layerchain.json"
time="2019-05-07T14:26:11.101108000-07:00" level=debug msg="lcowdriver: create: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: success"
time="2019-05-07T14:26:11.102104500-07:00" level=debug msg="lcowdriver: createreadwrite: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: locking cachedSandboxMutex"
time="2019-05-07T14:26:11.103098800-07:00" level=debug msg="lcowdriver: createreadwrite: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: releasing cachedSandboxMutex"
time="2019-05-07T14:26:11.103098800-07:00" level=debug msg="lcowdriver: createreadwrite: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: using cached sandbox to populate"
time="2019-05-07T14:26:11.136107600-07:00" level=debug msg="Calling POST /v1.40/containers/fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632/attach?stderr=1&stdin=1&stdout=1&stream=1"
time="2019-05-07T14:26:11.139106700-07:00" level=debug msg="attach: stdin: begin"
time="2019-05-07T14:26:11.140106500-07:00" level=debug msg="attach: stdout: begin"
time="2019-05-07T14:26:11.140106500-07:00" level=debug msg="attach: stderr: begin"
time="2019-05-07T14:26:11.142104500-07:00" level=debug msg="Calling POST /v1.40/containers/fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632/wait?condition=removed"
time="2019-05-07T14:26:11.144105300-07:00" level=debug msg="Calling POST /v1.40/containers/fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632/start"
time="2019-05-07T14:26:11.151102200-07:00" level=debug msg="Assigning addresses for endpoint boring_cori's interface on network nat"
time="2019-05-07T14:26:11.152101900-07:00" level=debug msg="RequestAddress(172.22.176.0/20, <nil>, map[])"
time="2019-05-07T14:26:11.153099100-07:00" level=debug msg="endpointStruct.EnableInternalDNS =[false]"
time="2019-05-07T14:26:11.154099100-07:00" level=debug msg="[POST]=>[/endpoints/] Request : {\"VirtualNetwork\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"EnableInternalDNS\":true}"
time="2019-05-07T14:26:11.165106900-07:00" level=debug msg="Network Response : {\"ActivityId\":\"B780409F-A937-4F8A-8693-58AB884477B4\",\"AdditionalParams\":{},\"CreateProcessingStartTime\":132017379711611059,\"DNSServerList\":\"172.22.176.1,10.240.0.10,10.240.1.10\",\"DNSSuffix\":\"delivery.puppetlabs.net\",\"EnableInternalDNS\":true,\"EnableLowInterfaceMetric\":true,\"EncapOverhead\":0,\"GatewayAddress\":\"172.22.176.1\",\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132017379711581008},\"ID\":\"FF565EEE-25D4-418F-B378-FB0A4934308E\",\"IPAddress\":\"172.22.191.224\",\"MacAddress\":\"00-15-5D-3C-24-7A\",\"Name\":\"Ethernet\",\"Policies\":[],\"PrefixLength\":20,\"Resources\":{\"AdditionalParams\":{},\"AllocationOrder\":0,\"Health\":{\"LastErrorCode\":0,\"LastUpdateTime\":132017379711581008},\"ID\":\"B780409F-A937-4F8A-8693-58AB884477B4\",\"PortOperationTime\":0,\"State\":1,\"SwitchOperationTime\":0,\"VfpOperationTime\":0,\"parentId\":\"91673930-4046-438B-8BAE-3B43A3831BFD\"},\"SharedContainers\":[],\"State\":1,\"Type\":\"nat\",\"Version\":38654705666,\"VirtualNetwork\":\"F6116DEF-C9B7-45CF-9FE7-CB172A856106\",\"VirtualNetworkName\":\"nat\"}"
time="2019-05-07T14:26:11.176099800-07:00" level=debug msg="Assigning addresses for endpoint boring_cori's interface on network nat"
time="2019-05-07T14:26:11.184102800-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:11.186106700-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:11.191103000-07:00" level=debug msg="Programming external connectivity on endpoint boring_cori (f92e3ea6789eafe7a85417fc3a8987f3aebd9270a919320c3552ceebed828a79)"
time="2019-05-07T14:26:11.192106100-07:00" level=debug msg="EnableService fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 START"
time="2019-05-07T14:26:11.193106600-07:00" level=debug msg="EnableService fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 DONE"
time="2019-05-07T14:26:11.198102100-07:00" level=debug msg="lcowdriver: getmetadata: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632"
time="2019-05-07T14:26:11.201108100-07:00" level=debug msg="Generated spec: {\"ociVersion\":\"1.0.1-dev\",\"process\":{\"terminal\":true,\"consoleSize\":{\"height\":80,\"width\":134},\"user\":{\"uid\":0,\"gid\":0},\"args\":[\"/bin/sh\"],\"env\":[\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\",\"HOSTNAME=fb0499961e93\",\"TERM=xterm\"],\"cwd\":\"/\",\"capabilities\":{\"bounding\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"effective\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"inheritable\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"permitted\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"]}},\"root\":{\"path\":\"rootfs\"},\"hostname\":\"fb0499961e93\",\"mounts\":[{\"destination\":\"/proc\",\"type\":\"proc\",\"source\":\"proc\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev\",\"type\":\"tmpfs\",\"source\":\"tmpfs\",\"options\":[\"nosuid\",\"strictatime\",\"mode=755\",\"size=65536k\"]},{\"destination\":\"/dev/pts\",\"type\":\"devpts\",\"source\":\"devpts\",\"options\":[\"nosuid\",\"noexec\",\"newinstance\",\"ptmxmode=0666\",\"mode=0620\",\"gid=5\"]},{\"destination\":\"/sys\",\"type\":\"sysfs\",\"source\":\"sysfs\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"ro\"]},{\"destination\":\"/sys/fs/cgroup\",\"type\":\"cgroup\",\"source\":\"cgroup\",\"options\":[\"ro\",\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/mqueue\",\"type\":\"mqueue\",\"source\":\"mqueue\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/shm\",\"type\":\"tmpfs\",\"source\":\"shm\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"mode=1777\"]}],\"linux\":{\"resources\":{},\"namespaces\":[{\"type\":\"mount\"},{\"type\":\"network\"},{\"type\":\"uts\"},{\"type\":\"pid\"},{\"type\":\"ipc\"}],\"maskedPaths\":[\"/proc/asound\",\"/proc/acpi\",\"/proc/kcore\",\"/proc/keys\",\"/proc/latency_stats\",\"/proc/timer_list\",\"/proc/timer_stats\",\"/proc/sched_debug\",\"/proc/scsi\",\"/sys/firmware\"],\"readonlyPaths\":[\"/proc/bus\",\"/proc/fs\",\"/proc/irq\",\"/proc/sys\",\"/proc/sysrq-trigger\"]},\"windows\":{\"layerFolders\":[\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\",\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632\"],\"hyperv\":{},\"network\":{\"endpointList\":[\"FF565EEE-25D4-418F-B378-FB0A4934308E\"],\"allowUnqualifiedDNSQuery\":true}}}"
time="2019-05-07T14:26:11.204097200-07:00" level=debug msg="libcontainerd: createLinux(): containerId fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 "
time="2019-05-07T14:26:11.205098600-07:00" level=debug msg="hcsshim::NameToGuid" name=6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424
time="2019-05-07T14:26:11.205098600-07:00" level=debug msg="hcsshim::NameToGuid - succeeded" guid=b7dd4f52-64c9-59ea-b002-5a27a996f437 name=6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424
time="2019-05-07T14:26:11.206100700-07:00" level=debug msg="hcsshim::CreateComputeSystem - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:11.207105600-07:00" level=debug msg="HCS ComputeSystem Document" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 json="{\"SystemType\":\"container\",\"Name\":\"fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632\",\"Owner\":\"docker\",\"LayerFolderPath\":\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632\",\"Layers\":[{\"ID\":\"b7dd4f52-64c9-59ea-b002-5a27a996f437\",\"Path\":\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\\\\layer.vhd\"}],\"HvPartition\":true,\"EndpointList\":[\"FF565EEE-25D4-418F-B378-FB0A4934308E\"],\"HvRuntime\":{\"ImagePath\":\"C:\\\\Program Files\\\\Linux Containers\",\"LinuxInitrdFile\":\"initrd.img\",\"LinuxKernelFile\":\"kernel\"},\"AllowUnqualifiedDNSQuery\":true,\"ContainerType\":\"linux\",\"TerminateOnLastHandleClosed\":true}"

Container Request.. Continued Success

time="2019-05-07T14:26:13.376788300-07:00" level=debug notification-type=SystemCreateCompleted system-id=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.376788300-07:00" level=debug msg="hcsshim::CreateComputeSystem - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.378790800-07:00" level=debug msg="starting container" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 module=libcontainerd namespace=moby
time="2019-05-07T14:26:13.379786700-07:00" level=debug msg="hcsshim::ComputeSystem::Start - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.444931700-07:00" level=debug notification-type=SystemStartCompleted system-id=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.446933100-07:00" level=debug msg="hcsshim::ComputeSystem::Start - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.447940000-07:00" level=debug msg="createLinux() completed successfully" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 module=libcontainerd namespace=moby
time="2019-05-07T14:26:13.449930900-07:00" level=debug msg="hcsshim::ComputeSystem::CreateProcess - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.451929100-07:00" level=debug msg="HCS ComputeSystem Process Document" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 json="{\"CommandArgs\":[\"/bin/sh\"],\"WorkingDirectory\":\"/\",\"Environment\":{\"HOSTNAME\":\"fb0499961e93\",\"PATH\":\"/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\",\"TERM\":\"xterm\"},\"EmulateConsole\":true,\"CreateStdInPipe\":true,\"CreateStdOutPipe\":true,\"ConsoleSize\":[80,134],\"OCISpecification\":{\"ociVersion\":\"1.0.1-dev\",\"process\":{\"terminal\":true,\"consoleSize\":{\"height\":80,\"width\":134},\"user\":{\"uid\":0,\"gid\":0},\"args\":[\"/bin/sh\"],\"env\":[\"PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin\",\"HOSTNAME=fb0499961e93\",\"TERM=xterm\"],\"cwd\":\"/\",\"capabilities\":{\"bounding\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"effective\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"inheritable\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"],\"permitted\":[\"CAP_CHOWN\",\"CAP_DAC_OVERRIDE\",\"CAP_FSETID\",\"CAP_FOWNER\",\"CAP_MKNOD\",\"CAP_NET_RAW\",\"CAP_SETGID\",\"CAP_SETUID\",\"CAP_SETFCAP\",\"CAP_SETPCAP\",\"CAP_NET_BIND_SERVICE\",\"CAP_SYS_CHROOT\",\"CAP_KILL\",\"CAP_AUDIT_WRITE\"]}},\"root\":{\"path\":\"rootfs\"},\"hostname\":\"fb0499961e93\",\"mounts\":[{\"destination\":\"/proc\",\"type\":\"proc\",\"source\":\"proc\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev\",\"type\":\"tmpfs\",\"source\":\"tmpfs\",\"options\":[\"nosuid\",\"strictatime\",\"mode=755\",\"size=65536k\"]},{\"destination\":\"/dev/pts\",\"type\":\"devpts\",\"source\":\"devpts\",\"options\":[\"nosuid\",\"noexec\",\"newinstance\",\"ptmxmode=0666\",\"mode=0620\",\"gid=5\"]},{\"destination\":\"/sys\",\"type\":\"sysfs\",\"source\":\"sysfs\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"ro\"]},{\"destination\":\"/sys/fs/cgroup\",\"type\":\"cgroup\",\"source\":\"cgroup\",\"options\":[\"ro\",\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/mqueue\",\"type\":\"mqueue\",\"source\":\"mqueue\",\"options\":[\"nosuid\",\"noexec\",\"nodev\"]},{\"destination\":\"/dev/shm\",\"type\":\"tmpfs\",\"source\":\"shm\",\"options\":[\"nosuid\",\"noexec\",\"nodev\",\"mode=1777\"]}],\"linux\":{\"resources\":{},\"namespaces\":[{\"type\":\"mount\"},{\"type\":\"network\"},{\"type\":\"uts\"},{\"type\":\"pid\"},{\"type\":\"ipc\"}],\"maskedPaths\":[\"/proc/asound\",\"/proc/acpi\",\"/proc/kcore\",\"/proc/keys\",\"/proc/latency_stats\",\"/proc/timer_list\",\"/proc/timer_stats\",\"/proc/sched_debug\",\"/proc/scsi\",\"/sys/firmware\"],\"readonlyPaths\":[\"/proc/bus\",\"/proc/fs\",\"/proc/irq\",\"/proc/sys\",\"/proc/sysrq-trigger\"]},\"windows\":{\"layerFolders\":[\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\6f396a7e238abd9a472f797928e0d016a8de0561c914e5bb4de090d44f0a1424\",\"C:\\\\ProgramData\\\\docker\\\\lcow\\\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632\"],\"hyperv\":{},\"network\":{\"endpointList\":[\"FF565EEE-25D4-418F-B378-FB0A4934308E\"],\"allowUnqualifiedDNSQuery\":true}}}}"
time="2019-05-07T14:26:13.450935000-07:00" level=info msg="sending event" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 event=create module=libcontainerd namespace=moby
time="2019-05-07T14:26:13.712015800-07:00" level=debug msg="HCS ComputeSystem CreateProcess PID" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:13.715926100-07:00" level=debug msg="hcsshim::ComputeSystem::CreateProcess - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:13.717925200-07:00" level=debug msg="init process started" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 module=libcontainerd namespace=moby pid=293
time="2019-05-07T14:26:13.718937100-07:00" level=debug msg="hcsshim::Process::Stdio - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:13.719923300-07:00" level=debug msg="hcsshim::Process::Stdio - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:13.719923300-07:00" level=debug msg="hcsshim::Process::Wait - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:13.721928400-07:00" level=debug msg="start() completed" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 module=libcontainerd namespace=moby
time="2019-05-07T14:26:13.721928400-07:00" level=info msg="sending event" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 event=start event-info="{fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 init 293 0 0001-01-01 00:00:00 +0000 UTC false <nil>}" module=libcontainerd namespace=moby
time="2019-05-07T14:26:13.734928000-07:00" level=debug msg="Calling POST /v1.40/containers/fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632/resize?h=80&w=134"
time="2019-05-07T14:26:13.735934000-07:00" level=debug msg=resizing container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 height=80 module=libcontainerd namespace=moby pid=293 process=init width=134
time="2019-05-07T14:26:13.736924700-07:00" level=debug msg="hcsshim::Process::ResizeConsole - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:13.740923700-07:00" level=debug msg="hcsshim::Process::ResizeConsole - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.494787900-07:00" level=debug notification-type=ProcessExited pid=293 system-id=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.503020000-07:00" level=debug msg="hcsshim::Process::Wait - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.505016000-07:00" level=debug msg="hcsshim::Process::ExitCode - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.506016600-07:00" level=debug msg="hcsshim::Process::Properties - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.507018600-07:00" level=debug msg="hcsshim::Process::Properties - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.508018000-07:00" level=debug msg="hcsshim::Process::ExitCode - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.508018000-07:00" level=debug msg="hcsshim::Process::Close - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.510016400-07:00" level=debug msg="hcsshim::Process::Close - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.510016400-07:00" level=debug msg="hcsshim::ComputeSystem::Shutdown - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.603771000-07:00" level=debug msg="hcsshim::ComputeSystem::Shutdown - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.615234000-07:00" level=debug msg="completed container shutdown" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 module=libcontainerd namespace=moby process=init
time="2019-05-07T14:26:15.617233600-07:00" level=debug msg="hcsshim::ComputeSystem::Close - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.615234000-07:00" level=debug notification-type=SystemExited system-id=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.618238000-07:00" level=debug msg="hcsshim::ComputeSystem::Close - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.619233400-07:00" level=info msg="sending event" container=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 event=exit event-info="{fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 init 293 0 2019-05-07 14:26:15.505016 -0700 PDT m=+14.446729501 false <nil>}" module=libcontainerd namespace=moby
time="2019-05-07T14:26:15.620232000-07:00" level=debug msg="hcsshim::Process::CloseStdin - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 pid=293
time="2019-05-07T14:26:15.621229400-07:00" level=error msg="hcsshim::Process::CloseStdin - End Operation - Error" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 error="hcsshim::Process::CloseStdin fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632:293: hcsshim: the handle has already been closed" pid=293
time="2019-05-07T14:26:15.620232000-07:00" level=debug msg="attach: stdout: end"
time="2019-05-07T14:26:15.620232000-07:00" level=debug msg="attach: stdin: end"
time="2019-05-07T14:26:15.620232000-07:00" level=debug msg="attach: stderr: end"
time="2019-05-07T14:26:15.624231400-07:00" level=debug msg="attach done"
time="2019-05-07T14:26:15.625230400-07:00" level=debug msg="Revoking external connectivity on endpoint boring_cori (f92e3ea6789eafe7a85417fc3a8987f3aebd9270a919320c3552ceebed828a79)"
time="2019-05-07T14:26:15.630229900-07:00" level=debug msg="hcsshim::OpenComputeSystem - Begin Operation" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.631228700-07:00" level=debug msg="hcsshim::OpenComputeSystem - End Operation - Success" cid=fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632
time="2019-05-07T14:26:15.643234000-07:00" level=debug msg="[DELETE]=>[/endpoints/FF565EEE-25D4-418F-B378-FB0A4934308E] Request : "
time="2019-05-07T14:26:15.664231500-07:00" level=debug msg="Releasing addresses for endpoint boring_cori's interface on network nat"
time="2019-05-07T14:26:15.666231600-07:00" level=debug msg="ReleaseAddress(172.22.176.0/20, 172.22.191.224)"
time="2019-05-07T14:26:15.705228500-07:00" level=debug msg="lcowdriver: remove: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632"
time="2019-05-07T14:26:15.706230700-07:00" level=debug msg="lcowdriver: remove: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: layerPath C:\\ProgramData\\docker\\lcow\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632"
time="2019-05-07T14:26:15.709234900-07:00" level=debug msg="lcowdriver: remove: id fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632: layerPath C:\\ProgramData\\docker\\lcow\\fb0499961e935b8412cc1b7e8c327d676510b68b526638a3f4d1b27d0f2ed632 succeeded"
time="2019-05-07T14:26:15.712236500-07:00" level=debug msg="Closing buffered stdin pipe"

from lcow.

lowenna commented on June 19, 2024

Hmmm. Those binaries seem to work just fine on my RS5 machine (slightly older build than yours - UBR 253 rather than your 437). So I think it's more environmental than a fundamental issue:

The docker run part showing the kernel version

PS C:\Program Files\Linux Containers> docker run --rm -it alpine /bin/sh
/ # uname -r
4.19.27-linuxkit
/ #

And the utility VM side showing the commit that was used to build:

PS E:\go\src\github.com\docker\docker> hcsdiag list
5346eaa10c183d6636e91353c83a6605b731202a9ff2194e4af12f6f099de0aa
    Hyper-V Linux Container,    Running, 80463CCB-87A4-4627-A332-31A414B90E97, docker

PS E:\go\src\github.com\docker\docker> hcsdiag console -uvm 5346eaa10c183d6636e91353c83a6605b731202a9ff2194e4af12f6f099de0aa
/ # ls -l
total 52
drwxr-xr-x    2 root     root          1820 Apr 17 21:31 bin
drwxr-xr-x    2 root     root            40 Apr 18  2017 boot
drwxr-xr-x    9 root     root          3180 May  7 22:13 dev
drwx------   14 root     root           660 Apr 18  2017 etc
-rw-r--r--    1 root     root             5 Apr 17 21:31 gcs.branch
-rw-r--r--    1 root     root            41 Apr 17 21:31 gcs.commit
drwxr-xr-x    2 root     root            40 Apr 17 21:30 home
-rwxr-xr-x    1 root     root         42856 Apr 17 21:31 init
drwxr-xr-x    4 root     root           360 Apr 17 21:30 lib
drwx------    2 root     root            40 Jan  1  1970 lost+found
drwxr-xr-x    5 root     root           100 Apr 17 21:30 media
drwxr-xr-x    2 root     root            40 Apr 17 21:30 mnt
drwxr-xr-x    2 root     root            40 Apr 17 21:30 opt
dr-xr-xr-x   84 root     root             0 May  7 22:13 proc
drwx------    2 root     root            40 Apr 17 21:30 root
drwxr-xr-x    4 root     root            80 May  7 22:13 run
drwxr-xr-x    2 root     root          1400 Apr 17 21:31 sbin
drwxr-xr-x    2 root     root            40 Apr 17 21:30 srv
dr-xr-xr-x   13 root     root             0 May  7 22:13 sys
drwxrwxrwt    5 root     root           140 May  7 22:13 tmp
drwxr-xr-x    7 root     root           140 Apr 17 21:30 usr
drwxr-xr-x   10 root     root           220 Apr 17 21:30 var
/ # cat gcs.branch
HEAD
/ # cat gcs.commit
fefd0ad73f6bfa33b8cce597be303e134fddc408
/ #

It it 100% repro? Or intermittent? How long does it appear to hang for? Does it eventually timeout? Are you able to pinpoint which of the two files is the culprit (ie switch in just initrd.img or just kernel)?

from lcow.

Iristyle commented on June 19, 2024

I can reproduce the issue 100% on two separate machines (with the same Windows kernel) - one running locally and another Azure hosted VM. Both are VMs rather than bare metal if the nested virtualization makes any difference.

Kernel Version: 10.0 17763 (17763.1.amd64fre.rs5_release.180914-1434)
Operating System: Windows 10 Enterprise Version 1809 (OS Build 17763.475)

Kernel Version: 10.0 17763 (17763.1.amd64fre.rs5_release.180914-1434)
Operating System: Windows 10 Pro Version 1809 (OS Build 17763.475)

That said, I haven't verified if the hang will time out - though I do know it will hang for several minutes at least. Let me try to run a few quick tests to investigate further.

Are there any lower-level logs that I can get from the event log or elsewhere for the hcs bits?

from lcow.

Iristyle commented on June 19, 2024

Ok, I can at least confirm that the kernel appears to be the issue. If I drop the newer initrd.img in with the older kernel, I can launch Alpine:

PS C:\Windows\system32> docker --log-level debug run -it --rm alpine /bin/sh
/ # uname -a
Linux c5c4ce4ae720 4.19.27-linuxkit #1 SMP Sun Mar 10 18:51:44 UTC 2019 x86_64 Linux

PS C:\Windows\system32> hcsdiag list
c5c4ce4ae720b5e2f601630ebc49c9b5ae17154eff60e0f9c648c5f2c218bffd
    Hyper-V Linux Container,    Running, A1D30201-A7D2-4B6C-965F-B8573528E841, docker

PS C:\Windows\system32> hcsdiag console -uvm c5c4ce4ae720b5e2f601630ebc49c9b5ae17154eff60e0f9c648c5f2c218bffd
/ # cat gcs.branch
HEAD
/ # cat gcs.commit
fefd0ad73f6bfa33b8cce597be303e134fddc408
/ # ls -l
total 52
drwxr-xr-x    2 root     root          1820 Apr 17 21:31 bin
drwxr-xr-x    2 root     root            40 Apr 18  2017 boot
drwxr-xr-x    9 root     root          3180 May  7 22:56 dev
drwx------   14 root     root           660 Apr 18  2017 etc
-rw-r--r--    1 root     root             5 Apr 17 21:31 gcs.branch
-rw-r--r--    1 root     root            41 Apr 17 21:31 gcs.commit
drwxr-xr-x    2 root     root            40 Apr 17 21:30 home
-rwxr-xr-x    1 root     root         42856 Apr 17 21:31 init
drwxr-xr-x    4 root     root           360 Apr 17 21:30 lib
drwx------    2 root     root            40 Jan  1  1970 lost+found
drwxr-xr-x    5 root     root           100 Apr 17 21:30 media
drwxr-xr-x    2 root     root            40 Apr 17 21:30 mnt
drwxr-xr-x    2 root     root            40 Apr 17 21:30 opt
dr-xr-xr-x   76 root     root             0 May  7 22:56 proc
drwx------    2 root     root            40 Apr 17 21:30 root
drwxr-xr-x    4 root     root            80 May  7 22:56 run
drwxr-xr-x    2 root     root          1400 Apr 17 21:31 sbin
drwxr-xr-x    2 root     root            40 Apr 17 21:30 srv
dr-xr-xr-x   13 root     root             0 May  7 22:56 sys
drwxrwxrwt    5 root     root           140 May  7 22:56 tmp
drwxr-xr-x    7 root     root           140 Apr 17 21:30 usr
drwxr-xr-x   10 root     root           220 Apr 17 21:30 var

However, if I drop the newer kernel in with the old initrd.img, it hangs. Will fire things up with the bad kernel for a max of 10 minutes to see if the hang is only temporary.

from lcow.

Iristyle commented on June 19, 2024

After a period of 4 minutes, I do get a log entry immediately after the entry for HCS ComputeSystem Document coming from https://github.com/microsoft/hcsshim/blob/master/internal/hcs/watcher.go#L38

time="2019-05-07T16:09:30.574113400-07:00" level=warning msg="Syscall did not complete within operation timeout. This may indicate a platform issue. If it appears to be making no forward progress, obtain the stacks and see if there is a syscall stuck in the platform API for a significant length of time." cid=6f2c2b94e2473d651610e298940c7830dcfa4716248c53c8303974531a48ee4e timeout=4m0s

from lcow.

lowenna commented on June 19, 2024

Hmmm this is going to be difficult to debug as getting the dmesg output from the kernel in the v1 HCS schema (which docker currently uses) is a) broken and unlikely to be fixed as we're aggressively moving to the v2 schema via containerd runtime and b) might need internal/non-public tools to grab the serial console output unless https://github.com/jstarks/npiperelay could be used as an alternate. Can you try in the first instance building uvmboot from https://github.com/microsoft/hcsshim/tree/master/internal/tools/uvmboot to see if a utility VM with that kernel will even boot on your machines. That tool uses the v2 schema, so we would be closer to getting the dmesg output that way. @kevpar might have a good example of how to use uvmboot with an arbitrary initrd and kernel.

from lcow.

Andy2244 commented on June 19, 2024

Sorry if it wasn't clear, but a simple docker run does not work either.

I have the same issue, docker run --rm -it alpine /bin/sh just hangs with LCOW build from master, the latest release (v4.14.35-v0.3.9) bins work.

Docker version master-dockerproject-2019-06-05, build c02f389c
Kernel Version: 10.0 18362 (18362.1.amd64fre.19h1_release.190318-1202)
Operating System: Windows 10 Pro Version 1903 (OS Build 18362.145)

It works if i omit the 17/april changes and build from this commit:
git checkout d5dfdbc7d754f17b2c9ba05e867dc96aa192200b

uname -r
4.19.27-linuxkit

from lcow.

lowenna commented on June 19, 2024

It definitely appears to be a kernel issue. The initrd.img from the link above works fine. I'm running an internally built kernel on 4.19.24 which is fine, the linuxkit 4.19.27 must be missing some config option. @rn Where is the config the kernel was built from? Were there any obvious changes recently as I'm pretty certain I have had a 4.19 linuxkit kernel working.

from lcow.

rn commented on June 19, 2024

@jhowardmsft the kernel config is at https://github.com/linuxkit/linuxkit/blob/master/kernel/config-4.19.x-x86_64

I don't think there were any significant changes recently to the config file.

from lcow.

rn commented on June 19, 2024

Thanks for the triage. The LinuxKit changes themselves are very unlikely candidates. More likely is that some code in the Linux kernel itself changed. I did a quick scan through the changes from v4.19.28..v4.19.29 and not much sticks out. Without getting console output this almost impossible to debug (and I don't have access to a windows machine anymore)

from lcow.

Latest dependency bump of OpenGCS, Alpine, kernel, runc doesn't work about lcow HOT 15 OPEN

Comments (15)

Initial Daemon startup

Container request

Initial Daemon Start

Container request (up to point at which new image hangs)

Container Request.. Continued Success

Related Issues (20)

Recommend Projects

React

Vue.js

Typescript

TensorFlow

Django

Laravel

D3

Recommend Topics

javascript

web

server

Machine learning

Visualization

Game

Recommend Org

Facebook

Microsoft

Google

Alibaba

D3

Tencent