"virtio-9p: unexpected EOF reading -- did the 9P server crash" error when running I/O intensive application on Docker for Mac Bets

nouvak · May 9, 2016, 12:58pm

Expected behavior

Docker should not crash for I/O intensive applications.

Actual behavior

If I start our application, it runs fine for about a minute. After that time, all the docker containers crash, “docker ps” command shows no containers running. This happens every single time for that use case.

I executed the “pinata diagnose -u” command and the first error I see is, as follows:

May 9 14:25:45 haris Docker[com.docker.driver.amd64-linux][85933] : virtio-9p: unexpected EOF reading – did the 9P server crash?

Information

OS X: version 10.11.4 (build: 15E65)
Docker.app: version v1.11.1-beta10
Running diagnostic tests:
[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/20160509-142646.tar.gz
Most specific failure is: No error was detected
Your unique id is: 5C3CA2EE-3D18-42DD-B9F2-199F1224BAF9
Please quote this in all correspondence.

The content of the “docker-system.log” file (please let me know if you need the whole update of the “pinata diagnose” command):

May 9 14:25:45 haris Docker[com.docker.driver.amd64-linux][85933] : virtio-9p: unexpected EOF reading – did the 9P server crash?
May 9 14:25:45 haris Docker[com.docker.osx.xhyve.linux][51287] : Reap com.docker.slirp (pid 85932): exit status 1
May 9 14:25:45 haris Docker[com.docker.osxfs][85931] : Fatal unexpected exception: Transfuse.Socket_closed
May 9 14:25:45 haris Docker[com.docker.osx.xhyve.linux][51287] : Reap com.docker.osxfs (pid 85931): exit status 1
May 9 14:25:45 haris Docker[com.docker.backend][84325] : VM has shutdown at 2016-05-09 14:25:45 +0200 CEST
May 9 14:25:45 haris Docker[com.docker.osx.xhyve.linux][51287] : Reap com.docker.driver.amd64-linux (pid 85933): exit status 0
May 9 14:25:45 haris Docker[com.docker.app][51272] : dockerState = starting
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Stop 1 children with order 1: com.docker.driver.amd64-linux (pid 85933)
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Stop 2 children with order 2: com.docker.osxfs (pid 85931), com.docker.slirp (pid 85932)
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Starting com.docker.osxfs, com.docker.slirp, com.docker.driver.amd64-linux
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Start com.docker.osxfs (pid 87222)
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Start com.docker.slirp (pid 87223)
May 9 14:25:46 haris Docker[com.docker.osx.xhyve.linux][51287] : Start com.docker.driver.amd64-linux (pid 87224)

Steps to reproduce the behavior

The same behaviour occurs every time I execute our web application in Docker environment.
The testing environment consists of multiple Docker containers with Postgres, Redis and Django framework.

The use case that causes the crash of the containers involves the sending of multiple messages to the Celery distributed task queue.

Unfortunately, I cannot share the web application, however I would be able to include some diagnostics if you guys need some additional info.

If you need some additional info, please don’t hesitate to contact me.

sassrobi · May 9, 2016, 1:55pm

I’ve got same error message while running official postgresql database with 5 databases, about 10 connections at the same time. It takes a couple of minutes to got this error, but it is reproducible.
Nothing special, only the port mapped to docker.local:5432 (rather than using dynamic mapping)

nouvak · May 9, 2016, 2:06pm

I can confirm that I have very similar environment as @sassrobi.
I am also running a couple of postgresql instances in docker containers, each of them having port exported to the host (port numbers 5432, 5433, 5434).

davetucker · May 9, 2016, 2:38pm

I’ve opened an issue for this on our bug tracker (#3035) and we’ll look in to it soon!
Thanks for the bug reports and for trying Docker for Mac!

nouvak · May 9, 2016, 2:49pm

Splendid!
If you need any additional info, I can re-run our application easily.
As mentioned, the crash of docker containers occurs in less than a minute in our case.

chrishiestand · May 10, 2016, 8:08pm

I’ve added a reproducible test case: https://github.com/chrishiestand/docker-mysql-import-bug

chrishiestand · May 24, 2016, 8:25pm

This no longer reproduces for me on 1.11.1-beta12 build 7528

sassrobi · May 24, 2016, 9:12pm

I can confirm that. It is stable for me since I upgraded to the mentioned version.

bryanl · June 5, 2016, 1:23pm

I’m still seeing this happen with Version 1.11.1-beta13.1 (build: 8193). I’m running a single postgres container, and the workload is not very high as it’s a very small database.

justincormack · June 5, 2016, 3:16pm

We are not using virtio-p9 any more, so I think it is a different issue - can you open a new issue with more details, in particular and messages in the log. Will do some testing with postgres.

bryanl · June 5, 2016, 3:20pm

Well now it get’s more interesting

This is the error message that led me to this thread. Do I possibly have a busted install?

As an aside, I’m running pg with a volume mount.

justincormack · June 5, 2016, 3:25pm

Ah we are still using virtio-9p for port forwards, it could well be related, but not quite the same… Will look into it.

justincormack · June 5, 2016, 3:27pm

Any way to replicate relatively easily would be appreciated, eg how are you running it, roughly how many connections, also state of netstat -a on the mac just after it crashes might help.

justincormack · June 5, 2016, 3:27pm

Internal issue #3660

johanneswuerbach · June 8, 2016, 10:33pm

Same here, when launching a “complicated” compose stack the VM reboots and I see “Fatal unexpected exception: Transfuse.Socket_closed” in OS X syslog before the VM is killed.

Sadly I can’t really share a test case, but could provide further logs as required.

justincormack · June 9, 2016, 9:53am

We think it may be fixed in the next beta (should be out later today), but we are not entirely sure, we cannot replicate with the test we have any more, but it may not be quite the same issue we are testing for. If you could re-test after the update that would be very helpful.

johanneswuerbach · June 11, 2016, 7:09am

Looks like the new beta solved my issue, thanks .

justincormack · June 11, 2016, 9:16am

Great! Glad it is solved!

dancannon · June 30, 2016, 2:47pm

Hi, unfortunately I am still getting this issue with the latest version (1.12.0-rc2-beta17). I have attached the logs below, what another information would help debug this?

Diagnostic ID: EA7FCAA2-C4D9-428A-9703-C7725F409528

Docker for Mac: version: mac-v1.12.0-beta17
OS X: version 10.11.5 (build: 15F34)
logs: /tmp/20160630-154611.tar.gz
[OK]     docker-cli
[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

Jun 30 15:38:20 Daniels-MacBook-Pro iTerm2[8692]: Time to encode state for window <PseudoTerminal: 0x7fe8fbb9a660 tabs=3 window=<PTYWindow: 0x7fe8fe23e5c0 frame=NSRect: {{3, 4}, {1434, 873}} title=3. tail -f /var/log/system.log | grep --color=auto  Docker (grep) alpha=1.000000 isMain=0 isKey=0 isVisible=1 delegate=0x7fe8fbb9a660>>: 0.01906299591064453
Jun 30 15:38:50 Daniels-MacBook-Pro iTerm2[8692]: Time to encode state for window <PseudoTerminal: 0x7fe8fbb9a660 tabs=3 window=<PTYWindow: 0x7fe8fe23e5c0 frame=NSRect: {{3, 4}, {1434, 873}} title=3. tail -f /var/log/system.log | grep --color=auto  Docker (grep) alpha=1.000000 isMain=0 isKey=0 isVisible=1 delegate=0x7fe8fbb9a660>>: 0.01038897037506104
Jun 30 15:39:05 Daniels-MacBook-Pro Docker[43328]: virtio-9p: unexpected EOF reading -- did the 9P server crash?
Jun 30 15:39:05 Daniels-MacBook-Pro Docker[43320]: Reap com.docker.slirp (pid 43326): exit status 1
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43325]: Fatal unexpected exception: Transfuse.Socket_closed
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Reap com.docker.osxfs (pid 43325): exit status 1
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43327]: VM shutdown at 2016-06-30 15:39:06 +0100 BST
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Reap com.docker.driver.amd64-linux (pid 43328): exit status 0
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Stop 1 children with order 1: com.docker.driver.amd64-linux (pid 43328)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Stop 2 children with order 2: com.docker.osxfs (pid 43325), com.docker.slirp (pid 43326)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Starting com.docker.osxfs, com.docker.slirp, com.docker.driver.amd64-linux
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Start com.docker.osxfs (pid 45399)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Start com.docker.slirp (pid 45400)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[43320]: Start com.docker.driver.amd64-linux (pid 45401)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45399]: Logging to Apple System Log
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: Logging to Apple System Log
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: Setting handler to ignore all SIGPIPE signals
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: Setting soft fd limit to 10240
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: starting port_forwarding port_control_path:fd:4 vsock_path:/Users/danielcannon/Library/Containers/com.docker.docker/Data/@connect
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: attempting to reconnect to database on unix /Users/danielcannon/Library/Containers/com.docker.docker/Data/s40
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: reconnected transport layer
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: allowing binds to any IP addresses
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: Creating slirp server pcap_settings:disabled peer_ip:192.168.65.2 local_ip:192.168.65.1
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: Acquired hypervisor lock
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: Docker is not responding: waiting 0.5s
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: hypervisor: native
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: filesystem: osxfs
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: network: hybrid
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45399]: Using protocol TwoThousand msize 16384
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: Hypervisor: native; BootProtocol: direct; UefiBootDisk: /Users/danielcannon/UefiBoot.qcow2
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: 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-vpnkit,uuid=b0eff5f6-a622-4b4a-ae0d-5b3c51dae28e,path=/Users/danielcannon/Library/Containers/com.docker.docker/Data/s50,macfile=/Users/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/mac.0 -s 3,virtio-blk,file:///Users/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2,format=qcow -s 4,virtio-9p,path=/Users/danielcannon/Library/Containers/com.docker.docker/Data/s40,tag=db -s 5,virtio-rnd -s 6,virtio-9p,path=/Users/danielcannon/Library/Containers/com.docker.docker/Data/s51,tag=port -s 7,virtio-sock,guest_cid=3,path=/Users/danielcannon/Library/Containers/com.docker.docker/Data,guest_forwards=2376;1525 -l com1,autopty=/Users/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/tty,log=/Users/danielcannon/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/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux", com.docker.database="com.docker.driver.amd64-linux" ntp=gateway -F /Users/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/hypervisor.pid
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: PPP.negotiate: received ((magic VMN3T)(version 1)(commit ff18c0c63c5ff3c4a4a925d191d5592d655779d7))
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: virtio-net-vpnkit: magic=VMN3T version=1 commit=0123456789012345678901234567890123456789
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: PPP.negotiate: received (Ethernet b0eff5f6-a622-4b4a-ae0d-5b3c51dae28e)
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: PPP.negotiate: sending ((mtu 1500)(max_packet_size 1550)(client_macaddr c0:ff:ee:c0:ff:ee))
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: mirage_block_open file:///Users/danielcannon/Library/Containers/com.docker.docker/Data/com.docker.driver.amd64-linux/Docker.qcow2 buffered = true
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: PPP.listen: called a second time: doing nothing
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45400]: TCP/IP ready
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: mirage_block_open returning 0
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: mirage_block_stat
Jun 30 15:39:06 Daniels-MacBook-Pro Docker[45401]: vsock init 7:0 = /Users/danielcannon/Library/Containers/com.docker.docker/Data, guest_cid = 3
Jun 30 15:39:11 Daniels-MacBook-Pro Docker[43306]: dockerState = Starting
Jun 30 15:39:13 Daniels-MacBook-Pro Docker[45401]:
Jun 30 15:39:14 Daniels-MacBook-Pro Docker[45401]: Docker is not responding: waiting 0.5s
Jun 30 15:39:15 Daniels-MacBook-Pro Docker[45401]: Docker is not responding: waiting 0.5s
Jun 30 15:39:20 Daniels-MacBook-Pro Docker[45399]: transfused: mount /bin/fusermount -o allow_other,max_read=1048576,subtype=osxfs /Mac
Jun 30 15:39:20 Daniels-MacBook-Pro Docker[45399]: osxfs tracing: OFF
Jun 30 15:39:20 Daniels-MacBook-Pro Docker[45400]: Using protocol TwoThousand msize 8192
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:2379:tcp:172.17.0.4:2379)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:2379:tcp:172.17.0.4:2379] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:2379
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:2379:tcp:172.17.0.4:2379
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:4171:tcp:172.17.0.12:4171)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:4171:tcp:172.17.0.12:4171] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:4171
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:4171:tcp:172.17.0.12:4171
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:7750:tcp:172.17.0.10:7750)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:7750:tcp:172.17.0.10:7750] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:7750
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:7750:tcp:172.17.0.10:7750
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:15672:tcp:172.17.0.2:15672)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:15672:tcp:172.17.0.2:15672] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:15672
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:15672:tcp:172.17.0.2:15672
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:5672:tcp:172.17.0.2:5672)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:5672:tcp:172.17.0.2:5672] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:5672
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:5672:tcp:172.17.0.2:5672
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:4161:tcp:172.17.0.5:4161)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:4161:tcp:172.17.0.5:4161] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:4161
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:4161:tcp:172.17.0.5:4161
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:4160:tcp:172.17.0.5:4160)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:4160:tcp:172.17.0.5:4160] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:4160
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:4160:tcp:172.17.0.5:4160
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:8086:tcp:172.17.0.6:8086)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:8086:tcp:172.17.0.6:8086] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:8086
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:8086:tcp:172.17.0.6:8086
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:8083:tcp:172.17.0.6:8083)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:8083:tcp:172.17.0.6:8083] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:8083
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:8083:tcp:172.17.0.6:8083
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:2003:tcp:172.17.0.6:2003)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:2003:tcp:172.17.0.6:2003] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:2003
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:2003:tcp:172.17.0.6:2003
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:4151:tcp:172.17.0.8:4151)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:4151:tcp:172.17.0.8:4151] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:4151
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:4151:tcp:172.17.0.8:4151
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:4150:tcp:172.17.0.8:4150)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:4150:tcp:172.17.0.8:4150] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:4150
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:4150:tcp:172.17.0.8:4150
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:3000:tcp:172.17.0.9:3000)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:3000:tcp:172.17.0.9:3000] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:3000
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:3000:tcp:172.17.0.9:3000
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:9160:tcp:172.17.0.3:9160)
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:9160:tcp:172.17.0.3:9160] to file
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:9160
Jun 30 15:39:22 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:9160:tcp:172.17.0.3:9160
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(tcp:0.0.0.0:9042:tcp:172.17.0.3:9042)
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[tcp:0.0.0.0:9042:tcp:172.17.0.3:9042] to file
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:9042
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Created instance tcp:0.0.0.0:9042:tcp:172.17.0.3:9042
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(udp:0.0.0.0:7760:udp:172.17.0.11:7760)
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[udp:0.0.0.0:7760:udp:172.17.0.11:7760] to file
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:7760
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Created instance udp:0.0.0.0:7760:udp:172.17.0.11:7760
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Creating resource Entry(udp:0.0.0.0:8125:udp:172.17.0.7:8125)
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Write offset=0 data=[udp:0.0.0.0:8125:udp:172.17.0.7:8125] to file
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: attempting a best-effort bind of ::1:8125
Jun 30 15:39:23 Daniels-MacBook-Pro Docker[45400]: Created instance udp:0.0.0.0:8125:udp:172.17.0.7:8125
Jun 30 15:39:29 Daniels-MacBook-Pro Docker[45401]: Docker is responding
Jun 30 15:39:29 Daniels-MacBook-Pro Docker[43327]: VM started at 2016-06-30 15:39:29 +0100 BST
Jun 30 15:39:29 Daniels-MacBook-Pro Docker[43306]: dockerState = Running
Jun 30 15:39:52 Daniels-MacBook-Pro iTerm2[8692]: Time to encode state for window <PseudoTerminal: 0x7fe8fbb9a660 tabs=3 window=<PTYWindow: 0x7fe8fe23e5c0 frame=NSRect: {{3, 4}, {1434, 873}} title=3. tail -f /var/log/system.log | grep --color=auto  Docker (grep) alpha=1.000000 isMain=0 isKey=0 isVisible=1 delegate=0x7fe8fbb9a660>>: 0.2091969847679138
Jun 30 15:41:54 Daniels-MacBook-Pro iTerm2[8692]: Time to encode state for window <PseudoTerminal: 0x7fe8fbb9a660 tabs=3 window=<PTYWindow: 0x7fe8fe23e5c0 frame=NSRect: {{3, 4}, {1434, 873}} title=3. tail -f /var/log/system.log | grep --color=auto  Docker (grep) alpha=1.000000 isMain=0 isKey=0 isVisible=1 delegate=0x7fe8fbb9a660>>: 0.00464397668838501