Docker beta on mac fails to start

kadrach · June 19, 2016, 10:44am

Expected behavior

Install docker beta.
Docker app says “running”
Actually is running

Actual behavior

$ docker ps
Cannot connect to the Docker daemon. Is the docker daemon running on this host?

Information

System restart has no effect
docker-toolbox has never been installed, and I explicity uninstalled virtualbox.

Docker for Mac: version: mac-v1.12.0-beta16.2 OS X: version 10.11.5 (build: 15F34) logs: /tmp/20160619-202910.tar.gz failure: docker ps failed: Failure("docker ps: timeout after 10.00s") [ERROR] docker-cli /var/run/docker.sock does not exist Permission denied (EACCES) connecting to /var/run/docker.sock: check permissions docker ps failed [OK] app [OK] menubar [OK] virtualization [OK] system [OK] osxfs [OK] db [OK] slirp [OK] moby-console [OK] logs [OK] vmnetd [OK] env [OK] moby [OK] driver.amd64-linux

diagnostics id 6C8BCC59-C7C7-4140-8EFC-DEA044989F22

syslog -k Sender Docker Jun 19 20:24:05 noe8 Docker[38535] <Notice>: Application version: 1.12.0-rc2-beta16 (9493) Jun 19 20:24:05 noe8 Docker[38535] <Notice>: OSX Version: Version 10.11.5 (Build 15F34) Jun 19 20:24:05 noe8 Docker[38535] <Notice>: migrator: start migration process Jun 19 20:24:05 noe8 Docker[38535] <Notice>: migrator: previous install detected Jun 19 20:24:05 noe8 Docker[38535] <Notice>: migrator: previous version is 1.12.0-rc2-beta16 Jun 19 20:24:05 noe8 Docker[38535] <Notice>: migrator: no migration needed, you are good to go! Jun 19 20:24:05 noe8 Docker[38535] <Notice>: migrator: end of migration process Jun 19 20:24:05 noe8 Docker[38535] <Notice>: event tracker initialized Jun 19 20:24:05 noe8 Docker[38535] <Notice>: Bundle path: /Applications/Docker.app Jun 19 20:24:05 noe8 Docker[38544] <Notice>: com.docker.vmnetd starting Jun 19 20:24:05 noe8 Docker[38545] <Notice>: Client reports version 1, commit f615be9fb245904fbdf1aa0cad251d418c869428 Jun 19 20:24:05 noe8 Docker[38545] <Notice>: EOF reading packet from Unix domain socket: closing Jun 19 20:24:05 noe8 Docker[38545] <Error>: Failed to read command from client Jun 19 20:24:05 noe8 Docker[38535] <Notice>: com.docker.docker commit: f615be9fb245904fbdf1aa0cad251d418c869428 wants version 16 Jun 19 20:24:05 noe8 Docker[38535] <Notice>: com.docker.vmnetd commit: f615be9fb245904fbdf1aa0cad251d418c869428 has version 16 Jun 19 20:24:05 noe8 Docker[38535] <Notice>: probe of /var/tmp/com.docker.vmnetd.socket successful: not reinstalling component Jun 19 20:24:05 noe8 Docker[38546] <Notice>: Client reports version 1, commit f615be9fb245904fbdf1aa0cad251d418c869428 Jun 19 20:24:05 noe8 Docker[38546] <Critical>: client with uid 501 requests com.docker.vmnetd install symlinks Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /usr/local/bin/docker is a symlink Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /usr/local/bin/docker-compose is a symlink Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /usr/local/bin/notary is a symlink Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /usr/local/bin/docker-machine is a symlink Jun 19 20:24:05 noe8 Docker[38546] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 19 20:24:05 noe8 Docker[38535] <Notice>: Symlinks are valid. Jun 19 20:24:05 noe8 Docker[38535] <Notice>: com.docker.osx.hyperkit.linux launched with PID 38547 Jun 19 20:24:05 noe8 Docker[38548] <Critical>: dial unix /Users/noe8/Library/Containers/com.docker.docker/Data/s20: connect: connection refused Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Acquired task manager lock Jun 19 20:24:05 noe8 Docker[38535] <Error>: frontend process failed Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Starting com.docker.db, com.docker.osxfs, com.docker.slirp, com.docker.osx.hyperkit.linux, com.docker.driver.amd64-linux Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Start com.docker.db (pid 38551) Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Start com.docker.osxfs (pid 38552) Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Start com.docker.slirp (pid 38553) Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Start com.docker.osx.hyperkit.linux (pid 38554) Jun 19 20:24:05 noe8 Docker[38547] <Notice>: Start com.docker.driver.amd64-linux (pid 38555) Jun 19 20:24:05 noe8 Docker[38554] <Notice>: API server starting Jun 19 20:24:05 noe8 Docker[38554] <Notice>: <U+1F340> socket path is: /Users/noe8/Library/Containers/com.docker.docker/Data/s20 Jun 19 20:24:05 noe8 Docker[38554] <Notice>: API socket removed Jun 19 20:24:05 noe8 Docker[38554] <Notice>: Try 100/100 failed to connect to db: dial unix /Users/noe8/Library/Containers/com.docker.docker/Data/s40: connect: connection refused Jun 19 20:24:05 noe8 Docker[38552] <Notice>: Logging to Apple System Log Jun 19 20:24:05 noe8 Docker[38553] <Notice>: Logging to Apple System Log Jun 19 20:24:05 noe8 Docker[38553] <Notice>: Setting handler to ignore all SIGPIPE signals Jun 19 20:24:05 noe8 Docker[38553] <Notice>: Setting soft fd limit to 10240 Jun 19 20:24:05 noe8 Docker[38553] <Notice>: starting port_forwarding port_control_path:fd:4 vsock_path:/Users/noe8/Library/Containers/com.docker.docker/Data/@connect Jun 19 20:24:05 noe8 Docker[38553] <Notice>: attempting to reconnect to database on unix /Users/noe8/Library/Containers/com.docker.docker/Data/s40 Jun 19 20:24:05 noe8 Docker[38553] <Notice>: reconnected transport layer Jun 19 20:24:05 noe8 Docker[38553] <Notice>: allowing binds to any IP addresses Jun 19 20:24:05 noe8 Docker[38553] <Notice>: Creating slirp server pcap_settings:disabled peer_ip:192.168.65.2 local_ip:192.168.65.1 Jun 19 20:24:05 noe8 Docker[38555] <Notice>: Acquired hypervisor lock Jun 19 20:24:05 noe8 Docker[38554] <Notice>: VM shutdown at 2016-06-19 20:19:55 +1000 AEST Jun 19 20:24:05 noe8 Docker[38555] <Notice>: Docker is not responding: waiting 0.5s Jun 19 20:24:05 noe8 Docker[38555] <Notice>: hypervisor: native Jun 19 20:24:05 noe8 Docker[38555] <Notice>: filesystem: osxfs Jun 19 20:24:05 noe8 Docker[38555] <Notice>: network: hybrid Jun 19 20:24:05 noe8 Docker[38552] <Notice>: Using protocol TwoThousand msize 16384 Jun 19 20:24:05 noe8 Docker[38555] <Notice>: Hypervisor: native; BootProtocol: direct; UefiBootDisk: /Users/noe8/UefiBoot.qcow2 Jun 19 20:24:05 noe8 Docker[38555] <Notice>: Launching: /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=e335d4bd-a1aa-47ff-a23c-054fa1ee6a74,path=/Users/noe8/Library/Containers/com.docker.docker/Data/s50,macfile=/Users/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/Users/noe8/Library/Containers/com.docker.docker/Data/s40,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/Users/noe8/Library/Containers/com.docker.docker/Data/s51,tag=port -s 7,virtio-sock,guest_cid=3,path=/Users/noe8/Library/Containers/com.docker.docker/Data,guest_forwards=2376;1525 -l com1,autopty=/Users/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/noe8/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/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" ntp=gateway -F /Users/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid Jun 19 20:24:05 noe8 Docker[38553] <Notice>: PPP.negotiate: received ((magic VMN3T)(version 1)(commit f615be9fb245904fbdf1aa0cad251d418c869428)) Jun 19 20:24:05 noe8 Docker[38553] <Notice>: PPP.negotiate: received (Ethernet e335d4bd-a1aa-47ff-a23c-054fa1ee6a74) Jun 19 20:24:05 noe8 Docker[38555] <Notice>: virtio-net-vpnkit: magic=VMN3T version=1 commit=0123456789012345678901234567890123456789^B Jun 19 20:24:05 noe8 Docker[38553] <Notice>: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee)) Jun 19 20:24:05 noe8 Docker[38553] <Notice>: PPP.listen: called a second time: doing nothing Jun 19 20:24:05 noe8 Docker[38553] <Notice>: TCP/IP ready Jun 19 20:24:05 noe8 Docker[38555] <Notice>: mirage_block_open file:///Users/noe8/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2 buffered = true Jun 19 20:24:05 noe8 Docker[38555] <Notice>: mirage_block_open returning 0 Jun 19 20:24:05 noe8 Docker[38555] <Notice>: mirage_block_stat Jun 19 20:24:05 noe8 Docker[38555] <Notice>: vsock init 7:0 = /Users/noe8/Library/Containers/com.docker.docker/Data, guest_cid = 3 Jun 19 20:24:08 noe8 Docker[38555] <Notice>: rdmsr to register 0x34 on vcpu 1 Jun 19 20:24:08 noe8 Docker[38555] <Notice>: Docker is not responding: waiting 0.5s --- last message repeated 5 times --- Jun 19 20:24:11 noe8 Docker[38552] <Notice>: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /Mac Jun 19 20:24:11 noe8 Docker[38552] <Notice>: osxfs tracing: OFF Jun 19 20:24:11 noe8 Docker[38553] <Notice>: Using protocol TwoThousand msize 8192 Jun 19 20:24:13 noe8 Docker[38555] <Notice>: Docker is responding Jun 19 20:24:13 noe8 Docker[38554] <Notice>: VM started at 2016-06-19 20:24:13 +1000 AEST Jun 19 20:24:13 noe8 Docker[38535] <Notice>: dockerState = Starting Jun 19 20:28:20 noe8 Docker[38757] <Notice>: EOF reading packet from Unix domain socket: closing Jun 19 20:28:20 noe8 Docker[38757] <Critical>: Failed to read hello from client Jun 19 20:28:20 noe8 Docker[38553] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed Jun 19 20:29:20 noe8 Docker[38864] <Notice>: EOF reading packet from Unix domain socket: closing Jun 19 20:29:20 noe8 Docker[38553] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed Jun 19 20:29:20 noe8 Docker[38864] <Critical>: Failed to read hello from client Jun 19 20:29:45 noe8 Docker[38926] <Notice>: EOF reading packet from Unix domain socket: closing Jun 19 20:29:45 noe8 Docker[38926] <Critical>: Failed to read hello from client Jun 19 20:29:45 noe8 Docker[38553] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed

The docker.sock seems to point into the void:
lrwxr-xr-x 1 root daemon 55B 19 Jun 20:24 /var/run/docker.sock -> /var/root/Library/Containers/com.docker.docker/Data/s60, where the latter does not exist.
Replacing this symlink with ~/Library/Containers/com.docker.docker/Data/s60 and restarting docker does not seem to help.

Any help would be appreciated

idoo · June 20, 2016, 10:00am

I have same issue on my Mac with last docker
Version 1.12.0-rc2-beta16 (build: 9493)
f615be9fb245904fbdf1aa0cad251d418c869428

joerter · June 20, 2016, 1:30pm

I am also seeing this issue. After upgrading to beta16, Docker beta fails to start on my Mac. It hangs at “Docker is starting”.

775D667E-A278-4605-887C-459673426121

johnthughes · June 20, 2016, 5:35pm

Had the same issue. Turned out I had docker env variables set in my shell.

‘env | grep -i docker’

Will show any you have set.

Mine were set in my .bash_profile. I commented them out and started a new shell, checked they were not showing up any more and docker for mac worked perfect.

Hope that helps

kadrach · June 21, 2016, 3:39am

Unfortunately, as stated above, I’ve never had docker installed on this box. No docker-related environment variables are set.

johnthughes · June 21, 2016, 3:43am

Yeah, very I only posted because I found my way to your posting before I figured out my issue and i thought some might find it helpful

austinylin · June 21, 2016, 4:06am

Having the same issue as well. Looks like a bad symlink.

I am able to get docker working if I run this command each time I launch the docker app:

sudo ln -sf ~/Library/Containers/com.docker.docker/Data/s60 /var/run/docker.sock

Closing and re-openin docker reverts the symlink back to pointing at /var/root/Library/Containers/com.docker.docker/Data/s60

Diagnostic ID: B6B1AE67-A97B-458D-9D7B-37441DEF99B8

mcobrien · June 21, 2016, 7:30am

Same issue here, although the symlink is present and I don’t have any docker environment variables set. Diagnostic ID: 50706572-922D-46C8-908A-13E5CA6C3E62

rhulse · June 21, 2016, 9:02am

Clean install, never had Docker or virtual box on this machine, no env variable.

Appears to be a problem with the sylmlink

$ ls -l /var/run/docker.sock
lrwxr-xr-x 1 root daemon 55 21 Jun 20:37 /var/run/docker.sock -> /var/root/Library/Containers/com.docker.docker/Data/s60

779FBEE2-0BEB-4815-8A26-AD8C8279FFA6

nolith · June 21, 2016, 3:56pm

Same problem here.

Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: Application version: 1.12.0-rc2-beta16 (9493) Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: OSX Version: Version 10.11.5 (Build 15F34) Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: migrator: start migration process Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: migrator: previous install detected Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: migrator: previous version is 1.12.0-rc2-beta16 Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: migrator: no migration needed, you are good to go! Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: migrator: end of migration process Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: event tracker initialized Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: Bundle path: /Applications/Docker.app Jun 21 10:02:51 mehetueret Docker[72422] <Notice>: Client reports version 1, commit f615be9fb245904fbdf1aa0cad251d418c869428 Jun 21 10:02:51 mehetueret Docker[72422] <Notice>: EOF reading packet from Unix domain socket: closing Jun 21 10:02:51 mehetueret Docker[72422] <Error>: Failed to read command from client Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: com.docker.docker commit: f615be9fb245904fbdf1aa0cad251d418c869428 wants version 16 Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: com.docker.vmnetd commit: 603884f1679ff0528724854e9d27fc8e67fdfd8f has version 16 Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: probe of /var/tmp/com.docker.vmnetd.socket successful: not reinstalling component Jun 21 10:02:51 mehetueret Docker[72423] <Notice>: Client reports version 1, commit f615be9fb245904fbdf1aa0cad251d418c869428 Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: client with uid 501 requests com.docker.vmnetd install symlinks Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /usr/local/bin/docker is a symlink Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /usr/local/bin/docker-compose is a symlink Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /usr/local/bin/notary is a symlink Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /usr/local/bin/docker-machine is a symlink Jun 21 10:02:51 mehetueret Docker[72423] <Critical>: File /var/run/docker.sock is a symlink or a socket Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: Symlinks are valid. Jun 21 10:02:51 mehetueret Docker[72413] <Notice>: com.docker.osx.hyperkit.linux launched with PID 72425 Jun 21 10:07:15 mehetueret Docker[72685] <Notice>: EOF reading packet from Unix domain socket: closing Jun 21 10:07:15 mehetueret Docker[72685] <Critical>: Failed to read hello from client Jun 21 10:07:15 mehetueret Docker[58412] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed Jun 21 10:15:32 mehetueret Docker[72993] <Notice>: EOF reading packet from Unix domain socket: closing Jun 21 10:15:32 mehetueret Docker[72993] <Critical>: Failed to read hello from client Jun 21 10:15:32 mehetueret Docker[58412] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed Jun 21 10:15:40 mehetueret Docker[73042] <Notice>: EOF reading packet from Unix domain socket: closing Jun 21 10:15:40 mehetueret Docker[73042] <Critical>: Failed to read hello from client Jun 21 10:15:40 mehetueret Docker[58412] <Error>: server loop caught (Failure "Caught EOF on underlying FLOW"): no further requests will be processed

richmarisa · June 21, 2016, 4:20pm

Also seeing this. Docker starts in my Mac admin account, but hangs at Starting… in my user account.

dionjwa · June 22, 2016, 10:31pm

Also seeing this OSX 10.11.5 (15F34), Docker version 1.12.0-rc2, build 906eacd, experimental.
I restarted and now I don’t know how to get docker working again. rhulse’s solution of recreating the symlink did not work for me.

rhulse · June 22, 2016, 11:20pm

Wasn’t really a solution. I was just documenting what link (and permissions) the installed put in place. I suspect the permissions are wrong somewhere, which is why it was not working in my case, and did not want to make any changes to things so I can test any upstream fix when it arrives.

jeanepaul · June 23, 2016, 2:10am

Greetings,

be sure to unset all DOCKER_* if you have previously used Docker Toolbox

nolith · June 23, 2016, 7:36am

reset to factory default solved my problem (but I lost all my containers)

mcobrien · June 23, 2016, 7:39am

thanks @nolith! That worked for me.

alexellis2 · June 23, 2016, 5:57pm

This also worked for me but I have now lost my entire library. :-/

enspencer · June 24, 2016, 5:39pm

Same, had to reset to factory default.

sweetaz · June 26, 2016, 10:55am

Fantastic! Factory reset fixes it for me too.

nolith · June 29, 2016, 1:13pm

today I noticed, after stopping docker, that it failed top start againg and I had to factory reset it another time.

Did you had this problem also?