See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1323/changes>
Changes: [joris.van.remoortere] Quota: Properly initialized the sorter for quota'ed roles in the ------------------------------------------ [...truncated 67232 lines...] I1204 17:25:11.774807 30325 master.cpp:3987] Slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) already registered, resending acknowledgement I1204 17:25:11.774880 30328 hierarchical.cpp:445] Added slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: ) I1204 17:25:11.775149 30328 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:11.774606 30334 slave.cpp:3197] Received ping from slave-observer(112)@172.17.0.1:48074 I1204 17:25:11.775202 30328 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:11.775243 30328 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 in 326996ns I1204 17:25:11.775202 30333 replica.cpp:538] Replica received write request for position 6 from (3855)@172.17.0.1:48074 I1204 17:25:11.775467 30334 slave.cpp:902] Registered with master [email protected]:48074; given slave ID 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 I1204 17:25:11.775495 30334 fetcher.cpp:81] Clearing fetcher cache I1204 17:25:11.775709 30328 status_update_manager.cpp:181] Resuming sending status updates I1204 17:25:11.775895 30334 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_3nfiR9/meta/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1/slave.info' I1204 17:25:11.776273 30334 slave.cpp:961] Forwarding total oversubscribed resources W1204 17:25:11.776391 30334 slave.cpp:947] Already registered with master [email protected]:48074 I1204 17:25:11.776415 30334 slave.cpp:961] Forwarding total oversubscribed resources I1204 17:25:11.776443 30328 master.cpp:4427] Received update of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) with total oversubscribed resources I1204 17:25:11.776628 30328 master.cpp:4427] Received update of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) with total oversubscribed resources I1204 17:25:11.777060 30328 hierarchical.cpp:499] Slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) I1204 17:25:11.777284 30328 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:11.777324 30328 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:11.777350 30328 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 in 253017ns I1204 17:25:11.777690 30328 hierarchical.cpp:499] Slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) I1204 17:25:11.777902 30328 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:11.777945 30328 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:11.777976 30328 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 in 249139ns I1204 17:25:11.779923 30330 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule' I1204 17:25:11.779994 30330 http.cpp:336] HTTP POST for /master/maintenance/schedule from 172.17.0.1:36069 I1204 17:25:11.781002 30332 registrar.cpp:439] Applied 1 operations in 177282ns; attempting to update the 'registry' I1204 17:25:11.805124 30333 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 29.772152ms I1204 17:25:11.805233 30333 replica.cpp:713] Persisted action at 6 I1204 17:25:11.806217 30329 replica.cpp:692] Replica received learned notice for position 6 from @0.0.0.0:0 I1204 17:25:11.897162 30329 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 90.884972ms I1204 17:25:11.897335 30329 leveldb.cpp:399] Deleting ~2 keys from leveldb took 75270ns I1204 17:25:11.897361 30329 replica.cpp:713] Persisted action at 6 I1204 17:25:11.897395 30329 replica.cpp:698] Replica learned TRUNCATE action at position 6 I1204 17:25:11.898412 30327 log.cpp:683] Attempting to append 734 bytes to the log I1204 17:25:11.898509 30329 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7 I1204 17:25:11.899447 30327 replica.cpp:538] Replica received write request for position 7 from (3856)@172.17.0.1:48074 I1204 17:25:11.962810 30327 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 63.292588ms I1204 17:25:11.962906 30327 replica.cpp:713] Persisted action at 7 I1204 17:25:11.964457 30340 replica.cpp:692] Replica received learned notice for position 7 from @0.0.0.0:0 I1204 17:25:11.997746 30340 leveldb.cpp:341] Persisting action (755 bytes) to leveldb took 33.208369ms I1204 17:25:11.997843 30340 replica.cpp:713] Persisted action at 7 I1204 17:25:11.997885 30340 replica.cpp:698] Replica learned APPEND action at position 7 I1204 17:25:12.002301 30335 registrar.cpp:484] Successfully updated the 'registry' in 221.197824ms I1204 17:25:12.003253 30335 master.cpp:4483] Updating unavailability of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2), starting at 2399.2470250923weeks I1204 17:25:12.003509 30335 master.cpp:4483] Updating unavailability of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host), starting at 2399.2470250923weeks I1204 17:25:12.003720 30335 master.cpp:4483] Updating unavailability of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host), starting at 2399.2470250923weeks I1204 17:25:12.003918 30335 master.cpp:4483] Updating unavailability of slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2), starting at 2399.2470250923weeks I1204 17:25:12.004662 30335 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.004751 30335 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:12.004789 30335 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 in 456546ns I1204 17:25:12.005056 30335 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.005105 30335 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:12.005133 30335 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 in 262843ns I1204 17:25:12.005409 30335 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.005460 30335 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:12.005486 30335 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 in 258122ns I1204 17:25:12.005743 30335 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.005794 30335 hierarchical.cpp:1409] No inverse offers to send out! I1204 17:25:12.005821 30335 hierarchical.cpp:1078] Performed allocation for slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 in 258378ns I1204 17:25:12.006494 30327 log.cpp:702] Attempting to truncate the log to 7 I1204 17:25:12.006711 30336 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8 I1204 17:25:12.008008 30333 replica.cpp:538] Replica received write request for position 8 from (3857)@172.17.0.1:48074 I1204 17:25:12.031255 30333 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.171522ms I1204 17:25:12.031345 30333 replica.cpp:713] Persisted action at 8 I1204 17:25:12.034888 30333 replica.cpp:692] Replica received learned notice for position 8 from @0.0.0.0:0 I1204 17:25:12.035431 30306 scheduler.cpp:154] Version: 0.27.0 I1204 17:25:12.036473 30326 scheduler.cpp:236] New master detected at [email protected]:48074 I1204 17:25:12.056442 30333 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 21.477807ms I1204 17:25:12.056582 30333 leveldb.cpp:399] Deleting ~2 keys from leveldb took 64246ns I1204 17:25:12.056605 30333 replica.cpp:713] Persisted action at 8 I1204 17:25:12.056637 30333 replica.cpp:698] Replica learned TRUNCATE action at position 8 I1204 17:25:12.066714 30327 scheduler.cpp:298] Sending SUBSCRIBE call to [email protected]:48074 I1204 17:25:12.069664 30334 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.069746 30334 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36070 I1204 17:25:12.069969 30334 master.cpp:1899] Received subscription request for HTTP framework 'default' I1204 17:25:12.070039 30334 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*' I1204 17:25:12.070751 30334 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I1204 17:25:12.071384 30334 hierarchical.cpp:266] Added framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.073546 30337 master.hpp:1583] Sending heartbeat to 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.075237 30328 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from [email protected]:48074 I1204 17:25:12.076933 30334 hierarchical.cpp:1056] Performed allocation for 2 slaves in 5.51563ms I1204 17:25:12.077787 30334 master.cpp:5137] Sending 2 offers to framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) I1204 17:25:12.078356 30327 scheduler.cpp:457] Enqueuing event HEARTBEAT received from [email protected]:48074 I1204 17:25:12.079051 30327 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event I1204 17:25:12.079973 30334 master.cpp:5227] Sending 2 inverse offers to framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) I1204 17:25:12.083124 30335 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:48074 I1204 17:25:12.084208 30332 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:48074 I1204 17:25:12.089965 30337 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.090317 30337 hierarchical.cpp:1056] Performed allocation for 2 slaves in 703680ns I1204 17:25:12.100298 30331 scheduler.cpp:298] Sending ACCEPT call to [email protected]:48074 I1204 17:25:12.103096 30328 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.103183 30328 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36071 I1204 17:25:12.104060 30328 master.cpp:3010] Processing ACCEPT call for offers: [ 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-O0 ] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) I1204 17:25:12.104116 30328 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task c529d2ea-2899-499e-9826-55dbce934d4c as user 'mesos' W1204 17:25:12.106345 30328 validation.cpp:420] Executor executor-1 for task c529d2ea-2899-499e-9826-55dbce934d4c uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1204 17:25:12.106408 30328 validation.cpp:432] Executor executor-1 for task c529d2ea-2899-499e-9826-55dbce934d4c uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1204 17:25:12.106853 30328 master.hpp:176] Adding task c529d2ea-2899-499e-9826-55dbce934d4c with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 (maintenance-host-2) I1204 17:25:12.107080 30328 master.cpp:3403] Launching task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) I1204 17:25:12.107586 30329 slave.cpp:1292] Got assigned task c529d2ea-2899-499e-9826-55dbce934d4c for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.108196 30329 slave.cpp:1411] Launching task c529d2ea-2899-499e-9826-55dbce934d4c for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.108913 30329 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_3nfiR9/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-1/runs/4aa9506e-bbfe-41cc-8679-bb8bfbdd9e6b' to user 'mesos' I1204 17:25:12.113574 30331 scheduler.cpp:298] Sending ACCEPT call to [email protected]:48074 I1204 17:25:12.117285 30335 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.117377 30335 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36072 I1204 17:25:12.118271 30329 slave.cpp:5042] Launching executor executor-1 of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_3nfiR9/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-1/runs/4aa9506e-bbfe-41cc-8679-bb8bfbdd9e6b' I1204 17:25:12.118474 30335 master.cpp:3010] Processing ACCEPT call for offers: [ 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-O1 ] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host) for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) I1204 17:25:12.118536 30335 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task f91bac40-e57e-4512-a053-82b87463e53f as user 'mesos' W1204 17:25:12.120851 30335 validation.cpp:420] Executor executor-2 for task f91bac40-e57e-4512-a053-82b87463e53f uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1204 17:25:12.120926 30335 validation.cpp:432] Executor executor-2 for task f91bac40-e57e-4512-a053-82b87463e53f uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1204 17:25:12.121454 30335 master.hpp:176] Adding task f91bac40-e57e-4512-a053-82b87463e53f with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 (maintenance-host) I1204 17:25:12.121767 30335 master.cpp:3403] Launching task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host) I1204 17:25:12.122450 30327 slave.cpp:1292] Got assigned task f91bac40-e57e-4512-a053-82b87463e53f for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.123481 30327 slave.cpp:1411] Launching task f91bac40-e57e-4512-a053-82b87463e53f for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.125010 30329 exec.cpp:134] Version: 0.27.0 I1204 17:25:12.125529 30327 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_uo0deK/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-2/runs/791a89b6-1fb3-44bf-914e-e387c03ccd8f' to user 'mesos' I1204 17:25:12.129344 30325 exec.cpp:184] Executor started at: executor(48)@172.17.0.1:48074 with pid 30306 I1204 17:25:12.130405 30329 slave.cpp:1629] Queuing task 'c529d2ea-2899-499e-9826-55dbce934d4c' for executor 'executor-1' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.130697 30329 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_3nfiR9/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-1/runs/4aa9506e-bbfe-41cc-8679-bb8bfbdd9e6b' I1204 17:25:12.130885 30329 slave.cpp:2406] Got registration for executor 'executor-1' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from executor(48)@172.17.0.1:48074 I1204 17:25:12.132135 30329 slave.cpp:1794] Sending queued task 'c529d2ea-2899-499e-9826-55dbce934d4c' to executor 'executor-1' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 at executor(48)@172.17.0.1:48074 I1204 17:25:12.132491 30340 exec.cpp:208] Executor registered on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 I1204 17:25:12.132879 30340 exec.cpp:220] Executor::registered took 37708ns I1204 17:25:12.133180 30340 exec.cpp:295] Executor asked to run task 'c529d2ea-2899-499e-9826-55dbce934d4c' I1204 17:25:12.133352 30340 exec.cpp:304] Executor::launchTask took 82161ns I1204 17:25:12.133473 30340 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.133796 30340 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from executor(48)@172.17.0.1:48074 I1204 17:25:12.134412 30335 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.134505 30335 status_update_manager.cpp:497] Creating StatusUpdate stream for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.135094 30335 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to the slave I1204 17:25:12.135608 30326 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to [email protected]:48074 I1204 17:25:12.135879 30326 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.135939 30326 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to executor(48)@172.17.0.1:48074 I1204 17:25:12.136107 30326 exec.cpp:341] Executor received status update acknowledgement fa984fa5-0d7f-4aba-b5af-dec53cb766f0 for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.136224 30335 master.cpp:4572] Status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) I1204 17:25:12.136292 30335 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.136771 30335 master.cpp:6224] Updating the state of task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1204 17:25:12.137452 30327 slave.cpp:5042] Launching executor executor-2 of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_uo0deK/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-2/runs/791a89b6-1fb3-44bf-914e-e387c03ccd8f' I1204 17:25:12.139441 30326 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:48074 I1204 17:25:12.139770 30327 exec.cpp:134] Version: 0.27.0 I1204 17:25:12.140545 30338 exec.cpp:184] Executor started at: executor(49)@172.17.0.1:48074 with pid 30306 I1204 17:25:12.140741 30327 slave.cpp:1629] Queuing task 'f91bac40-e57e-4512-a053-82b87463e53f' for executor 'executor-2' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.140913 30327 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_uo0deK/slaves/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0/frameworks/3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000/executors/executor-2/runs/791a89b6-1fb3-44bf-914e-e387c03ccd8f' I1204 17:25:12.141110 30327 slave.cpp:2406] Got registration for executor 'executor-2' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from executor(49)@172.17.0.1:48074 I1204 17:25:12.141487 30338 exec.cpp:208] Executor registered on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 I1204 17:25:12.141572 30338 exec.cpp:220] Executor::registered took 48938ns I1204 17:25:12.141958 30327 slave.cpp:1794] Sending queued task 'f91bac40-e57e-4512-a053-82b87463e53f' to executor 'executor-2' of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 at executor(49)@172.17.0.1:48074 I1204 17:25:12.142405 30325 exec.cpp:295] Executor asked to run task 'f91bac40-e57e-4512-a053-82b87463e53f' I1204 17:25:12.142529 30325 exec.cpp:304] Executor::launchTask took 93784ns I1204 17:25:12.142647 30325 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.142976 30335 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from executor(49)@172.17.0.1:48074 I1204 17:25:12.143401 30335 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.143442 30335 status_update_manager.cpp:497] Creating StatusUpdate stream for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.143858 30335 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to the slave I1204 17:25:12.144266 30338 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to [email protected]:48074 I1204 17:25:12.144443 30338 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.144487 30338 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 to executor(49)@172.17.0.1:48074 I1204 17:25:12.144727 30338 master.cpp:4572] Status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 from slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host) I1204 17:25:12.144770 30338 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.145117 30338 master.cpp:6224] Updating the state of task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1204 17:25:12.145232 30338 exec.cpp:341] Executor received status update acknowledgement e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.147022 30331 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:48074 I1204 17:25:12.163482 30331 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:48074 I1204 17:25:12.167515 30331 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.167623 30331 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36073 I1204 17:25:12.167846 30331 master.cpp:3729] Processing ACKNOWLEDGE call fa984fa5-0d7f-4aba-b5af-dec53cb766f0 for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 I1204 17:25:12.168726 30331 status_update_manager.cpp:392] Received status update acknowledgement (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.169164 30331 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: fa984fa5-0d7f-4aba-b5af-dec53cb766f0) for task c529d2ea-2899-499e-9826-55dbce934d4c of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.175426 30331 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:48074 I1204 17:25:12.179494 30326 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.179592 30326 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36074 I1204 17:25:12.179810 30326 master.cpp:3729] Processing ACKNOWLEDGE call e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 I1204 17:25:12.180744 30326 status_update_manager.cpp:392] Received status update acknowledgement (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.181164 30326 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: e6b8e6e2-2e71-47e0-81f5-ef68c0fd6efb) for task f91bac40-e57e-4512-a053-82b87463e53f of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 I1204 17:25:12.183434 30330 scheduler.cpp:298] Sending DECLINE call to [email protected]:48074 I1204 17:25:12.186511 30337 hierarchical.cpp:1316] No resources available to allocate! I1204 17:25:12.186854 30337 hierarchical.cpp:1056] Performed allocation for 2 slaves in 771480ns I1204 17:25:12.187147 30337 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.187235 30337 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36075 I1204 17:25:12.187407 30337 master.cpp:3455] Processing DECLINE call for offers: [ 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-O3 ] for framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 (default) I1204 17:25:12.187974 30337 hierarchical.cpp:764] Framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 filtered inverse offers from slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 for 101secs I1204 17:25:12.191545 30330 scheduler.cpp:298] Sending ACCEPT call to [email protected]:48074 I1204 17:25:12.195410 30336 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1204 17:25:12.195515 30336 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:36076 W1204 17:25:12.196146 30336 master.cpp:2973] ACCEPT call used invalid offers '[ 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-O2 ]': Offer 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-O2 is no longer valid 2015-12-04 17:25:14,349:30306(0x2b95063d2700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:58556] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-04 17:25:17,685:30306(0x2b95063d2700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:58556] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-04 17:25:21,021:30306(0x2b95063d2700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:58556] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-04 17:25:24,357:30306(0x2b95063d2700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:58556] zk retcode=-4, errno=111(Connection refused): server refused to accept the client ../../src/tests/master_maintenance_tests.cpp:1559: Failure Failed to wait 15secs for event I1204 17:25:27.204079 30332 master.cpp:938] Master terminating W1204 17:25:27.204320 30332 master.cpp:6276] Removing task c529d2ea-2899-499e-9826-55dbce934d4c with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) in non-terminal state TASK_RUNNING I1204 17:25:27.205200 30332 master.cpp:6319] Removing executor 'executor-1' with resources of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 at slave(112)@172.17.0.1:48074 (maintenance-host-2) W1204 17:25:27.205687 30332 master.cpp:6276] Removing task f91bac40-e57e-4512-a053-82b87463e53f with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host) in non-terminal state TASK_RUNNING I1204 17:25:27.206296 30332 master.cpp:6319] Removing executor 'executor-2' with resources of framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 on slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 at slave(111)@172.17.0.1:48074 (maintenance-host) I1204 17:25:27.208566 30340 hierarchical.cpp:475] Removed slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S1 I1204 17:25:27.208665 30332 slave.cpp:3243] [email protected]:48074 exited W1204 17:25:27.208689 30332 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected I1204 17:25:27.208719 30332 slave.cpp:3243] [email protected]:48074 exited W1204 17:25:27.208732 30332 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected I1204 17:25:27.209583 30340 hierarchical.cpp:475] Removed slave 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-S0 I1204 17:25:27.210284 30340 hierarchical.cpp:306] Removed framework 3aa8a6f3-017f-4197-919f-5c5e3b0cf760-0000 F1204 17:25:27.210284 30306 logging.cpp:55] RAW: Pure virtual method called @ 0x2b9456c85fd4 google::LogMessage::Fail() @ 0x2b9456c8b6fa google::RawLog__() @ 0x2b9455eda0ea __cxa_pure_virtual @ 0x9d99bb mesos::internal::tests::Cluster::Slaves::shutdown() @ 0xf05d46 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0xf05d08 mesos::internal::tests::MesosTest::Shutdown() @ 0xf022a5 mesos::internal::tests::MesosTest::TearDown() @ 0x14e31c4 testing::internal::HandleSehExceptionsInMethodIfSupported<>() @ 0x14de09e testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x14bf7f0 testing::Test::Run() @ 0x14bff2a testing::TestInfo::Run() @ 0x14c0570 testing::TestCase::Run() @ 0x14c6cb8 testing::internal::UnitTestImpl::RunAllTests() @ 0x14e3de9 testing::internal::HandleSehExceptionsInMethodIfSupported<>() @ 0x14dec14 testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x14c5a54 testing::UnitTest::Run() @ 0xd009a6 RUN_ALL_TESTS() @ 0xd00593 main @ 0x2b9458ac5ec5 (unknown) @ 0x927269 (unknown) make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[4]: *** [check-local] Aborted make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[3]: *** [check-am] Error 2 make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[1]: Leaving directory `/mesos/mesos-0.27.0/_build' make[1]: *** [check-recursive] Error 1 make: *** [distcheck] Error 1 + docker rmi mesos-1449247619-10788 Untagged: mesos-1449247619-10788:latest Deleted: 6275caa8511a897b14210a221a9ea3e9e6f5a6358f0c878e2437d580e6179347 Deleted: 9a7870e8e2dcc975930327b611711e3a31fedb9207f6d265412ec9c74ee9e11b Deleted: e61e5c9236ff8ab6a814ef13bfbcfca249460d5fdf5281ff4fef9c37c4ee0712 Deleted: d6075c5b01d24c705e8d7da13a53218439ca1961788dd53cbeee2e0e1b467852 Deleted: 8e468ba27e217ebb60d54d4d60b12b6dfdb75717cafb7ab3a632be66bbbd7272 Deleted: bc54aa73b5bfea423adf54451eb1d5c190f12391bceabb47a74b7974d5ee8af4 Deleted: 9b7864146e9121a77b504cffb15fd971692b9236a2d1ab212beb48a25e06f5e4 Deleted: 955e64c09ceb98822758456512b4aae2f0adb245deadc1479db264cb25094489 Deleted: 89240062c358d124cc57eb6bec6c53d3644c677e88a6c1fe118c34901a9f7c27 Deleted: 5a683379905792e0d84c53bab43ddec012a9972c7e2bede0410b0f6e99d2c612 Deleted: fafc0ad01d7dbb9266f641267ef6440cc9a2483f241eb7217c3c27008ed09a54 Deleted: 03164e0a753f4ae0b9b92c09e1043758b6c50625f8c87938db8c7e8691846c16 Deleted: 49b67a379cbce6a7f9c9c5e97a19336a2775a2190718dd4f54af259bbdf86367 Build step 'Execute shell' marked build as failure
