Docker Community Forums

Share and learn in the Docker community.

[solved] Whale is tossing containers on Mac, keeps saying "Docker starting up"


(Teleki) #1

Expected behavior

Steady icon.

Actual behavior

Keeps tossing containers

Information

  • the output of:
    • pinata diagnose -u on OSX
    • DockerDebugInfo.ps1 using Powershell on Windows
  • a reproducible case if this is a bug, Dockerfiles FTW
  • page URL if this is a docs issue or the name of a man page
  • host distribution and version ( OSX 10.10.x, OSX 10.11.x, Windows, etc )

OS X: version 10.11.4 (build: 15E65)
Docker.app: version v1.11.1-beta10
Running diagnostic tests:
Error docker ps: timeout after 10.00s
Thread 6 killed on uncaught exception Misc.Failure
Error docker ps: timeout after 10.00s
[ERROR] docker-cli docker ps failed: Misc.Failure
[OK] docker-cli
[OK] Moby booted
[OK] driver.amd64-linux
[OK] vmnetd
[OK] osxfs
[OK] db
[OK] slirp
[OK] menubar
[OK] environment
[OK] Docker
[OK] VT-x
Docker logs are being collected into /tmp/20160505-213841.tar.gz
Most specific failure is: docker ps failed: Misc.Failure
Your unique id is: 45F5473E-AD45-4258-908F-EC2CF50F4CC7
Please quote this in all correspondence.

Steps to reproduce the behavior

  1. Install Docker Beta
  2. Restart Mac
  3. Observe whale tossing containers

(Kostas) #2

I just upgraded (from beta7 I think) and the same is happening to me.

Output from pinata:

OS X: version 10.11.4 (build: 15E65)
Docker.app: version v1.11.1-beta10
Running diagnostic tests:
Error docker ps: timeout after 10.00s
[ERROR]   docker-cli       docker ps failed: Misc.Failure
[OK]      docker-cli
[ERROR]   Moby booted      /Users/kostas/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/console-ring does not exist
[OK]      Moby booted
[OK]      driver.amd64-linux
[OK]      vmnetd
[OK]      osxfs
[OK]      db
[OK]      slirp
[OK]      menubar
[OK]      environment
[OK]      Docker
[OK]      VT-x
Error docker ps: got timeout (docker ps: exit 1)
Thread 6 killed on uncaught exception Misc.Failure
Error exec: echo "00000003.0000f3a6" | nc -U /var/tmp/com.docker.vsock/connect > /tmp/20160506-082426/diagnostics.tar: exit 1
Docker logs are being collected into /tmp/20160506-082426.tar.gz
Most specific failure is: /Users/kostas/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/console-ring does not exist
Your unique id is: 36AEF6D4-A415-46F0-A70B-C1952F7D6A1A

Edit: after quitting and booting docker again, it seems to work fine.


(Wdiechmann) #3

Have the exact same experience - and reboot’ing does not help (and updated to latest Docker just now)

pinata diagnose -u

OS X: version 10.11.4 (build: 15E65)
Docker.app: version v1.11.1-beta11
Running diagnostic tests:
Error docker ps: timeout after 10.00s
Thread 6 killed on uncaught exception Misc.Failure
Error docker ps: timeout after 10.00s
[ERROR]   docker-cli       docker ps failed: Misc.Failure
[OK]      docker-cli
[OK]      Moby booted
[OK]      driver.amd64-linux
[OK]      vmnetd
[OK]      osxfs
[OK]      db
[OK]      slirp
[OK]      menubar
[OK]      environment
[OK]      Docker
[OK]      VT-x
Docker logs are being collected into /tmp/20160512-083135.tar.gz
Most specific failure is: docker ps failed: Misc.Failure
Your unique id is: 8106CA56-84CB-487F-B543-1F973B2216A0

syslog -k Sender Docker

May 12 08:22:12 Store-Bukkebruse Docker[1193] <Notice>: Terminating com.docker.osx.hyperkit.linux and waiting until exit
May 12 08:22:12 Store-Bukkebruse Docker[1245] <Notice>: Received SIGTERM, shutting down
May 12 08:22:12 Store-Bukkebruse Docker[1245] <Notice>: Stop 1 children with order 1: com.docker.driver.amd64-linux (pid 1251)
May 12 08:22:12 Store-Bukkebruse Docker[1245] <Notice>: Signal terminated to com.docker.driver.amd64-linux (pid 1251)
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: sending SIGTERM to com.docker.hyperkit pid 1254
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: virtio-net-ipc: initialising uuid=34d8fa4c-ef1b-4ad6-9802-a201f73421b9,path=/var/tmp/com.docker.slirp.socket,macfile=/Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: Interface will have uuid 34d8fa4c-ef1b-4ad6-9802-a201f73421b9
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: Connection established with MAC=c0:ff:ee:c0:ff:ee and MTU 1500
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: virtio-9p: initialising path=/var/tmp/com.docker.db.socket,tag=db
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: virtio-9p: initialising path=/var/tmp/com.docker.port.socket,tag=port
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: linkname /Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: COM1 connected to /dev/ttys000
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: COM1 linked to /Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty
May 12 08:22:12 Store-Bukkebruse Docker[1251] <Notice>: Docker is not responding: waiting 0.5s
May 12 08:22:12 Store-Bukkebruse Docker[1248] <Error>: Fatal unexpected exception: Transfuse.Socket_closed
May 12 08:22:12 Store-Bukkebruse Docker[1249] <Notice>: PPP.listen: closing connection
May 12 08:22:12 Store-Bukkebruse Docker[1245] <Notice>: Reap com.docker.osxfs (pid 1248): exit status 1
May 12 08:22:12 Store-Bukkebruse Docker[1250] <Notice>: VM has shutdown at 2016-05-12 08:22:12 +0200 CEST
May 12 08:22:12 Store-Bukkebruse Docker[1245] <Notice>: Reap com.docker.driver.amd64-linux (pid 1251): exit status 0
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Stop 2 children with order 2: com.docker.osxfs (pid 1248), com.docker.slirp (pid 1249)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Signal terminated to com.docker.slirp (pid 1249)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Reap com.docker.slirp (pid 1249): signal: terminated
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Stop 1 children with order 3: com.docker.backend (pid 1250)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Signal terminated to com.docker.backend (pid 1250)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Reap com.docker.osx.hyperkit.linux (pid 1250): signal: terminated
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Stop 1 children with order 4: com.docker.db (pid 1247)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Signal terminated to com.docker.db (pid 1247)
May 12 08:22:13 Store-Bukkebruse Docker[1245] <Notice>: Reap com.docker.db (pid 1247): exit status 1
May 12 08:22:13 Store-Bukkebruse Docker[1193] <Notice>: Notified of termination of com.docker.osx.hyperkit.linux
May 12 08:22:13 Store-Bukkebruse Docker[1193] <Notice>: com.docker.osx.hyperkit.linux terminated with exit code 0
--- last message repeated 1 time ---
May 12 08:22:13 Store-Bukkebruse Docker[1193] <Notice>: applicationWillTerminate
May 12 08:22:26 Store-Bukkebruse Docker[2452] <Notice>: Application version: 1.11.1-beta11 (6974)
May 12 08:22:26 Store-Bukkebruse Docker[2452] <Notice>: OSX Version: Version 10.11.4 (Build 15E65)
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: migrator: start migration process
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: migrator: previous install detected
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: migrator: previous version is 1.11.1-beta11
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: migrator: no migration needed, you are good to go!
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: migrator: end of migration process
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: event tracker initialized
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: Limited access: checking...
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: Bundle path: /Applications/Docker.app
May 12 08:22:27 Store-Bukkebruse Docker[2464] <Notice>: Client reports version 1, commit 37559e5f6acd56a4810963acc7001e88f2d88017
May 12 08:22:27 Store-Bukkebruse Docker[2464] <Notice>: EOF reading packet from Unix domain socket: closing
May 12 08:22:27 Store-Bukkebruse Docker[2464] <Error>: Failed to read command from client
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: com.docker.docker commit: 37559e5f6acd56a4810963acc7001e88f2d88017 wants version 14
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: com.docker.vmnetd commit: 37559e5f6acd56a4810963acc7001e88f2d88017 has version 14
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: probe of /var/tmp/com.docker.vmnetd.socket successful: not reinstalling component
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Notice>: Client reports version 1, commit 37559e5f6acd56a4810963acc7001e88f2d88017
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: client with uid 501 requests com.docker.vmnetd install symlinks
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /usr/local/bin/docker is a symlink
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /var/run/docker.sock is a symlink or a socket
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /usr/local/bin/docker-compose is a symlink
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /var/run/docker.sock is a symlink or a socket
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /usr/local/bin/notary is a symlink
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /var/run/docker.sock is a symlink or a socket
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /usr/local/bin/pinata is a symlink
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /var/run/docker.sock is a symlink or a socket
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /usr/local/bin/docker-machine is a symlink
May 12 08:22:27 Store-Bukkebruse Docker[2465] <Critical>: File /var/run/docker.sock is a symlink or a socket
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: Symlinks are valid.
May 12 08:22:27 Store-Bukkebruse Docker[2452] <Notice>: com.docker.osx.hyperkit.linux launched with PID 2467
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Acquired task manager lock
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Starting com.docker.db, com.docker.osxfs, com.docker.slirp, com.docker.osx.hyperkit.linux, com.docker.driver.amd64-linux
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Start com.docker.db (pid 2469)
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Start com.docker.osxfs (pid 2470)
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Start com.docker.slirp (pid 2471)
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Start com.docker.osx.hyperkit.linux (pid 2472)
May 12 08:22:27 Store-Bukkebruse Docker[2467] <Notice>: Start com.docker.driver.amd64-linux (pid 2473)
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: Logging to Apple System Log
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: Setting handler to ignore all SIGPIPE signals
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: starting port_forwarding port_control_path:fd:4 vsock_path:/var/tmp/com.docker.vsock/connect
May 12 08:22:27 Store-Bukkebruse Docker[2470] <Notice>: Logging to Apple System Log
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: attempting to reconnect to database on unix /var/tmp/com.docker.db.socket
May 12 08:22:27 Store-Bukkebruse Docker[2472] <Notice>: API server starting
May 12 08:22:27 Store-Bukkebruse Docker[2472] <Notice>: 🍀 socket path is: /Users/walther/Library/Containers/com.docker.docker/Data/s20
May 12 08:22:27 Store-Bukkebruse Docker[2472] <Notice>: > socket removed.
May 12 08:22:27 Store-Bukkebruse Docker[2472] <Notice>: VM has shutdown at 2016-05-12 08:22:12 +0200 CEST
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: Acquired hypervisor lock
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: Docker is not responding: waiting 0.5s
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: hypervisor: native
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: filesystem: osxfs
May 12 08:22:27 Store-Bukkebruse Docker[2470] <Notice>: Using protocol TwoThousand msize 16384
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: Hypervisor: native; BootProtocol: direct; UefiBootDisk: /Users/walther/UefiBoot.qcow2
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: Launching: /Applications/Docker.app/Contents/MacOS/com.docker.hyperkit -A -m 2G -c 4 -u -s 0:0,hostbridge -s 31,lpc -s 2:0,virtio-ipc,uuid=34d8fa4c-ef1b-4ad6-9802-a201f73421b9,path=/var/tmp/com.docker.slirp.socket,macfile=/Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/var/tmp/com.docker.db.socket,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/var/tmp/com.docker.port.socket,tag=port -s 7,virtio-sock,guest_cid=3,path=/var/tmp/com.docker.vsock,guest_forwards=2376;1525 -l com1,autopty=/Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/walther/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/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" -F /Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid
May 12 08:22:27 Store-Bukkebruse Docker[2476] <Notice>: Starting xhyve
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: May 12 08:22:27  Docker[2476] <Notice>: Starting xhyve
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: reconnected transport layer
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: allowing binds to any IP addresses
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: Starting slirp server pcap_settings:disabled peer_ip:192.168.65.2 local_ip:192.168.65.1
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: PPP.negotiate: received ((magic VMN3T)(version 14)(commit 37559e5f6acd56a4810963acc7001e88f2d88017))
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: PPP.negotiate: received (Ethernet 34d8fa4c-ef1b-4ad6-9802-a201f73421b9)
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee))
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: PPP.listen: called a second time: doing nothing
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: mirage_block_open file:///Users/walther/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2 buffered = true
May 12 08:22:27 Store-Bukkebruse Docker[2471] <Notice>: TCP/IP ready
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: mirage_block_open returning 0
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: mirage_block_stat
May 12 08:22:27 Store-Bukkebruse Docker[2473] <Notice>: vsock init 7:0 = /var/tmp/com.docker.vsock, guest_cid = 3
May 12 08:22:29 Store-Bukkebruse Docker[2473] <Notice>: 
	rdmsr to register 0x34 on vcpu 3
May 12 08:22:29 Store-Bukkebruse Docker[2473] <Notice>: Docker is not responding: waiting 0.5s
--- last message repeated 4 times ---
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: mount 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: /bin/fusermount 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: -o 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: allow_other,max_read=1048576,subtype=osxfs 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: /Mac 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Error>: transfused: 
May 12 08:22:31 Store-Bukkebruse Docker[2470] <Notice>: osxfs tracing: OFF 
May 12 08:22:31 Store-Bukkebruse Docker[2471] <Notice>: Using protocol TwoThousand msize 8192
May 12 08:22:33 Store-Bukkebruse Docker[2473] <Notice>: Docker is not responding: waiting 0.5s
--- last message repeated 2 times ---

(Teleki) #4

Same here. Updated to Version 1.11.1-beta11 (build: 6974), but the whale is still tossing the containers. It reports “Docker is Starting” but it never actually starts up.


(Andrewhamon) #5

I seem to have the same or similar problem. Invoking docker ps results in Error response from daemon: Bad response from Docker engine

Information

This behavior seems to have happened after a system crash. Subsequent reboots have not resolved it.

> pinata diagnose -u
OS X: version 10.11.4 (build: 15E65)
Docker.app: version v1.11.1-beta12
Running diagnostic tests:
[OK]      Moby booted
[OK]      driver.amd64-linux
[OK]      vmnetd
[OK]      osxfs
[OK]      db
[OK]      slirp
[OK]      menubar
[OK]      environment
[OK]      Docker
[OK]      VT-x
Docker logs are being collected into /tmp/20160518-092837.tar.gz
Most specific failure is: No error was detected
Your unique id is: FCD8700A-0612-434C-9314-86914F533670
Please quote this in all correspondence.

(Teleki) #6

The latest beta v1.11.1-beta12 solved my issue (or maybe it was that I also reset the installation). Either way, now I am able to run the app on the Mac.


(Iamthechad) #7

I’m still seeing this issue on v1.11.1-beta13.

I get similar errors in the logs:

May 25 14:21:43 Chads-iMac Docker[11388] <Notice>: Docker is not responding: waiting 0.5s
--- last message repeated 3 times ---
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: mount 
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: /bin/fusermount 
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: -o 
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: allow_other,max_read=1048576,subtype=osxfs 
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: /Mac 
May 25 14:21:45 Chads-iMac Docker[11385] <Error>: transfused: 
May 25 14:21:45 Chads-iMac Docker[11385] <Notice>: osxfs tracing: OFF 
May 25 14:21:45 Chads-iMac Docker[11386] <Notice>: Using protocol TwoThousand msize 8192
May 25 14:21:46 Chads-iMac Docker[11388] <Notice>: Docker is not responding: waiting 0.5s

Tried restarting Docker and rebooting computer with no success.


(Seanlynch) #8

Is there a reason this is marked as solved? I don’t see a proposed solution above. Can that be removed?


(Lukas) #9

had the same problem, for me factory default solved it

Hope that helps


(Teleki) #10

The reason I marked it solved is because I reported the issue, and now this issue is no longer a problem for me.


(Seanlynch) #11

Ah I see. For anyone else seeing this, it was solved for me by:

  1. Opening the Docker Menu Bar app menu and clicking Settings…
  2. Hitting Uninstall/Reset icon
  3. Pressing the Reset to Factory Defaults button and hitting Reset Docker on the confirmation dialog.

For those that run into this issue and come here for a solution (or to report this solution doesn’t work for them), can you please mention whether you had a previous version of Docker for Mac or any of the other docker tools installed (and if so, which ones)?


(Wdiechmann) #12

PERFECT!

That solved my issues :9

thx for sharing!


[solved] Docker on/for mac starting (but getting nowhere)
[solved] Starting Kitematic Beta after download
[solved] Docker starting