Build hangs on macOS Sierra Beta 2

icrackedsteven · July 6, 2016, 9:13pm

I’m trying to run a simple build on Beta 2 of Sierra and the build command always hangs either when sending the build context to the daemon, or when pulling the base image. On my larger build, it always hangs a few megabytes into sending the context.

The only way to try again is to reset to factory defaults, but that doesn’t seem to help at all.

I’ve actually seen the same behavior running the latest Docker Toolbox on Sierra, which has me wondering if this is an issue with Docker itself, but it’s hard to be sure.

Information

Docker 1.12.0-rc2-beta17
macOS Sierra 10.12 Beta (16A239j)
Diagnostic before running the build: E14D28EB-2F1A-4F78-BEB3-80750B05630A
Diagnostic while the build is in progress (hung): DAF39241-0F31-47EE-8486-8653A91DE872

Steps to reproduce the behavior

Use this Dockerfile with the above OS/Docker versions:

FROM node:5.11

ADD .

In an empty directory, place the Dockerfile, and run:

docker build .

phyllisstein · July 6, 2016, 10:43pm

Hey there! I’m seeing the same thing on Sierra Beta 2, and can confirm that the issue appears to affect a brewed installation of stable Docker as well.

This is the error I’m seeing with stable Docker:

docker-machine create --driver=vmwarefusion --vmwarefusion-disk-size=100000 --vmwarefusion-memory-size=4096 --vmwarefusion-cpu-count=4 default
Running pre-create checks...
(default) No default Boot2Docker ISO found locally, downloading the latest release...
(default) Latest release for github.com/boot2docker/boot2docker is v1.11.2
(default) Downloading /Users/daniel/.docker/machine/cache/boot2docker.iso from https://github.com/boot2docker/boot2docker/releases/download/v1.11.2/boot2docker.iso...
Error attempting heartbeat call to plugin server: unexpected EOF
(default) 0%....
Error with pre-create check: "unexpected EOF"

Meanwhile, restoring Docker Beta and running the diagnostic (mid-hang, after updating CPU and memory settings) yielded this diagnostic: F59FBA2E-EC73-45E0-A43A-324E7465D2E8. I’ve also uploaded my Docker Beta syslogs here: http://☁.shannon.camp/ge7p.

I’d be grateful for any insight into fixing this! I’m kind of at my wits’ end.

truetech · July 7, 2016, 4:44pm

Having the same issue with beta18 on Sierra beta 2, build hangs about a half-second into pulling any image and is unresponsive for any other command until it’s reset. Factory reset or full uninstall let me try again, but the same issue happens immediately.

Diagnostic ID during the hang:
E3B0511A-686D-4538-9B8F-DDCC8F321A0C

Any ideas would be greatly appreciated!

stunthamster · July 7, 2016, 7:58pm

Hmmm, I’m also having issues with Veertu as well which (I believe) uses the same underlying hypervisor framework. Looks like Beta2 might have broken that framework in some fashion. Veertu VM’s crash immediately, and I suspect that’s what’s hanging in this case with Docker.

phyllisstein · July 7, 2016, 11:23pm

Reverting to Sierra Beta 1 resolved the issue for me with both Docker for Mac and the Homebrew-packaged Docker. Just for kicks, I decided to install the Beta 2 update and see if that broke it again. Lo and behold:

~ ❯❯❯ time docker ps
CONTAINER ID        IMAGE                            COMMAND                  CREATED             STATUS              PORTS                    NAMES
5ef3d1618b71        docker.ofh.priv/kandula:latest   "/docker-entrypoint.s"   4 minutes ago       Up 2 minutes        0.0.0.0:5432->5432/tcp   metol_kandula_1
docker ps  0.01s user 0.01s system 0% cpu 2:21.04 total

~ ❯❯❯ time docker ps
CONTAINER ID        IMAGE                            COMMAND                  CREATED             STATUS              PORTS                    NAMES
5ef3d1618b71        docker.ofh.priv/kandula:latest   "/docker-entrypoint.s"   8 minutes ago       Up 6 minutes        0.0.0.0:5432->5432/tcp   metol_kandula_1
docker ps  0.01s user 0.01s system 0% cpu 3:39.68 total

Here’s a tail of syslog -k Sender Docker:

Jul  7 19:11:26 zbornak Docker[1804] <Notice>: Application version: 1.12.0-rc3-beta18 (9996)
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: OSX Version: Version 10.12 (Build 16A239j)
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: migrator: start migration process
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: migrator: previous install detected
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: migrator: previous version is 1.12.0-rc3-beta18
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: migrator: no migration needed, you are good to go!
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: migrator: end of migration process
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: event tracker initialized
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: Bundle path: /Applications/Docker.app
Jul  7 19:11:26 zbornak Docker[1817] <Notice>: Client reports version 1, commit ec40b14c72adc0bff3b01fa8886dae7f2eee1541
Jul  7 19:11:26 zbornak Docker[1817] <Notice>: EOF reading packet from Unix domain socket: closing
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: com.docker.docker commit: ec40b14c72adc0bff3b01fa8886dae7f2eee1541 wants version 16
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: com.docker.vmnetd commit: 5cbabb0783b6d281b8f579cb9f4dff38373b2c10 has version 16
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: probe of /var/tmp/com.docker.vmnetd.socket successful: not reinstalling component
Jul  7 19:11:26 zbornak Docker[1817] <Error>: Failed to read command from client
Jul  7 19:11:26 zbornak Docker[1818] <Notice>: Client reports version 1, commit ec40b14c72adc0bff3b01fa8886dae7f2eee1541
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: client with uid 501 requests com.docker.vmnetd install symlinks
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /usr/local/bin/docker is a symlink
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /usr/local/bin/docker-compose is a symlink
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /var/run/docker.sock is a symlink or a socket
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /usr/local/bin/notary is a symlink
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /var/run/docker.sock is a symlink or a socket
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /usr/local/bin/docker-machine is a symlink
Jul  7 19:11:26 zbornak Docker[1818] <Critical>: File /var/run/docker.sock is a symlink or a socket
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: Symlinks are valid.
Jul  7 19:11:26 zbornak Docker[1804] <Notice>: com.docker.osx.hyperkit.linux launched with PID 1823
Jul  7 19:11:26 zbornak Docker[1822] <Critical>: dial unix /Users/daniel/Library/Containers/com.docker.docker/Data/s20: connect: connection refused
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Acquired task manager lock
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Starting com.docker.db, com.docker.osxfs, com.docker.slirp, com.docker.osx.hyperkit.linux, com.docker.driver.amd64-linux
Jul  7 19:11:26 zbornak Docker[1804] <Error>: frontend process failed
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Start com.docker.db (pid 1827)
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Start com.docker.osxfs (pid 1828)
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Start com.docker.slirp (pid 1829)
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Start com.docker.osx.hyperkit.linux (pid 1830)
Jul  7 19:11:26 zbornak Docker[1830] <Notice>: API server starting
Jul  7 19:11:26 zbornak Docker[1830] <Notice>: 🍀 socket path is: /Users/daniel/Library/Containers/com.docker.docker/Data/s20
Jul  7 19:11:26 zbornak Docker[1830] <Notice>: API socket removed
Jul  7 19:11:26 zbornak Docker[1830] <Notice>: VM shutdown at 2016-07-07 18:58:57 -0400 EDT
Jul  7 19:11:26 zbornak Docker[1828] <Notice>: Logging to Apple System Log
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: Logging to Apple System Log
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: Setting handler to ignore all SIGPIPE signals
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: Setting soft fd limit to 10240
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: starting port_forwarding port_control_path:fd:4 vsock_path:/Users/daniel/Library/Containers/com.docker.docker/Data/@connect
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: attempting to reconnect to database
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: reconnected transport layer
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: allowing binds to any IP addresses
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: Creating slirp server pcap_settings:disabled peer_ip:192.168.65.2 local_ip:192.168.65.1
Jul  7 19:11:26 zbornak Docker[1823] <Notice>: Start com.docker.driver.amd64-linux (pid 1831)
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: Acquired hypervisor lock
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: Docker is not responding: Get http://./info: dial unix /Users/daniel/Library/Containers/com.docker.docker/Data/*00000003.00000948: connect: connection refused: waiting 0.5s
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: hypervisor: native
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: filesystem: osxfs
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: network: hybrid
Jul  7 19:11:26 zbornak Docker[1828] <Notice>: Using protocol TwoThousand msize 16384
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: Hypervisor: native; BootProtocol: direct; UefiBootDisk: /Users/daniel/UefiBoot.qcow2
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: Launching: /Applications/Docker.app/Contents/MacOS/com.docker.hyperkit -A -m 4G -c 4 -u -s 0:0,hostbridge -s 31,lpc -s 2:0,virtio-vpnkit,uuid=a7da03b1-53f9-4992-89fe-ed0cb9a03aad,path=/Users/daniel/Library/Containers/com.docker.docker/Data/s50,macfile=/Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/Users/daniel/Library/Containers/com.docker.docker/Data/s40,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/Users/daniel/Library/Containers/com.docker.docker/Data/s51,tag=port -s 7,virtio-sock,guest_cid=3,path=/Users/daniel/Library/Containers/com.docker.docker/Data,guest_forwards=2376;1525 -l com1,autopty=/Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/console-ring -f kexec,/Applications/Docker.app/Contents/Resources/moby/vmlinuz64,/Applications/Docker.app/Contents/Resources/moby/initrd.img,earlyprintk=serial console=ttyS0 com.docker.driverDir="/Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" ntp=gateway -F /Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: PPP.negotiate: received ((magic VMN3T)(version 1)(commit ec40b14c72adc0bff3b01fa8886dae7f2eee1541))
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: PPP.negotiate: received (Ethernet a7da03b1-53f9-4992-89fe-ed0cb9a03aad)
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee))
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: PPP.listen: called a second time: doing nothing
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: TCP/IP ready
Jul  7 19:11:26 zbornak Docker[1829] <Notice>: stack connected
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: virtio-net-vpnkit: magic=VMN3T version=1 commit=0123456789012345678901234567890123456789
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: mirage_block_open file:///Users/daniel/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2 buffered = true
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: mirage_block_open returning 0
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: mirage_block_stat
Jul  7 19:11:26 zbornak Docker[1831] <Notice>: vsock init 7:0 = /Users/daniel/Library/Containers/com.docker.docker/Data, guest_cid = 3
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: launching...
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: accessing preferences...
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: bundle path: /Applications/Docker.app/Contents/Library/LoginItems/DockerHelper.app
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: auto start: true
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: launch path: Optional("/Applications/Docker.app")
Jul  7 19:11:27 zbornak Docker[1821] <Warning>: Docker app already launched: Optional("file:///Applications/Docker.app")
Jul  7 19:11:27 zbornak Docker[1821] <Notice>: terminating...
Jul  7 19:11:29 zbornak Docker[1831] <Notice>:
	rdmsr to register 0x34 on vcpu 0
Jul  7 19:11:29 zbornak Docker[1831] <Notice>: Docker is not responding: Get http://./info: EOF: waiting 0.5s
--- last message repeated 5 times ---
Jul  7 19:11:32 zbornak Docker[1828] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /Users
Jul  7 19:11:32 zbornak Docker[1828] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /Volumes
Jul  7 19:11:32 zbornak Docker[1828] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /tmp
Jul  7 19:11:32 zbornak Docker[1828] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /private
Jul  7 19:11:32 zbornak Docker[1828] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /Mac
Jul  7 19:11:32 zbornak Docker[1829] <Notice>: Using protocol TwoThousand msize 8192
Jul  7 19:11:34 zbornak Docker[1831] <Notice>: Docker is responding
Jul  7 19:11:34 zbornak Docker[1830] <Notice>: VM started at 2016-07-07 19:11:34 -0400 EDT
Jul  7 19:11:34 zbornak Docker[1804] <Notice>: dockerState = Starting
Jul  7 19:11:48 zbornak Docker[1829] <Notice>: Creating resource Entry(tcp:0.0.0.0:5432:tcp:172.17.0.2:5432)
Jul  7 19:11:48 zbornak Docker[1829] <Notice>: Write offset=0 data=[tcp:0.0.0.0:5432:tcp:172.17.0.2:5432] to file
Jul  7 19:11:48 zbornak Docker[1829] <Notice>: attempting a best-effort bind of ::1:5432
Jul  7 19:11:48 zbornak Docker[1829] <Notice>: Created instance tcp:0.0.0.0:5432:tcp:172.17.0.2:5432
Jul  7 19:12:50 zbornak Docker[1829] <Error>: failed to establish 9P connection: Caught EOF on underlying FLOW
Jul  7 19:12:50 zbornak Docker[2310] <Notice>: EOF reading packet from Unix domain socket: closing
Jul  7 19:12:50 zbornak Docker[2310] <Critical>: Failed to read hello from client
Jul  7 19:12:55 zbornak Docker[1829] <Error>: TCP 104.239.220.248:80 > 192.168.65.2:47970 proxy failed with flow proxy b: Connection refused
Jul  7 19:12:59 zbornak Docker[1804] <Notice>: BUG in libdispatch client: kevent[EVFILT_MACHPORT] monitored resource vanished before the source cancel handler was invoked
Jul  7 19:13:15 zbornak Docker[1829] <Error>: failed to establish 9P connection: Caught EOF on underlying FLOW
Jul  7 19:13:15 zbornak Docker[2373] <Notice>: EOF reading packet from Unix domain socket: closing
Jul  7 19:13:15 zbornak Docker[2373] <Critical>: Failed to read hello from client

I’ve uploaded a diagnostic report (#6220FE46-23C7-41F5-B76F-A2880E553E68) as well.

dabido · July 8, 2016, 6:50am

I’m experiencing the same problem here. How did you revert to beta 1?

phyllisstein · July 8, 2016, 7:33am

The hard way: backed up anything important, downloaded a new copy of the installer, and nuked the hard drive from orbit.

dafire · July 9, 2016, 8:10am

Have you tried setting ipv6 to link-local in the tcp/ip advanced network settings? I just changed it after I read in an other thread that it helped with some beta issues and it looks like docker is working for me now.
(Make sure to apply the changes in the main network settings window after changing the advanced settings)

phyllisstein · July 9, 2016, 12:38pm

No dice, I’m afraid. I installed Beta 2, disabled IPv6 (networksetup -setv6off Wi-Fi), and reinstalled Docker for Mac beta18, but any attempts to interact with the container hang or eventually time out. I uploaded a couple of new diagnostic reports, in case that’s useful to the maintainers; their IDs are 410A97E0-84A2-44C4-857B-EBC3E581EE1F and 3703E4C5-CD56-4CFE-971D-74CCC640404F.

pixerius · July 9, 2016, 11:18pm

Confirm. This method does NOT seem to work on recent beta versions of macos sierra/docker for mac.

imago · July 11, 2016, 10:30pm

same problem here.

networksetup -setv6off Wi-Fi
networksetup -setv6off 'Ethernet Display'

didn’t work… i tried the public beta and 10.12 Beta (16A239m)

$ docker build -t imago/extractornode . Sending build context to Docker daemon 5.014 MB

saripurigopi · July 12, 2016, 3:20am

Any solution for this apart from reverting to older beta /El Capitan?

sanderp · July 13, 2016, 10:44am

I just noticed this thread and tried killing the docker process using sudo pkill -9 -f docker and starting the app again. Now everything seems to be working.

cdadr · July 13, 2016, 12:01pm

The fixes still don’t work for me. I was able to get it everything working on a clean install of 10.12 Beta 2.

To setup each box, I simply copied the Docker.app to ~/Applications and ran it.

I’ve noticed that between the two computers the processes are running from different locations. On the computer where Docker hangs, the application is running from my home directory:

root              710   0.0  0.1  2443400   5636   ??  Ss    7:55AM   0:00.02 /Library/PrivilegedHelperTools/com.docker.vmnetd
username          713   0.0  0.1 573456792  11096   ??  S     7:55AM   0:00.04 /Applications/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux -watchdog fd:0
username          715   0.0  0.1 573411152   7876   ??  S     7:55AM   0:00.03 /Applications/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux -watchdog fd:0
username          717   0.0  0.2  2478736  16220   ??  Ss    7:55AM   0:00.47 com.docker.db --url=file:///Users/username/Library/Containers/com.docker.docker/Data/s40 --git /Users/username/Library/Containers/com.docker.docker/Data/database
username          718   0.0  0.2  2477476  12712   ??  Ss    7:56AM   0:00.28 com.docker.osxfs --address fd:3 --connect /Users/username/Library/Containers/com.docker.docker/Data/@connect --control fd:4 --volume-control fd:5 --database /Users/username/Library/Containers/com.docker.docker/Data/s40
username          719   0.0  0.1  2515408  11244   ??  Ss    7:56AM   0:00.03 com.docker.slirp --db /Users/username/Library/Containers/com.docker.docker/Data/s40 --socket fd:3 --port-control fd:4 --vsock-path /Users/username/Library/Containers/com.docker.docker/Data/@connect
username          720   0.0  0.1 573413852   9880   ??  Ss    7:56AM   0:00.03 com.docker.backend
username          721   0.0  0.2 573464328  17644   ??  Ss    7:56AM   0:00.15 com.docker.driver.amd64-linux -db /Users/username/Library/Containers/com.docker.docker/Data/s40 -osxfs-volume /Users/username/Library/Containers/com.docker.docker/Data/s30 -slirp /Users/username/Library/Containers/com.docker.docker/Data/s50 -vmnet /var/tmp/com.docker.vmnetd.socket -port /Users/username/Library/Containers/com.docker.docker/Data/s51 -vsock /Users/username/Library/Containers/com.docker.docker/Data -docker /Users/username/Library/Containers/com.docker.docker/Data/s60 -addr fd:3 -debug
username          722   0.0  0.1 573419344   7872   ??  S     7:56AM   0:00.02 /Applications/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux
username          723   0.0  0.1 573423492  11752   ??  S     7:56AM   0:00.03 /Applications/Docker.app/Contents/MacOS/com.docker.driver.amd64-linux -db /Users/username/Library/Containers/com.docker.docker/Data/s40 -osxfs-volume /Users/username/Library/Containers/com.docker.docker/Data/s30 -slirp /Users/username/Library/Containers/com.docker.docker/Data/s50 -vmnet /var/tmp/com.docker.vmnetd.socket -port /Users/username/Library/Containers/com.docker.docker/Data/s51 -vsock /Users/username/Library/Containers/com.docker.docker/Data -docker /Users/username/Library/Containers/com.docker.docker/Data/s60 -addr fd:3 -debug
username          724   7.9  1.4  4741736 116800   ??  S     7:56AM   0:07.42 /Applications/Docker.app/Contents/MacOS/com.docker.hyperkit -A -m 2G -c 2 -u -s 0:0,hostbridge -s 31,lpc -s 2:0,virtio-vpnkit,uuid=e12ebe9c-32f2-49b6-b7b7-193bb9068ea8,path=/Users/username/Library/Containers/com.docker.docker/Data/s50,macfile=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/Users/username/Library/Containers/com.docker.docker/Data/s40,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/Users/username/Library/Containers/com.docker.docker/Data/s51,tag=port -s 7,virtio-sock,guest_cid=3,path=/Users/username/Library/Containers/com.docker.docker/Data,guest_forwards=2376;1525 -l com1,autopty=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/console-ring -f kexec,/Applications/Docker.app/Contents/Resources/moby/vmlinuz64,/Applications/Docker.app/Contents/Resources/moby/initrd.img,earlyprintk=serial console=ttyS0 com.docker.driverDir="/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" ntp=gateway -F /Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid
username          734   0.1  0.1 573405432   8196   ??  S     7:56AM   0:00.02 /Applications/Docker.app/Contents/Resources/bin/com.docker.frontend {"action":"vmstateevent","args":{"vmstate":"running"}}
username          735   0.1  0.1 573405688   7860   ??  S     7:56AM   0:00.02 /Applications/Docker.app/Contents/Resources/bin/com.docker.frontend {"action":"vmstateevent","args":{"vmstate":"running"}}

On the machine where everything is working just fine, the process is running from the Gatekeeper Path Randomization path:

root              672   0.0  0.1  2443396   5704   ??  Ss    7:41AM   0:00.01 /Library/PrivilegedHelperTools/com.docker.vmnetd
username          676   0.0  0.1 573459372  11608   ??  S     7:41AM   0:00.11 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux -watchdog fd:0
username          678   0.0  0.1 573402432   7924   ??  S     7:41AM   0:00.09 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux -watchdog fd:0
username          679   0.0  0.3  2539220  21460   ??  Ss    7:41AM   0:00.99 com.docker.db --url=file:///Users/username/Library/Containers/com.docker.docker/Data/s40 --git /Users/username/Library/Containers/com.docker.docker/Data/database
username          682   0.0  0.1 573415152  12508   ??  Ss    7:41AM   0:00.34 com.docker.backend
username          684   0.0  0.1 573411788   8060   ??  S     7:41AM   0:00.07 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/MacOS/com.docker.osx.hyperkit.linux
username          711   0.0  0.1  2476424  12532   ??  Ss    7:41AM   0:00.16 com.docker.osxfs --address fd:3 --connect /Users/username/Library/Containers/com.docker.docker/Data/@connect --control fd:4 --volume-control fd:5 --database /Users/username/Library/Containers/com.docker.docker/Data/s40
username          712   0.0  0.1  2515404  11616   ??  Ss    7:41AM   0:00.04 com.docker.slirp --db /Users/username/Library/Containers/com.docker.docker/Data/s40 --socket fd:3 --port-control fd:4 --vsock-path /Users/username/Library/Containers/com.docker.docker/Data/@connect
username          713   0.0  0.2 573466128  17692   ??  Ss    7:41AM   0:00.21 com.docker.driver.amd64-linux -db /Users/username/Library/Containers/com.docker.docker/Data/s40 -osxfs-volume /Users/username/Library/Containers/com.docker.docker/Data/s30 -slirp /Users/username/Library/Containers/com.docker.docker/Data/s50 -vmnet /var/tmp/com.docker.vmnetd.socket -port /Users/username/Library/Containers/com.docker.docker/Data/s51 -vsock /Users/username/Library/Containers/com.docker.docker/Data -docker /Users/username/Library/Containers/com.docker.docker/Data/s60 -addr fd:3 -debug
username          714   0.0  0.1 573408768  11936   ??  S     7:41AM   0:00.03 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/MacOS/com.docker.driver.amd64-linux -db /Users/username/Library/Containers/com.docker.docker/Data/s40 -osxfs-volume /Users/username/Library/Containers/com.docker.docker/Data/s30 -slirp /Users/username/Library/Containers/com.docker.docker/Data/s50 -vmnet /var/tmp/com.docker.vmnetd.socket -port /Users/username/Library/Containers/com.docker.docker/Data/s51 -vsock /Users/username/Library/Containers/com.docker.docker/Data -docker /Users/username/Library/Containers/com.docker.docker/Data/s60 -addr fd:3 -debug
username          715   0.1  3.2  4758472 270580   ??  S     7:41AM   0:31.11 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/MacOS/com.docker.hyperkit -A -m 2G -c 2 -u -s 0:0,hostbridge -s 31,lpc -s 2:0,virtio-vpnkit,uuid=32b25e80-7df5-4db8-913a-88edef76df63,path=/Users/username/Library/Containers/com.docker.docker/Data/s50,macfile=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/Users/username/Library/Containers/com.docker.docker/Data/s40,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/Users/username/Library/Containers/com.docker.docker/Data/s51,tag=port -s 7,virtio-sock,guest_cid=3,path=/Users/username/Library/Containers/com.docker.docker/Data,guest_forwards=2376;1525 -l com1,autopty=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/console-ring -f kexec,/var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/Resources/moby/vmlinuz64,/var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/Resources/moby/initrd.img,earlyprintk=serial console=ttyS0 com.docker.driverDir="/Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" ntp=gateway -F /Users/username/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid
username          943   0.0  0.1 573405684   8340   ??  S     7:58AM   0:00.01 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/Resources/bin/com.docker.frontend {"action":"vmstateevent","args":{"vmstate":"running"}}
username          944   0.0  0.1 573403636   7920   ??  S     7:58AM   0:00.01 /var/folders/rv/l4qy163d1vx4zscyqsh1l2t00000gn/T/AppTranslocation/BA8569B9-F98C-4F19-8DEC-BA377A32EA5B/d/Docker.app/Contents/Resources/bin/com.docker.frontend {"action":"vmstateevent","args":{"vmstate":"running"}}
username          951   0.0  0.0  2442024   2084 s001  S+    7:58AM   0:00.01 grep docker

I’ve heard of issues going the other way, where GPR prevents apps from running, but I haven’t heard about it helping apps actually run.

I’ve also tried reverting the Gatekeeper database and reauthorizing the app but to no avail. It looks like GPR does not work on any previously opened apps, so I guess I need to find a way to reset that list.

icrackedsteven · July 14, 2016, 6:20pm

@cdadr I found this post on App Translocation and I’m curious if you can identify what’s different on the working machine that would cause it to be running Docker from the read-only translocation volume. It seems like moving Docker.app from the Docker install image should prevent it from being quarantined, so it’s really strange. What are the xattrs on the app? This is a really interesting find – thanks for digging into this!

cdadr · July 14, 2016, 6:43pm

@icrackedsteven
I actually did some further digging and I now think this is unrelated to GPR. I restored a spare laptop to Beta 1 and had no issues with Docker.app running in the translocation and it immediately broke when I updated to Beta 2.

After working with the com.docker.slirp (docker/vpnkit) team on the uwt errors, I now believe this is related to com.docker.vmnetd dropping connections. After switching slirp to use lwt instead of uwt, the uwt errors go away but docker pulls still hang.

After a command hangs, you can run docker-diagnose and take a look at the logs. In the logs you’ll find that com.docker.vmnetd hits a critical error and stops working:

Jul 14 08:42:43 quark Docker[com.docker.driver.amd64-linux][92500] <Notice>: Docker is responding
Jul 14 08:42:43 quark Docker[com.docker.backend][92499] <Notice>: VM started at 2016-07-14 08:42:43 -0400 EDT
Jul 14 08:43:37 quark Docker[com.docker.vmnetd][92545] <Notice>: EOF reading packet from Unix domain socket: closing
Jul 14 08:43:37 quark Docker[com.docker.vmnetd][92545] <Critical>: Failed to read hello from client

I’ll keep digging and see if there’s anything obvious that can resolve this issue but I think the ball is ultimately in the hands of the Docker for Mac team (unless they want to open up the source for vmnetd and let us poke around )

pfeurean · July 16, 2016, 12:00am

It appears NTP is the culprit. This fixed docker for me.

icrackedsteven · July 16, 2016, 1:29am

Confirmed. This works for me as well. Thanks for sharing this!