You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/10/15 23:22:05 UTC

Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4327

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4327/display/redirect?page=changes>

Changes:

[jpeach] Stopped keeping multiple limitations in MesosContainerizer.

[jpeach] Removed multiple reasons from ContainerTermination.

[jpeach] Propagated the termination info down the container tree.

[jpeach] Added the TaskResourceLimitation message.

[jpeach] Propagated the container termination to the agent API.

[jpeach] Added resources to the TaskStatus message.

[jpeach] Propagated new wait response fields from the default executor.

[jpeach] Printed resource limitations from mesos-execute.

[jpeach] Added a test for nested container resource limitations.

------------------------------------------
[...truncated 5.20 MB...]
3: I1015 23:21:48.344979 18210 slave.cpp:6310] Finished recovery
3: I1015 23:21:48.345448 18210 slave.cpp:6492] Querying resource estimator for oversubscribable resources
3: I1015 23:21:48.345634 18206 status_update_manager.cpp:177] Pausing sending status updates
3: I1015 23:21:48.345670 18210 slave.cpp:993] New master detected at master@172.17.0.2:32992
3: I1015 23:21:48.345767 18210 slave.cpp:1028] Detecting new master
3: I1015 23:21:48.345859 18210 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator
3: I1015 23:21:48.349951 18209 slave.cpp:1055] Authenticating with master master@172.17.0.2:32992
3: I1015 23:21:48.350041 18209 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1015 23:21:48.350298 18202 authenticatee.cpp:121] Creating new client SASL connection
3: I1015 23:21:48.350617 18205 master.cpp:7936] Authenticating slave(96)@172.17.0.2:32992
3: I1015 23:21:48.350710 18204 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(201)@172.17.0.2:32992
3: I1015 23:21:48.350929 18207 authenticator.cpp:98] Creating new server SASL connection
3: I1015 23:21:48.351133 18194 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1015 23:21:48.351177 18194 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1015 23:21:48.351297 18193 authenticator.cpp:204] Received SASL authentication start
3: I1015 23:21:48.351358 18193 authenticator.cpp:326] Authentication requires more steps
3: I1015 23:21:48.351475 18200 authenticatee.cpp:259] Received SASL authentication step
3: I1015 23:21:48.351598 18197 authenticator.cpp:232] Received SASL authentication step
3: I1015 23:21:48.351626 18197 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c1afaf103111' server FQDN: 'c1afaf103111' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1015 23:21:48.351639 18197 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1015 23:21:48.351686 18197 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1015 23:21:48.351714 18197 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c1afaf103111' server FQDN: 'c1afaf103111' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1015 23:21:48.351725 18197 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1015 23:21:48.351732 18197 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1015 23:21:48.351742 18197 authenticator.cpp:318] Authentication success
3: I1015 23:21:48.351819 18215 authenticatee.cpp:299] Authentication success
3: I1015 23:21:48.351876 18214 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(96)@172.17.0.2:32992
3: I1015 23:21:48.351933 18196 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(201)@172.17.0.2:32992
3: I1015 23:21:48.352109 18203 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:32992
3: I1015 23:21:48.352380 18203 slave.cpp:1626] Will retry registration in 8.44011ms if necessary
3: I1015 23:21:48.352569 18212 master.cpp:5801] Received register agent message from slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.352599 18212 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1015 23:21:48.353018 18195 master.cpp:5861] Authorized registration of agent at slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.353130 18195 master.cpp:5954] Registering agent at slave(96)@172.17.0.2:32992 (maintenance-host-2) with id 9c00845b-150c-44a3-9c06-174e030a935d-S1
3: I1015 23:21:48.353507 18206 registrar.cpp:495] Applied 1 operations in 98052ns; attempting to update the registry
3: I1015 23:21:48.354102 18206 registrar.cpp:552] Successfully updated the registry in 528896ns
3: I1015 23:21:48.354290 18202 master.cpp:6001] Admitted agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.354820 18204 slave.cpp:4966] Received ping from slave-observer(90)@172.17.0.2:32992
3: I1015 23:21:48.355136 18204 slave.cpp:1193] Registered with master master@172.17.0.2:32992; given agent ID 9c00845b-150c-44a3-9c06-174e030a935d-S1
3: I1015 23:21:48.354863 18202 master.cpp:6032] Registered agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1015 23:21:48.355284 18197 status_update_manager.cpp:184] Resuming sending status updates
3: I1015 23:21:48.355334 18207 hierarchical.cpp:593] Added agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 (maintenance-host-2) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1015 23:21:48.355592 18204 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/meta/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/slave.info'
3: I1015 23:21:48.355604 18207 hierarchical.cpp:1943] No allocations performed
3: I1015 23:21:48.355656 18207 hierarchical.cpp:1486] Performed allocation for 1 agents in 173221ns
3: I1015 23:21:48.355962 18204 slave.cpp:1262] Forwarding total oversubscribed resources {}
3: I1015 23:21:48.356147 18213 master.cpp:6817] Received update of agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2) with total oversubscribed resources {}
3: I1015 23:21:48.356312 18213 master.cpp:6828] Ignoring update on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2) as it reports no changes
3: I1015 23:21:48.357590 18212 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
3: I1015 23:21:48.358796 18205 http.cpp:1185] HTTP POST for /master/maintenance/schedule from 172.17.0.2:45360
3: I1015 23:21:48.360002 18202 registrar.cpp:495] Applied 1 operations in 197884ns; attempting to update the registry
3: I1015 23:21:48.360628 18202 registrar.cpp:552] Successfully updated the registry in 563968ns
3: I1015 23:21:48.360906 18204 master.cpp:6910] Updating unavailability of agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 at slave(95)@172.17.0.2:32992 (maintenance-host), starting at 2498.5681702305weeks
3: I1015 23:21:48.361037 18204 master.cpp:6910] Updating unavailability of agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2), starting at 2498.5681702305weeks
3: I1015 23:21:48.361371 18198 hierarchical.cpp:1943] No allocations performed
3: I1015 23:21:48.361418 18198 hierarchical.cpp:1486] Performed allocation for 2 agents in 198978ns
3: I1015 23:21:48.363050 18191 scheduler.cpp:190] Version: 1.5.0
3: I1015 23:21:48.363392 18210 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
3: I1015 23:21:48.363795 18194 scheduler.cpp:496] New master detected at master@172.17.0.2:32992
3: I1015 23:21:48.363816 18194 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
3: I1015 23:21:48.365365 18202 scheduler.cpp:387] Connected with the master at http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.366474 18195 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.367612 18205 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1015 23:21:48.368795 18207 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:45362
3: I1015 23:21:48.369123 18207 master.cpp:2580] Received subscription request for HTTP framework 'default'
3: I1015 23:21:48.369179 18207 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1015 23:21:48.369690 18207 master.cpp:2716] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1015 23:21:48.370551 18192 hierarchical.cpp:303] Added framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.370733 18207 master.hpp:349] Sending heartbeat to framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.371353 18205 scheduler.cpp:738] Enqueuing event SUBSCRIBED received from http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.371891 18205 scheduler.cpp:738] Enqueuing event HEARTBEAT received from http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.372872 18192 hierarchical.cpp:1486] Performed allocation for 2 agents in 2.098605ms
3: I1015 23:21:48.373481 18203 master.cpp:7766] Sending 2 offers to framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default)
3: I1015 23:21:48.374372 18203 master.cpp:7855] Sending 2 inverse offers to framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default)
3: I1015 23:21:48.375582 18206 scheduler.cpp:738] Enqueuing event OFFERS received from http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.376415 18206 scheduler.cpp:738] Enqueuing event INVERSE_OFFERS received from http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.378485 18198 hierarchical.cpp:1943] No allocations performed
3: I1015 23:21:48.378733 18198 hierarchical.cpp:1486] Performed allocation for 2 agents in 401919ns
3: I1015 23:21:48.380703 18205 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.381368 18205 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.382614 18192 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1015 23:21:48.384217 18212 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1015 23:21:48.384399 18212 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:45364
3: I1015 23:21:48.385504 18212 master.cpp:9389] Removing offer 9c00845b-150c-44a3-9c06-174e030a935d-O0
3: I1015 23:21:48.385682 18212 master.cpp:4196] Processing ACCEPT call for offers: [ 9c00845b-150c-44a3-9c06-174e030a935d-O0 ] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2) for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default)
3: I1015 23:21:48.385789 18212 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 64c5e734-286a-4b06-9185-a5dd41f59969
3: W1015 23:21:48.387913 18212 validation.cpp:1338] Executor 'executor-1' for task '64c5e734-286a-4b06-9185-a5dd41f59969' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: W1015 23:21:48.387946 18212 validation.cpp:1350] Executor 'executor-1' for task '64c5e734-286a-4b06-9185-a5dd41f59969' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1015 23:21:48.388483 18212 master.cpp:10135] Adding task 64c5e734-286a-4b06-9185-a5dd41f59969 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.389093 18212 master.cpp:4879] Launching task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default) with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.389778 18213 slave.cpp:1747] Got assigned task '64c5e734-286a-4b06-9185-a5dd41f59969' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.389976 18215 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:45364
3: I1015 23:21:48.390717 18213 slave.cpp:2015] Authorizing task '64c5e734-286a-4b06-9185-a5dd41f59969' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.390795 18213 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 64c5e734-286a-4b06-9185-a5dd41f59969
3: I1015 23:21:48.391244 18196 hierarchical.cpp:887] Updated allocation of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 from cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000]
3: I1015 23:21:48.391361 18215 master.cpp:9389] Removing offer 9c00845b-150c-44a3-9c06-174e030a935d-O1
3: I1015 23:21:48.391546 18215 master.cpp:4196] Processing ACCEPT call for offers: [ 9c00845b-150c-44a3-9c06-174e030a935d-O1 ] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 at slave(95)@172.17.0.2:32992 (maintenance-host) for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default)
3: I1015 23:21:48.391631 18215 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 51b37f5a-85d7-4e9e-812d-2b4e124b996e
3: I1015 23:21:48.391786 18192 slave.cpp:2183] Launching task '64c5e734-286a-4b06-9185-a5dd41f59969' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.392891 18192 paths.cpp:605] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387' to user 'mesos'
3: I1015 23:21:48.393175 18192 slave.cpp:7283] Launching executor 'executor-1' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 with resources [] in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387'
3: I1015 23:21:48.394671 18192 slave.cpp:2411] Queued task '64c5e734-286a-4b06-9185-a5dd41f59969' for executor 'executor-1' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.394769 18192 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387' to virtual path '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/latest'
3: I1015 23:21:48.394805 18192 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387' to virtual path '/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/latest'
3: I1015 23:21:48.394837 18192 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387' to virtual path '/tmp/MasterMaintenanceTest_InverseOffersFilters_eUCiw1/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S1/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-1/runs/458e6175-0850-4b55-9f68-7cd78a902387'
3: I1015 23:21:48.395191 18192 slave.cpp:2874] Launching container 458e6175-0850-4b55-9f68-7cd78a902387 for executor 'executor-1' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: *** Aborted at 1508109708 (unix time) try "date -d @1508109708" if you are using GNU date ***
3: PC: @     0x2b4b7a27582d (unknown)
3: W1015 23:21:48.398275 18194 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:32992
3: *** SIGSEGV (@0xb0) received by PID 18191 (TID 0x2b4b816da700) from PID 176; stack trace: ***
3: I1015 23:21:48.398542 18194 exec.cpp:162] Version: 1.5.0
3:     @     0x2b4b75e6f330 (unknown)
3:     @     0x2b4b7a27582d (unknown)
3: I1015 23:21:48.398849 18214 exec.cpp:212] Executor started at: executor(28)@172.17.0.2:32992 with pid 18191
3:     @     0x2b4b7a26b6f1 (unknown)
3: I1015 23:21:48.399138 18193 slave.cpp:3941] Got registration for executor 'executor-1' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from executor(28)@172.17.0.2:32992
3:     @     0x2b4b7a2c2c9e (unknown)
3: W1015 23:21:48.399595 18215 validation.cpp:1338] Executor 'executor-2' for task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
3: I1015 23:21:48.399606 18198 exec.cpp:237] Executor registered on agent 9c00845b-150c-44a3-9c06-174e030a935d-S1
3: W1015 23:21:48.399632 18215 validation.cpp:1350] Executor 'executor-2' for task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
3: I1015 23:21:48.399662 18198 exec.cpp:249] Executor::registered took 27092ns
3: I1015 23:21:48.400187 18193 slave.cpp:2613] Sending queued task '64c5e734-286a-4b06-9185-a5dd41f59969' to executor 'executor-1' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 at executor(28)@172.17.0.2:32992
3: I1015 23:21:48.400554 18213 exec.cpp:331] Executor asked to run task '64c5e734-286a-4b06-9185-a5dd41f59969'
3: I1015 23:21:48.400640 18213 exec.cpp:340] Executor::launchTask took 73962ns
3: I1015 23:21:48.400305 18215 master.cpp:10135] Adding task 51b37f5a-85d7-4e9e-812d-2b4e124b996e with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 at slave(95)@172.17.0.2:32992 (maintenance-host)
3: I1015 23:21:48.400729 18213 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.401038 18202 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from executor(28)@172.17.0.2:32992
3: I1015 23:21:48.401037 18215 master.cpp:4879] Launching task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (default) with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 at slave(95)@172.17.0.2:32992 (maintenance-host)
3: I1015 23:21:48.401633 18215 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.401680 18215 status_update_manager.cpp:500] Creating StatusUpdate stream for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.401787 18196 slave.cpp:1747] Got assigned task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.402160 18215 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to the agent
3: I1015 23:21:48.402393 18195 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to master@172.17.0.2:32992
3: I1015 23:21:48.402633 18195 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.402683 18195 slave.cpp:4786] Sending acknowledgement for status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to executor(28)@172.17.0.2:32992
3: I1015 23:21:48.402804 18203 master.cpp:6993] Status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from agent 9c00845b-150c-44a3-9c06-174e030a935d-S1 at slave(96)@172.17.0.2:32992 (maintenance-host-2)
3: I1015 23:21:48.402845 18203 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 2ec8af58-8ccd-4617-b096-4fd4228aa752) for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.402871 18195 exec.cpp:388] Executor received status update acknowledgement 2ec8af58-8ccd-4617-b096-4fd4228aa752 for task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.403091 18196 slave.cpp:2015] Authorizing task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.403185 18196 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 51b37f5a-85d7-4e9e-812d-2b4e124b996e
3: I1015 23:21:48.403301 18203 master.cpp:9157] Updating the state of task 64c5e734-286a-4b06-9185-a5dd41f59969 of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1015 23:21:48.403441 18199 hierarchical.cpp:887] Updated allocation of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 on agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 from cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000]
3: I1015 23:21:48.404116 18207 slave.cpp:2183] Launching task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' for framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.404682 18207 paths.cpp:605] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd' to user 'mesos'
3: I1015 23:21:48.404690 18194 scheduler.cpp:738] Enqueuing event UPDATE received from http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.404950 18207 slave.cpp:7283] Launching executor 'executor-2' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 with resources [] in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd'
3: I1015 23:21:48.406240 18207 slave.cpp:2411] Queued task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' for executor 'executor-2' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.406360 18207 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd' to virtual path '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/latest'
3: I1015 23:21:48.406414 18207 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd' to virtual path '/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/latest'
3: I1015 23:21:48.406458 18207 slave.cpp:944] Successfully attached '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd' to virtual path '/tmp/MasterMaintenanceTest_InverseOffersFilters_eb62cn/slaves/9c00845b-150c-44a3-9c06-174e030a935d-S0/frameworks/9c00845b-150c-44a3-9c06-174e030a935d-0000/executors/executor-2/runs/af166950-f2e0-45ec-b599-890064f532bd'
3: I1015 23:21:48.406889 18207 slave.cpp:2874] Launching container af166950-f2e0-45ec-b599-890064f532bd for executor 'executor-2' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3:     @           0xa91155 os::strerror()
3: W1015 23:21:48.409508 18205 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:32992
3: I1015 23:21:48.409736 18205 exec.cpp:162] Version: 1.5.0
3: I1015 23:21:48.410037 18215 exec.cpp:212] Executor started at: executor(29)@172.17.0.2:32992 with pid 18191
3: I1015 23:21:48.410558 18211 slave.cpp:3941] Got registration for executor 'executor-2' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from executor(29)@172.17.0.2:32992
3: I1015 23:21:48.411170 18209 exec.cpp:237] Executor registered on agent 9c00845b-150c-44a3-9c06-174e030a935d-S0
3: I1015 23:21:48.411226 18209 exec.cpp:249] Executor::registered took 38668ns
3: I1015 23:21:48.411840 18211 slave.cpp:2613] Sending queued task '51b37f5a-85d7-4e9e-812d-2b4e124b996e' to executor 'executor-2' of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 at executor(29)@172.17.0.2:32992
3: I1015 23:21:48.412233 18210 exec.cpp:331] Executor asked to run task '51b37f5a-85d7-4e9e-812d-2b4e124b996e'
3: I1015 23:21:48.412356 18210 exec.cpp:340] Executor::launchTask took 113480ns
3: I1015 23:21:48.412484 18210 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.412868 18210 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from executor(29)@172.17.0.2:32992
3: I1015 23:21:48.413375 18206 scheduler.cpp:249] Sending ACKNOWLEDGE call to http://172.17.0.2:32992/master/api/v1/scheduler
3: I1015 23:21:48.413609 18200 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.413674 18200 status_update_manager.cpp:500] Creating StatusUpdate stream for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.414213 18200 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to the agent
3: I1015 23:21:48.414458 18197 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to master@172.17.0.2:32992
3: I1015 23:21:48.414700 18197 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.414752 18197 slave.cpp:4786] Sending acknowledgement for status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 to executor(29)@172.17.0.2:32992
3: I1015 23:21:48.414928 18198 exec.cpp:388] Executor received status update acknowledgement ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.414949 18214 master.cpp:6993] Status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 from agent 9c00845b-150c-44a3-9c06-174e030a935d-S0 at slave(95)@172.17.0.2:32992 (maintenance-host)
3: I1015 23:21:48.414999 18214 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: ed5d45eb-c5f3-4874-b9a7-9a27e99bc19a) for task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000
3: I1015 23:21:48.415441 18214 master.cpp:9157] Updating the state of task 51b37f5a-85d7-4e9e-812d-2b4e124b996e of framework 9c00845b-150c-44a3-9c06-174e030a935d-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
3: I1015 23:21:48.416414 18213 scheduler.cpp:738] Enqueuing event UPDATE received from http://172.17.0.2:32992/master/api/v1/scheduler
3:     @           0xa910b1 ErrnoError::ErrnoError()
3:     @           0xa91074 ErrnoError::ErrnoError()
3:     @     0x2b4b750ad797 process::io::internal::read()::$_3::operator()()
3:     @     0x2b4b750ac8f6 process::internal::Loop<>::start()
3:     @     0x2b4b7508a38d process::loop<>()
3:     @     0x2b4b7508a2a3 process::io::internal::read()
3:     @     0x2b4b7508a92c process::io::read()
3:     @     0x2b4b7512bf9e process::network::internal::PollSocketImpl::recv()
3:     @     0x2b4b7527f1f9 process::network::internal::SocketImpl::recv()
3:     @     0x2b4b750498fe process::network::internal::Socket<>::recv()
3:     @     0x2b4b7504974b process::http::internal::ConnectionProcess::read()
3:     @     0x2b4b7504a9ac process::http::internal::ConnectionProcess::_read()
3:     @     0x2b4b7504e970 _ZZN7process8dispatchINS_4http8internal17ConnectionProcessERKNS_6FutureISsEES7_EEvRKNS_3PIDIT_EEMS9_FvT0_EOT1_ENKUlRS5_PNS_11ProcessBaseEE_clESI_SK_
3:     @     0x2b4b7504e7f5 _ZNSt5_BindIFZN7process8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEES8_EEvRKNS0_3PIDIT_EEMSA_FvT0_EOT1_EUlRS6_PNS0_11ProcessBaseEE_S6_St12_PlaceholderILi1EEEE6__callIvJOSL_EJLm0ELm1EEEESA_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3:     @     0x2b4b7504e756 _ZNSt5_BindIFZN7process8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEES8_EEvRKNS0_3PIDIT_EEMSA_FvT0_EOT1_EUlRS6_PNS0_11ProcessBaseEE_S6_St12_PlaceholderILi1EEEEclIJSL_EvEESE_DpOT_
3:     @     0x2b4b7504e3ff _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEESC_EEvRKNS0_3PIDIT_EEMSE_FvT0_EOT1_EUlRSA_S2_E_SA_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
3:     @     0x2b4b7518dab4 std::function<>::operator()()
3:     @     0x2b4b7516f9f4 process::ProcessBase::visit()
3:     @     0x2b4b75225c4e process::DispatchEvent::visit()
3:     @           0xadc191 process::ProcessBase::serve()
3:     @     0x2b4b7516d20f process::ProcessManager::resume()
3:     @     0x2b4b7517cf01 process::ProcessManager::init_threads()::$_9::operator()()
3:     @     0x2b4b7517ce45 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
3:     @     0x2b4b7517ce15 std::_Bind_simple<>::operator()()
3:     @     0x2b4b7517cdec std::thread::_Impl<>::_M_run()
3:     @     0x2b4b79acba60 (unknown)
3:     @     0x2b4b75e67184 start_thread
3/3 Test #3: MesosTests .......................***Exception: SegFault 64.98 sec

67% tests passed, 1 tests failed out of 3

Total Test time (real) = 103.13 sec

The following tests FAILED:
	  3 - MesosTests (SEGFAULT)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1508108117-13546
Untagged: mesos-1508108117-13546:latest
Deleted: sha256:fafc89c31a14ae569a86cd00b6589f166f0d9cb4e0aacfd675b7068c8477bf3e
Deleted: sha256:87af7ea64b1fe1d2c08692d450eff88109db777c0fa2d98defe8f6518a216fc1
Deleted: sha256:0e258967c81ee0c6b21921f5d01eccda8a7b7eda8f4567ba7af79cd687c8c77a
Deleted: sha256:89ac978c2caddadbb792a311669ee12f3af95ba4a6aaf4dd7fcdb01afdcd836c
Deleted: sha256:ba2ea9aabe4b76e1691a31766a97bb77adc47d5fc8529c64c0185725fc7c6b9d
Deleted: sha256:ca0bff41e811aeb7675b266c86c019cfffc20e23b84350b57f6ae1e2b5f0454e
Deleted: sha256:0d2f2902f26ca5c63068788ade61d7bbec2cd1235d962b5c0e5faa58f498e287
Deleted: sha256:9aa33135d2598c1bf8bedcac6113500be2d4c0b3347d6832541e731196237b71
Deleted: sha256:c2829f73166572075e9604f795745ce7024b6439b86293a615552e431c01b756
Deleted: sha256:1aa80f5259d842037a944773649306bc05282c20812c515b0e581b474f221993
Deleted: sha256:c41cfcdfacadac516e2cdc86a62849437d70b884bd7ae2163cd9f446f58f9e9a
Deleted: sha256:2628110296a91a22e5dbafccd0c288c777fd2e1b84ab2c47ae3c9f621fa5509f
Deleted: sha256:ed6dbb7f3ba1a905b271bfbd6c1ca8fb9c732745b1412080dcc753712aebeeeb
Deleted: sha256:221210567eba98f07a84dd1c62efcdcebb9507a5806eaf21320b43b1bc9e74fc
Deleted: sha256:28178c93d3d945509087f0c9bd482bb76a530644409879e8ca6a2338a630f51f
Deleted: sha256:694deb0fd49a71c9d4679806b30862c65daae2a22664139a8a47b46ffb911944
Deleted: sha256:c2ae7932fe127884752188aacc3d1ac4262ff08a54d7a3f25747a0ede2380a17
Deleted: sha256:1869332fae5747e8be91d17e55173a6808ea538acc386a622ee840df34b8b859
Deleted: sha256:4789cfcfd46a3df8ba8335c8d1ba83cc5c070cde815615cb3749906b3a2f9704
Deleted: sha256:b3bca0634027fee04b59818cbd9aed4f963e098323d632b62b44519828316cc0
Deleted: sha256:64b7e9ec81942cb8de02a375754afe2bd89010dfe978be2fd5393792d8c99b1e
Deleted: sha256:37e08d61adf255ba49d30232a52c06ed8ff1905a1f85d0ab72119ef7832d8a1f
Deleted: sha256:59b5c6d7f9ba3ddd1b54b167670fe2252855cf23b6c3dee4b946d5350f84af5a
Deleted: sha256:447440c673966b085b1c57218dcd0df56575b22e9ff56ec42453559f328b6b84
Deleted: sha256:8d00c44f0540eb4ba5c7c39d93568895f759c6d1d30a5171693c888658eb6d13
Deleted: sha256:dad6610e9b26c4da153e896282386a981d819d8f4e35d38ce51c9262cfc44e94
Deleted: sha256:44d5940cadfed02e026272612f4cd5a51b109b3e7a89b57743588b9c29b0d6e8
Deleted: sha256:58f6d7d8bdf525ce1f644bea8dcef827816c2d8892b28916a2334454f724744c
Build step 'Execute shell' marked build as failure


Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4329

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4329/display/redirect>


Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4328

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4328/display/redirect?page=changes>

Changes:

[bmahler] Added protobuf install handler benchmark.

------------------------------------------
[...truncated 31.32 MB...]
3: I1016 23:43:57.624395 26335 registrar.cpp:424] Successfully recovered registrar
3: I1016 23:43:57.624725 26337 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1016 23:43:57.624805 26332 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1016 23:43:57.630151 18191 process.cpp:3194] Attempted to spawn already running process files@172.17.0.3:39076
3: I1016 23:43:57.631017 18191 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1016 23:43:57.631500 18191 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1016 23:43:57.631624 18191 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1016 23:43:57.631654 18191 provisioner.cpp:255] Using default backend 'copy'
3: I1016 23:43:57.633466 18191 cluster.cpp:448] Creating default 'local' authorizer
3: I1016 23:43:57.635633 26332 slave.cpp:254] Mesos agent started on (831)@172.17.0.3:39076
3: I1016 23:43:57.635699 26332 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sX6OiD" --zk_session_timeout="10secs"
3: I1016 23:43:57.636441 26332 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/credential'
3: W1016 23:43:57.636466 18191 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:39076
3: I1016 23:43:57.636708 26332 slave.cpp:287] Agent using credential for: test-principal
3: I1016 23:43:57.636734 26332 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_ZcskIv/http_credentials'
3: I1016 23:43:57.637143 26332 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1016 23:43:57.637344 26332 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1016 23:43:57.637421 18191 sched.cpp:232] Version: 1.5.0
3: I1016 23:43:57.637682 26332 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1016 23:43:57.637811 26332 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1016 23:43:57.638067 26332 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1016 23:43:57.638201 26332 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1016 23:43:57.638202 26331 sched.cpp:336] New master detected at master@172.17.0.3:39076
3: I1016 23:43:57.638387 26331 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1016 23:43:57.638401 26331 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:39076
3: I1016 23:43:57.638587 26331 sched.cpp:853] Will retry registration in 1.511584283secs if necessary
3: I1016 23:43:57.638837 26352 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.638859 26352 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1016 23:43:57.639350 26330 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1016 23:43:57.640045 26333 sched.cpp:747] Framework registered with a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.640059 26341 hierarchical.cpp:303] Added framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.640095 26333 sched.cpp:761] Scheduler::registered took 26779ns
3: I1016 23:43:57.640233 26341 hierarchical.cpp:1943] No allocations performed
3: I1016 23:43:57.640276 26341 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.640318 26341 hierarchical.cpp:1486] Performed allocation for 0 agents in 138676ns
3: I1016 23:43:57.643514 26332 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1016 23:43:57.644147 26332 slave.cpp:593] Agent attributes: [  ]
3: I1016 23:43:57.644163 26332 slave.cpp:602] Agent hostname: c784cbccf4cf
3: I1016 23:43:57.644356 26346 status_update_manager.cpp:177] Pausing sending status updates
3: I1016 23:43:57.646802 26351 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sX6OiD/meta'
3: I1016 23:43:57.647135 26343 status_update_manager.cpp:203] Recovering status update manager
3: I1016 23:43:57.647368 26338 containerizer.cpp:649] Recovering containerizer
3: I1016 23:43:57.648741 26333 provisioner.cpp:416] Provisioner recovery complete
3: I1016 23:43:57.649060 26346 slave.cpp:6310] Finished recovery
3: I1016 23:43:57.649526 26346 slave.cpp:6492] Querying resource estimator for oversubscribable resources
3: I1016 23:43:57.649797 26332 status_update_manager.cpp:177] Pausing sending status updates
3: I1016 23:43:57.649819 26347 slave.cpp:993] New master detected at master@172.17.0.3:39076
3: I1016 23:43:57.649906 26347 slave.cpp:1028] Detecting new master
3: I1016 23:43:57.650022 26347 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator
3: I1016 23:43:57.650378 26339 slave.cpp:1055] Authenticating with master master@172.17.0.3:39076
3: I1016 23:43:57.650471 26339 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1016 23:43:57.650791 26349 authenticatee.cpp:121] Creating new client SASL connection
3: I1016 23:43:57.650924 26339 hierarchical.cpp:1943] No allocations performed
3: I1016 23:43:57.650969 26339 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.651010 26339 hierarchical.cpp:1486] Performed allocation for 0 agents in 164109ns
3: I1016 23:43:57.651106 26331 master.cpp:7936] Authenticating slave(831)@172.17.0.3:39076
3: I1016 23:43:57.651237 26352 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1499)@172.17.0.3:39076
3: I1016 23:43:57.651522 26335 authenticator.cpp:98] Creating new server SASL connection
3: I1016 23:43:57.651723 26348 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1016 23:43:57.651747 26348 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1016 23:43:57.651842 26337 authenticator.cpp:204] Received SASL authentication start
3: I1016 23:43:57.651891 26337 authenticator.cpp:326] Authentication requires more steps
3: I1016 23:43:57.651976 26337 authenticatee.cpp:259] Received SASL authentication step
3: I1016 23:43:57.652094 26344 authenticator.cpp:232] Received SASL authentication step
3: I1016 23:43:57.652127 26344 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c784cbccf4cf' server FQDN: 'c784cbccf4cf' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1016 23:43:57.652143 26344 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1016 23:43:57.652191 26344 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1016 23:43:57.652222 26344 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c784cbccf4cf' server FQDN: 'c784cbccf4cf' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1016 23:43:57.652237 26344 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1016 23:43:57.652246 26344 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1016 23:43:57.652266 26344 authenticator.cpp:318] Authentication success
3: I1016 23:43:57.652369 26342 authenticatee.cpp:299] Authentication success
3: I1016 23:43:57.652429 26329 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(831)@172.17.0.3:39076
3: I1016 23:43:57.652500 26330 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1499)@172.17.0.3:39076
3: I1016 23:43:57.652710 26341 slave.cpp:1147] Successfully authenticated with master master@172.17.0.3:39076
3: I1016 23:43:57.653062 26341 slave.cpp:1626] Will retry registration in 4.319607ms if necessary
3: I1016 23:43:57.653363 26340 master.cpp:5801] Received register agent message from slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.653403 26340 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1016 23:43:57.653961 26332 master.cpp:5861] Authorized registration of agent at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.654116 26332 master.cpp:5954] Registering agent at slave(831)@172.17.0.3:39076 (c784cbccf4cf) with id a86a2254-6bda-4daa-acc1-c927462ded41-S0
3: I1016 23:43:57.654533 26347 registrar.cpp:495] Applied 1 operations in 90022ns; attempting to update the registry
3: I1016 23:43:57.655215 26347 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1016 23:43:57.655462 26345 master.cpp:6001] Admitted agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.656168 26331 slave.cpp:4966] Received ping from slave-observer(746)@172.17.0.3:39076
3: I1016 23:43:57.656447 26348 slave.cpp:1193] Registered with master master@172.17.0.3:39076; given agent ID a86a2254-6bda-4daa-acc1-c927462ded41-S0
3: I1016 23:43:57.656576 26338 status_update_manager.cpp:184] Resuming sending status updates
3: I1016 23:43:57.656829 26348 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_sX6OiD/meta/slaves/a86a2254-6bda-4daa-acc1-c927462ded41-S0/slave.info'
3: I1016 23:43:57.656831 26352 hierarchical.cpp:593] Added agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 (c784cbccf4cf) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1016 23:43:57.657295 26348 slave.cpp:1262] Forwarding total oversubscribed resources {}
3: I1016 23:43:57.657390 26345 master.cpp:6032] Registered agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1016 23:43:57.657974 26345 master.cpp:6817] Received update of agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) with total oversubscribed resources {}
3: I1016 23:43:57.658263 26345 master.cpp:6828] Ignoring update on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) as it reports no changes
3: I1016 23:43:57.658620 26352 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.658664 26352 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.617722ms
3: I1016 23:43:57.659520 26337 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.660123 26344 sched.cpp:917] Scheduler::resourceOffers took 108488ns
3: I1016 23:43:57.662250 26336 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O0
3: I1016 23:43:57.662389 26336 master.cpp:4196] Processing ACCEPT call for offers: [ a86a2254-6bda-4daa-acc1-c927462ded41-O0 ] on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.662578 26336 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1016 23:43:57.663719 26336 master.cpp:2348] Dropping CREATE offer operation from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076: Not authorized to create persistent volumes as 'test-principal'
3: I1016 23:43:57.665067 26340 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports:[31000-32000], allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.667048 26351 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.667089 26351 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.481815ms
3: I1016 23:43:57.668210 26334 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.668864 26343 sched.cpp:917] Scheduler::resourceOffers took 105648ns
3: W1016 23:43:57.669780 18191 process.cpp:3194] Attempted to spawn already running process version@172.17.0.3:39076
3: I1016 23:43:57.669791 26347 master.cpp:5207] Processing DECLINE call for offers: [ a86a2254-6bda-4daa-acc1-c927462ded41-O1 ] for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.670601 18191 sched.cpp:232] Version: 1.5.0
3: I1016 23:43:57.670631 26347 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O1
3: I1016 23:43:57.670765 26347 master.cpp:3439] Processing SUPPRESS call for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.671192 26345 sched.cpp:336] New master detected at master@172.17.0.3:39076
3: I1016 23:43:57.671308 26345 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1016 23:43:57.671324 26345 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.3:39076
3: I1016 23:43:57.671366 26331 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports:[31000-32000], allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.671447 26345 sched.cpp:853] Will retry registration in 564.165254ms if necessary
3: I1016 23:43:57.671566 26331 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.671658 26352 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.671679 26352 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1016 23:43:57.672150 26342 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1016 23:43:57.672719 26333 hierarchical.cpp:303] Added framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.672956 26342 sched.cpp:747] Framework registered with a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.672998 26342 sched.cpp:761] Scheduler::registered took 24435ns
3: I1016 23:43:57.674304 26333 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.674340 26333 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.46492ms
3: I1016 23:43:57.675148 26341 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.675961 26330 sched.cpp:917] Scheduler::resourceOffers took 117884ns
3: I1016 23:43:57.676728 26332 hierarchical.cpp:1943] No allocations performed
3: I1016 23:43:57.676785 26332 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.676836 26332 hierarchical.cpp:1486] Performed allocation for 1 agents in 296605ns
3: I1016 23:43:57.678889 26351 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O2
3: I1016 23:43:57.679038 26351 master.cpp:4196] Processing ACCEPT call for offers: [ a86a2254-6bda-4daa-acc1-c927462ded41-O2 ] on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) for framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.679257 26351 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1016 23:43:57.680969 26351 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076 to agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.681555 26351 master.cpp:9335] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048 to agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.682971 26347 slave.cpp:3519] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048
3: I1016 23:43:57.685142 26338 hierarchical.cpp:887] Updated allocation of framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048
3: I1016 23:43:57.686524 26338 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048, allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.688854 26345 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.688917 26345 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.633848ms
3: I1016 23:43:57.689710 26331 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.690527 26337 sched.cpp:917] Scheduler::resourceOffers took 118514ns
3: I1016 23:43:57.691409 26344 master.cpp:5207] Processing DECLINE call for offers: [ a86a2254-6bda-4daa-acc1-c927462ded41-O3 ] for framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.692308 26344 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O3
3: I1016 23:43:57.692456 26344 master.cpp:3439] Processing SUPPRESS call for framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.693099 26346 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048, allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.693259 26346 hierarchical.cpp:1273] Suppressed offers for roles { default-role } of framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.693760 26342 master.cpp:5312] Processing REVIVE call for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.693963 26342 hierarchical.cpp:1308] Revived offers for roles { default-role } of framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.695664 26342 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.695711 26342 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.598336ms
3: I1016 23:43:57.696480 26341 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.697149 26330 sched.cpp:917] Scheduler::resourceOffers took 114383ns
3: I1016 23:43:57.698860 26336 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O4
3: I1016 23:43:57.699026 26336 master.cpp:4196] Processing ACCEPT call for offers: [ a86a2254-6bda-4daa-acc1-c927462ded41-O4 ] on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.699249 26336 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1016 23:43:57.700493 26343 master.cpp:2348] Dropping DESTROY offer operation from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076: Not authorized to destroy persistent volumes as 'test-principal'
3: I1016 23:43:57.703297 26335 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048, allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.705610 26347 hierarchical.cpp:2033] No inverse offers to send out!
3: I1016 23:43:57.705655 26347 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.535606ms
3: I1016 23:43:57.706460 26338 master.cpp:7766] Sending 1 offers to framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.707418 26348 sched.cpp:917] Scheduler::resourceOffers took 182698ns
3: I1016 23:43:57.707820 18191 sched.cpp:2005] Asked to stop the driver
3: I1016 23:43:57.707931 26345 sched.cpp:1187] Stopping framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.708271 26331 master.cpp:8447] Processing TEARDOWN call for framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.708299 26331 master.cpp:8459] Removing framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.708310 26331 master.cpp:3299] Deactivating framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 (default) at scheduler-4c2a76ac-5a0a-4159-b211-81ce9ac01f2c@172.17.0.3:39076
3: I1016 23:43:57.708360 18191 sched.cpp:2005] Asked to stop the driver
3: I1016 23:43:57.708423 26344 hierarchical.cpp:412] Deactivated framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.708457 26329 sched.cpp:1187] Stopping framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.709295 26331 master.cpp:9389] Removing offer a86a2254-6bda-4daa-acc1-c927462ded41-O5
3: I1016 23:43:57.709516 26350 slave.cpp:3211] Asked to shut down framework a86a2254-6bda-4daa-acc1-c927462ded41-0000 by master@172.17.0.3:39076
3: I1016 23:43:57.709552 26350 slave.cpp:3226] Cannot shut down unknown framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.709846 26331 master.cpp:8447] Processing TEARDOWN call for framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.709872 26331 master.cpp:8459] Removing framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.709887 26331 master.cpp:3299] Deactivating framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 (creator-framework) at scheduler-ffa96075-7896-4d22-9ef5-3d847f5f2ab6@172.17.0.3:39076
3: I1016 23:43:57.710068 26346 slave.cpp:3211] Asked to shut down framework a86a2254-6bda-4daa-acc1-c927462ded41-0001 by master@172.17.0.3:39076
3: I1016 23:43:57.710099 26346 slave.cpp:3226] Cannot shut down unknown framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.710105 26342 hierarchical.cpp:1161] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_bY0kTK/disk1,id1:path1]:2048, allocated: {}) on agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 from framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.710317 26342 hierarchical.cpp:355] Removed framework a86a2254-6bda-4daa-acc1-c927462ded41-0000
3: I1016 23:43:57.710383 26342 hierarchical.cpp:412] Deactivated framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.710598 26342 hierarchical.cpp:355] Removed framework a86a2254-6bda-4daa-acc1-c927462ded41-0001
3: I1016 23:43:57.710811 26347 slave.cpp:869] Agent terminating
3: I1016 23:43:57.711030 26338 master.cpp:1303] Agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf) disconnected
3: I1016 23:43:57.711055 26338 master.cpp:3336] Disconnecting agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.711134 26338 master.cpp:3355] Deactivating agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 at slave(831)@172.17.0.3:39076 (c784cbccf4cf)
3: I1016 23:43:57.711299 26344 hierarchical.cpp:690] Agent a86a2254-6bda-4daa-acc1-c927462ded41-S0 deactivated
3: I1016 23:43:57.716255 26333 master.cpp:1145] Master terminating
3: I1016 23:43:57.717171 26331 hierarchical.cpp:626] Removed agent a86a2254-6bda-4daa-acc1-c927462ded41-S0
3: [       OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (109 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (10994 ms total)
3: 
3: [----------] Global test environment tear-down
3: [==========] 1657 tests from 180 test cases ran. (386957 ms total)
3: [  PASSED  ] 1655 tests.
3: [  FAILED  ] 2 tests, listed below:
3: [  FAILED  ] DockerFetcherPluginTest.INTERNET_CURL_FetchImage
3: [  FAILED  ] MasterTest.IgnoreOldAgentReregistration
3: 
3:  2 FAILED TESTS
3:   YOU HAVE 21 DISABLED TESTS
3: 
3: I1016 23:43:57.790289 26353 process.cpp:1068] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed  391.95 sec

67% tests passed, 1 tests failed out of 3

Total Test time (real) = 430.04 sec

The following tests FAILED:
	  3 - MesosTests (Failed)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
+ docker rmi mesos-1508195569-19801
Untagged: mesos-1508195569-19801:latest
Deleted: sha256:6b4fb04b4e0f66f8e0b2b871b1e6891682d0720ca37af63b6d7f235f485c4294
Deleted: sha256:788ba51ac1389d43724d34c4dfe0dcb7cf4c4467b4e1909ebabb90c2a433f2ae
Deleted: sha256:a712c6ea4454918cd0010e8239c2474dd30839899a2339c11d2aca5efaff3e08
Deleted: sha256:693c77aa7be63ef7c92cea34a6cbe66248b6f574180b9cb66a72b66b581607f1
Deleted: sha256:107a9b63c971f9d63b6e89136ed310c19caac975475990d19dd21330042be8bb
Deleted: sha256:87b885ed171da7cf49bf16d7b61e6216159b3c739970dc998069486aa8e3616d
Deleted: sha256:8a084757a62b61929baaf4b6b770219e79d105c8d249b6695cc30118fb056719
Deleted: sha256:179daecdcdd3ddc7b0b29f28b683ee9f7e63e15f5f951ed3b9e29a634655737c
Deleted: sha256:73c0425c5f188b003a13702d1a28042af267bf3eabea0196f76ff7a3f5b970a2
Deleted: sha256:3464883d293ce5e4346e25e2a1e1c40792f750be7a34d2e6b11d961951aecd0f
Deleted: sha256:11809baace6a4422b70b962c0476ef4cab14eebd48d9a67339ae8b8c4648e13f
Deleted: sha256:32c54b88af34445ae23164b2b3376c38e3ac62f516459f502427a82b62bd347e
Deleted: sha256:8f3c0112cec0b13b00ade29334f527297d8e5965542c7fc1d30d80dc5df4e363
Deleted: sha256:0590da4912b9e9c45b2a5c2187188941905eb1cbf3291cf1dac0f0dba07fda19
Deleted: sha256:25cf747190af14aecc7bbb26c4a7e738c336ec59e4d735afca2cd16ec95c1d72
Deleted: sha256:654fcdafee1d0501a2423683b1a8a91b1156ea525fea632a745f39cd6cc64caa
Deleted: sha256:d360f10a5001fcf3a721d7b2b4292fc6e8422a978c1359f7002105e98e091b7f
Deleted: sha256:b23fdee18c1c33b104f5315dc1bbddb9e3428f6816d62fa751667f43c0b26d57
Deleted: sha256:9b7ace910e1e7355cc6608e494f76a76faa94809226dd08ce48335da05668f35
Deleted: sha256:71a3c9e3c45c20a9326d29a93c4c028fa46a79569a0e5a33c60eed4b3757aa74
Deleted: sha256:46513f30cb62b06772e47affdca3f6f76a331e1dbdbace9faa14bce8d61a4d4c
Deleted: sha256:ce26aff422f1767c5df40b53c8af35f7a7252f3a85733d3e4605b0e5dc07670c
Deleted: sha256:3303a910a7be766a72f5b42d275010b143c629b25d3ac9addf31d27f1c6ab651
Deleted: sha256:92bcb458c3a6caa81c9a55dbddbf47ce636bb4d5c72809ad81df7724ad7b74ec
Deleted: sha256:5d4a6b1a132155229156628d3f2b949c6b28f62d5c3aff4cd3a685e25df36f88
Deleted: sha256:0b458a674803196910fd860e443b8d84be35c8ed22777fc0945a3b87607ccc89
Deleted: sha256:01afa5747c9ba231eda44a7a0fc18c8afd99f57ab5b175210385573b5a5d1c9a
Deleted: sha256:ba57c2dd649a866794a0d8010d559ffd6501a170d89a6b7cd2bb0ae12a10e892
Build step 'Execute shell' marked build as failure