[MESOS-6507] 'DockerContainerizerTest.ROOT_DOCKER_SkipRecoverMalformedUUID' fails consistently.

• Type: Bug
• Status: Resolved
• Priority: Blocker
• Resolution: Resolved
• Component/s: docker, test
• Labels: failure
• Assignee: Unassigned
• Reporter: Gilbert Song
• Created: 29/Oct/16 00:14
• Updated: 29/Oct/16 02:54
• Resolved: 29/Oct/16 02:54

Description

Here is the log:

[23:09:24] :	 [Step 10/10] [ RUN      ] DockerContainerizerTest.ROOT_DOCKER_SkipRecoverMalformedUUID
[23:09:24] :	 [Step 10/10] I1028 23:09:24.304638 31435 docker.cpp:933] Running docker -H unix:///var/run/docker.sock rm -f -v mesos-s1.malformedUUID
[23:09:24] :	 [Step 10/10] I1028 23:09:24.398941 31435 resources.cpp:572] Parsing resources as JSON failed: cpus:1;mem:512
[23:09:24] :	 [Step 10/10] Trying semicolon-delimited string format instead
[23:09:24] :	 [Step 10/10] I1028 23:09:24.399123 31435 docker.cpp:809] Running docker -H unix:///var/run/docker.sock run --cpu-shares 1024 --memory 536870912 -e MESOS_SANDBOX=/mnt/mesos/sandbox -e MESOS_CONTAINER_NAME=mesos-s1.malformedUUID -v /mnt/teamcity/temp/buildTmp/DockerContainerizerTest_ROOT_DOCKER_SkipRecoverMalformedUUID_rjDyqa:/mnt/mesos/sandbox --net host --entrypoint /bin/sh --name mesos-s1.malformedUUID alpine -c sleep 1000
[23:09:24] :	 [Step 10/10] I1028 23:09:24.401227 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:24] :	 [Step 10/10] I1028 23:09:24.700460 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:24] :	 [Step 10/10] I1028 23:09:24.804401 31453 docker.cpp:785] Recovering Docker containers
[23:09:24] :	 [Step 10/10] I1028 23:09:24.804477 31453 docker.cpp:1091] Running docker -H unix:///var/run/docker.sock ps -a
[23:09:24] :	 [Step 10/10] I1028 23:09:24.905027 31454 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:25] :	 [Step 10/10] W1028 23:09:25.008965 31454 docker.cpp:838] Skipping recovery of executor '' of framework '' because its latest run could not be recovered
[23:09:25] :	 [Step 10/10] I1028 23:09:25.008996 31454 docker.cpp:957] Checking if Docker container named '/mesos-s1.malformedUUID' was started by Mesos
[23:09:25] :	 [Step 10/10] I1028 23:09:25.009019 31454 docker.cpp:967] Checking if Mesos container with ID 'malformedUUID' has been orphaned
[23:09:25] :	 [Step 10/10] I1028 23:09:25.009052 31454 docker.cpp:860] Running docker -H unix:///var/run/docker.sock stop -t 0 1e9990dbadad6078ceda5d5e0cbfd62b9242c22359126b42dca77d6fdd9a2747
[23:09:25] :	 [Step 10/10] I1028 23:09:25.109345 31451 docker.cpp:933] Running docker -H unix:///var/run/docker.sock rm -v 1e9990dbadad6078ceda5d5e0cbfd62b9242c22359126b42dca77d6fdd9a2747
[23:09:25] :	 [Step 10/10] I1028 23:09:25.212870 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:25] :	 [Step 10/10] I1028 23:09:25.513255 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:25] :	 [Step 10/10] I1028 23:09:25.815946 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:26] :	 [Step 10/10] I1028 23:09:26.119107 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:26] :	 [Step 10/10] I1028 23:09:26.421722 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:26] :	 [Step 10/10] I1028 23:09:26.724777 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:27] :	 [Step 10/10] I1028 23:09:27.028252 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:27] :	 [Step 10/10] I1028 23:09:27.331799 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:27] :	 [Step 10/10] I1028 23:09:27.634660 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:27] :	 [Step 10/10] I1028 23:09:27.938190 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:28] :	 [Step 10/10] I1028 23:09:28.241756 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:28] :	 [Step 10/10] I1028 23:09:28.544697 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:28] :	 [Step 10/10] I1028 23:09:28.847909 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:29] :	 [Step 10/10] I1028 23:09:29.151430 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:29] :	 [Step 10/10] I1028 23:09:29.454700 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:29] :	 [Step 10/10] I1028 23:09:29.757531 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:30] :	 [Step 10/10] I1028 23:09:30.060485 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:30] :	 [Step 10/10] I1028 23:09:30.364303 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:30] :	 [Step 10/10] I1028 23:09:30.668233 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:30] :	 [Step 10/10] I1028 23:09:30.971650 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:31] :	 [Step 10/10] I1028 23:09:31.275362 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:31] :	 [Step 10/10] I1028 23:09:31.578639 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:31] :	 [Step 10/10] I1028 23:09:31.881480 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:32] :	 [Step 10/10] I1028 23:09:32.185004 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:32] :	 [Step 10/10] I1028 23:09:32.488839 31435 docker.cpp:972] Running docker -H unix:///var/run/docker.sock inspect mesos-s1.malformedUUID
[23:09:32] :	 [Step 10/10] ../../src/tests/containerizer/docker_containerizer_tests.cpp:1519: Failure
[23:09:32] :	 [Step 10/10] Value of: exists(docker, slaveId, containerId)
[23:09:32] :	 [Step 10/10]   Actual: false
[23:09:32] :	 [Step 10/10] Expected: true
[23:09:32] :	 [Step 10/10] I1028 23:09:32.792539 31435 docker.cpp:1091] Running docker -H unix:///var/run/docker.sock ps -a
[23:09:32] :	 [Step 10/10] [  FAILED  ] DockerContainerizerTest.ROOT_DOCKER_SkipRecoverMalformedUUID (8590 ms)

Issue Links

• is broken by: MESOS-6212 Validate the name format of mesos-managed docker containers