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 2015/12/04 18:25:25 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,ubuntu:14.04,docker||Hadoop #1323
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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1:48074
I1204 17:25:12.084208 30332 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1:48074
I1204 17:25:12.163482 30331 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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] master@172.17.0.1: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] master@172.17.0.1: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
Jenkins build is back to normal : Mesos » gcc,--verbose,ubuntu:14.04,docker||Hadoop #1324
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1324/changes>
Re: Build failed in Jenkins: Mesos » gcc,--verbose,ubuntu:14.04,docker||Hadoop #1323
Posted by Joseph Wu <jo...@mesosphere.io>.
Wow, this is popping up a lot.
Joris will be committing this (partial) fix shortly though!
https://issues.apache.org/jira/browse/MESOS-4059
https://reviews.apache.org/r/40935/
On Fri, Dec 4, 2015 at 9:25 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> 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
> master@172.17.0.1: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
> master@172.17.0.1: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
> master@172.17.0.1: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
> master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1:48074
> I1204 17:25:12.084208 30332 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.1: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
> master@172.17.0.1: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
> master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1: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 master@172.17.0.1:48074
> I1204 17:25:12.163482 30331 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.1: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
> master@172.17.0.1: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
> master@172.17.0.1: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
> master@172.17.0.1: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] master@172.17.0.1: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] master@172.17.0.1: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
>