See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1315/>
------------------------------------------ [...truncated 65719 lines...] I1203 18:20:08.083830 362 leveldb.cpp:341] Persisting action (536 bytes) to leveldb took 489932ns I1203 18:20:08.083856 362 replica.cpp:713] Persisted action at 5 I1203 18:20:08.083878 362 replica.cpp:698] Replica learned APPEND action at position 5 I1203 18:20:08.086607 365 registrar.cpp:484] Successfully updated the 'registry' in 7.361024ms I1203 18:20:08.086846 362 log.cpp:702] Attempting to truncate the log to 5 I1203 18:20:08.087077 362 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6 I1203 18:20:08.088059 375 master.cpp:4085] Registered slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1203 18:20:08.088321 362 hierarchical.cpp:380] Added slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: ) I1203 18:20:08.088524 362 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.088575 362 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.088601 362 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 in 240968ns I1203 18:20:08.088644 360 replica.cpp:538] Replica received write request for position 6 from (3844)@172.17.0.1:38193 I1203 18:20:08.088717 361 slave.cpp:3197] Received ping from slave-observer(115)@172.17.0.1:38193 I1203 18:20:08.089000 361 slave.cpp:902] Registered with master [email protected]:38193; given slave ID 78046f05-cfb7-4478-8385-528382f71b9e-S1 I1203 18:20:08.089025 361 fetcher.cpp:79] Clearing fetcher cache I1203 18:20:08.089114 360 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 427596ns I1203 18:20:08.089143 360 replica.cpp:713] Persisted action at 6 I1203 18:20:08.089632 374 status_update_manager.cpp:181] Resuming sending status updates I1203 18:20:08.089820 361 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_XSU2vX/meta/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S1/slave.info' I1203 18:20:08.090217 361 slave.cpp:961] Forwarding total oversubscribed resources I1203 18:20:08.090314 360 replica.cpp:692] Replica received learned notice for position 6 from @0.0.0.0:0 I1203 18:20:08.090492 361 master.cpp:4427] Received update of slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) with total oversubscribed resources I1203 18:20:08.090750 360 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 402522ns I1203 18:20:08.090843 360 leveldb.cpp:399] Deleting ~2 keys from leveldb took 64765ns I1203 18:20:08.090870 360 replica.cpp:713] Persisted action at 6 I1203 18:20:08.090895 360 replica.cpp:698] Replica learned TRUNCATE action at position 6 I1203 18:20:08.090980 361 hierarchical.cpp:434] Slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) I1203 18:20:08.091161 361 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.091203 361 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.091225 361 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 in 203111ns I1203 18:20:08.094625 375 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule' I1203 18:20:08.094715 375 http.cpp:336] HTTP POST for /master/maintenance/schedule from 172.17.0.1:35704 I1203 18:20:08.095815 367 registrar.cpp:439] Applied 1 operations in 233576ns; attempting to update the 'registry' I1203 18:20:08.097347 374 log.cpp:683] Attempting to append 734 bytes to the log I1203 18:20:08.097617 374 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7 I1203 18:20:08.098783 374 replica.cpp:538] Replica received write request for position 7 from (3845)@172.17.0.1:38193 I1203 18:20:08.099269 374 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 422267ns I1203 18:20:08.099300 374 replica.cpp:713] Persisted action at 7 I1203 18:20:08.100100 374 replica.cpp:692] Replica received learned notice for position 7 from @0.0.0.0:0 I1203 18:20:08.100509 374 leveldb.cpp:341] Persisting action (755 bytes) to leveldb took 378294ns I1203 18:20:08.100538 374 replica.cpp:713] Persisted action at 7 I1203 18:20:08.100560 374 replica.cpp:698] Replica learned APPEND action at position 7 I1203 18:20:08.103358 368 registrar.cpp:484] Successfully updated the 'registry' in 7.382784ms I1203 18:20:08.103742 374 log.cpp:702] Attempting to truncate the log to 7 I1203 18:20:08.104044 362 master.cpp:4483] Updating unavailability of slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2), starting at 2399.10961820361weeks I1203 18:20:08.104357 374 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8 I1203 18:20:08.105026 362 master.cpp:4483] Updating unavailability of slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host), starting at 2399.10961820361weeks I1203 18:20:08.105545 363 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.105623 363 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.105664 363 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 in 323361ns I1203 18:20:08.105907 363 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.105969 363 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.105998 363 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 in 227967ns I1203 18:20:08.106138 362 master.cpp:4483] Updating unavailability of slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host), starting at 2399.10961820361weeks I1203 18:20:08.106348 362 master.cpp:4483] Updating unavailability of slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2), starting at 2399.10961820361weeks I1203 18:20:08.106581 364 replica.cpp:538] Replica received write request for position 8 from (3846)@172.17.0.1:38193 I1203 18:20:08.106816 370 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.106873 370 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.107069 370 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 in 554269ns I1203 18:20:08.107333 364 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 640014ns I1203 18:20:08.107363 364 replica.cpp:713] Persisted action at 8 I1203 18:20:08.107599 370 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.107808 370 hierarchical.cpp:1155] No inverse offers to send out! I1203 18:20:08.107841 370 hierarchical.cpp:973] Performed allocation for slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 in 380899ns I1203 18:20:08.109459 371 replica.cpp:692] Replica received learned notice for position 8 from @0.0.0.0:0 I1203 18:20:08.110229 341 scheduler.cpp:154] Version: 0.27.0 I1203 18:20:08.110628 371 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.126432ms I1203 18:20:08.110713 371 leveldb.cpp:399] Deleting ~2 keys from leveldb took 42772ns I1203 18:20:08.110736 371 replica.cpp:713] Persisted action at 8 I1203 18:20:08.110766 371 replica.cpp:698] Replica learned TRUNCATE action at position 8 I1203 18:20:08.111073 370 scheduler.cpp:236] New master detected at [email protected]:38193 I1203 18:20:08.121060 370 scheduler.cpp:298] Sending SUBSCRIBE call to [email protected]:38193 I1203 18:20:08.123993 370 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.124058 370 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35705 I1203 18:20:08.124267 370 master.cpp:1899] Received subscription request for HTTP framework 'default' I1203 18:20:08.124351 370 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*' I1203 18:20:08.124866 360 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I1203 18:20:08.125830 371 master.hpp:1583] Sending heartbeat to 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.125911 370 hierarchical.cpp:220] Added framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.127351 360 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from [email protected]:38193 I1203 18:20:08.127707 370 hierarchical.cpp:957] Performed allocation for 2 slaves in 1.763005ms I1203 18:20:08.127928 370 scheduler.cpp:457] Enqueuing event HEARTBEAT received from [email protected]:38193 I1203 18:20:08.128466 370 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event I1203 18:20:08.128676 360 master.cpp:5137] Sending 2 offers to framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) I1203 18:20:08.129547 360 master.cpp:5227] Sending 2 inverse offers to framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) I1203 18:20:08.131640 372 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:38193 I1203 18:20:08.132640 372 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:38193 I1203 18:20:08.142935 368 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.143271 368 hierarchical.cpp:957] Performed allocation for 2 slaves in 1.280338ms I1203 18:20:08.154265 363 scheduler.cpp:298] Sending ACCEPT call to [email protected]:38193 I1203 18:20:08.156982 373 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.157058 373 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35706 I1203 18:20:08.158001 373 master.cpp:3010] Processing ACCEPT call for offers: [ 78046f05-cfb7-4478-8385-528382f71b9e-O0 ] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host) for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) I1203 18:20:08.158068 373 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task e0957182-29d5-4591-938c-583b94075cac as user 'mesos' W1203 18:20:08.160732 373 validation.cpp:420] Executor executor-1 for task e0957182-29d5-4591-938c-583b94075cac uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1203 18:20:08.160810 373 validation.cpp:432] Executor executor-1 for task e0957182-29d5-4591-938c-583b94075cac uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1203 18:20:08.161272 373 master.hpp:176] Adding task e0957182-29d5-4591-938c-583b94075cac with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 (maintenance-host) I1203 18:20:08.161528 373 master.cpp:3403] Launching task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host) I1203 18:20:08.163053 361 slave.cpp:1292] Got assigned task e0957182-29d5-4591-938c-583b94075cac for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.163275 373 scheduler.cpp:298] Sending ACCEPT call to [email protected]:38193 I1203 18:20:08.165091 361 slave.cpp:1411] Launching task e0957182-29d5-4591-938c-583b94075cac for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.166036 361 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_t0jUCH/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S0/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-1/runs/21ad7ee3-0cf3-4cba-a600-9a92469c806d' to user 'mesos' I1203 18:20:08.169783 365 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.170961 365 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35707 I1203 18:20:08.172499 365 master.cpp:3010] Processing ACCEPT call for offers: [ 78046f05-cfb7-4478-8385-528382f71b9e-O1 ] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) I1203 18:20:08.172575 365 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task 124ff0e0-2a13-44e9-930a-3064718f86ae as user 'mesos' I1203 18:20:08.173951 361 slave.cpp:5042] Launching executor executor-1 of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_t0jUCH/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S0/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-1/runs/21ad7ee3-0cf3-4cba-a600-9a92469c806d' I1203 18:20:08.176758 361 exec.cpp:134] Version: 0.27.0 W1203 18:20:08.178130 362 validation.cpp:420] Executor executor-2 for task 124ff0e0-2a13-44e9-930a-3064718f86ae uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1203 18:20:08.178272 362 validation.cpp:432] Executor executor-2 for task 124ff0e0-2a13-44e9-930a-3064718f86ae uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1203 18:20:08.178927 362 master.hpp:176] Adding task 124ff0e0-2a13-44e9-930a-3064718f86ae with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 (maintenance-host-2) I1203 18:20:08.179332 362 master.cpp:3403] Launching task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) I1203 18:20:08.180057 362 slave.cpp:1292] Got assigned task 124ff0e0-2a13-44e9-930a-3064718f86ae for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.180531 361 slave.cpp:1629] Queuing task 'e0957182-29d5-4591-938c-583b94075cac' for executor 'executor-1' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.180651 365 exec.cpp:184] Executor started at: executor(48)@172.17.0.1:38193 with pid 341 I1203 18:20:08.181100 362 slave.cpp:1411] Launching task 124ff0e0-2a13-44e9-930a-3064718f86ae for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.181176 361 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_t0jUCH/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S0/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-1/runs/21ad7ee3-0cf3-4cba-a600-9a92469c806d' I1203 18:20:08.181932 361 slave.cpp:2406] Got registration for executor 'executor-1' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from executor(48)@172.17.0.1:38193 I1203 18:20:08.181985 362 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_XSU2vX/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S1/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-2/runs/6104f936-ea54-4f01-a5f9-01dd606a7215' to user 'mesos' I1203 18:20:08.185151 374 exec.cpp:208] Executor registered on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 I1203 18:20:08.185253 374 exec.cpp:220] Executor::registered took 50794ns I1203 18:20:08.185778 361 slave.cpp:1794] Sending queued task 'e0957182-29d5-4591-938c-583b94075cac' to executor 'executor-1' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 at executor(48)@172.17.0.1:38193 I1203 18:20:08.186409 370 exec.cpp:295] Executor asked to run task 'e0957182-29d5-4591-938c-583b94075cac' I1203 18:20:08.186703 370 exec.cpp:304] Executor::launchTask took 265614ns I1203 18:20:08.187018 370 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.187510 370 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from executor(48)@172.17.0.1:38193 I1203 18:20:08.188098 371 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.188145 371 status_update_manager.cpp:497] Creating StatusUpdate stream for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.188643 371 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to the slave I1203 18:20:08.189015 366 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to [email protected]:38193 I1203 18:20:08.189395 366 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.189781 372 master.cpp:4572] Status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host) I1203 18:20:08.190065 372 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.190698 372 master.cpp:6224] Updating the state of task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1203 18:20:08.191265 366 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to executor(48)@172.17.0.1:38193 I1203 18:20:08.191725 366 exec.cpp:341] Executor received status update acknowledgement a4153a7a-bb32-41cc-9457-841754508d66 for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.193317 363 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:38193 I1203 18:20:08.194072 362 slave.cpp:5042] Launching executor executor-2 of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_XSU2vX/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S1/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-2/runs/6104f936-ea54-4f01-a5f9-01dd606a7215' I1203 18:20:08.196590 362 exec.cpp:134] Version: 0.27.0 I1203 18:20:08.197185 369 exec.cpp:184] Executor started at: executor(49)@172.17.0.1:38193 with pid 341 I1203 18:20:08.197547 362 slave.cpp:1629] Queuing task '124ff0e0-2a13-44e9-930a-3064718f86ae' for executor 'executor-2' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.197845 362 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_XSU2vX/slaves/78046f05-cfb7-4478-8385-528382f71b9e-S1/frameworks/78046f05-cfb7-4478-8385-528382f71b9e-0000/executors/executor-2/runs/6104f936-ea54-4f01-a5f9-01dd606a7215' I1203 18:20:08.198031 362 slave.cpp:2406] Got registration for executor 'executor-2' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from executor(49)@172.17.0.1:38193 I1203 18:20:08.198644 369 exec.cpp:208] Executor registered on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 I1203 18:20:08.198714 369 exec.cpp:220] Executor::registered took 38382ns I1203 18:20:08.199406 362 slave.cpp:1794] Sending queued task '124ff0e0-2a13-44e9-930a-3064718f86ae' to executor 'executor-2' of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 at executor(49)@172.17.0.1:38193 I1203 18:20:08.199937 369 exec.cpp:295] Executor asked to run task '124ff0e0-2a13-44e9-930a-3064718f86ae' I1203 18:20:08.200042 369 exec.cpp:304] Executor::launchTask took 77814ns I1203 18:20:08.200152 369 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.200451 369 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from executor(49)@172.17.0.1:38193 I1203 18:20:08.200881 368 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.201140 368 status_update_manager.cpp:497] Creating StatusUpdate stream for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.201761 368 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to the slave I1203 18:20:08.202327 371 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to [email protected]:38193 I1203 18:20:08.202533 371 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.202585 371 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 to executor(49)@172.17.0.1:38193 I1203 18:20:08.202899 371 master.cpp:4572] Status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 from slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) I1203 18:20:08.202953 371 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.203388 371 master.cpp:6224] Updating the state of task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1203 18:20:08.203501 371 exec.cpp:341] Executor received status update acknowledgement 63ef56ab-2f32-4945-9f6d-f35829c3dc86 for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.205032 372 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:38193 I1203 18:20:08.228276 360 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:38193 I1203 18:20:08.230792 364 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.230885 364 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35708 I1203 18:20:08.231041 364 master.cpp:3729] Processing ACKNOWLEDGE call a4153a7a-bb32-41cc-9457-841754508d66 for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 I1203 18:20:08.231542 372 status_update_manager.cpp:392] Received status update acknowledgement (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.231957 364 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: a4153a7a-bb32-41cc-9457-841754508d66) for task e0957182-29d5-4591-938c-583b94075cac of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.239132 372 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:38193 I1203 18:20:08.241991 360 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.242050 360 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35709 I1203 18:20:08.242168 360 master.cpp:3729] Processing ACKNOWLEDGE call 63ef56ab-2f32-4945-9f6d-f35829c3dc86 for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 I1203 18:20:08.242746 360 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.243091 360 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: 63ef56ab-2f32-4945-9f6d-f35829c3dc86) for task 124ff0e0-2a13-44e9-930a-3064718f86ae of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.244417 360 scheduler.cpp:298] Sending DECLINE call to [email protected]:38193 I1203 18:20:08.246664 374 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.246726 374 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35710 I1203 18:20:08.246840 374 master.cpp:3455] Processing DECLINE call for offers: [ 78046f05-cfb7-4478-8385-528382f71b9e-O3 ] for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 (default) I1203 18:20:08.247181 371 hierarchical.cpp:699] Framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 filtered inverse offers from slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 for 101secs I1203 18:20:08.248678 364 scheduler.cpp:298] Sending ACCEPT call to [email protected]:38193 I1203 18:20:08.250946 374 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 18:20:08.251015 374 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:35711 W1203 18:20:08.251413 374 master.cpp:2973] ACCEPT call used invalid offers '[ 78046f05-cfb7-4478-8385-528382f71b9e-O2 ]': Offer 78046f05-cfb7-4478-8385-528382f71b9e-O2 is no longer valid I1203 18:20:08.251454 366 hierarchical.cpp:1062] No resources available to allocate! I1203 18:20:08.251724 366 hierarchical.cpp:1272] Filtered unavailability on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 for framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:08.251934 366 hierarchical.cpp:957] Performed allocation for 2 slaves in 1.499989ms 2015-12-03 18:20:09,158:341(0x7f61e908c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43036] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 18:20:12,495:341(0x7f61e908c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43036] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 18:20:15,832:341(0x7f61e908c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43036] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 18:20:19,167:341(0x7f61e908c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43036] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 18:20:22,503:341(0x7f61e908c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:43036] 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 I1203 18:20:23.261693 365 master.cpp:938] Master terminating W1203 18:20:23.261994 365 master.cpp:6276] Removing task 124ff0e0-2a13-44e9-930a-3064718f86ae with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) in non-terminal state TASK_RUNNING I1203 18:20:23.263239 368 hierarchical.cpp:410] Removed slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 I1203 18:20:23.263739 365 master.cpp:6319] Removing executor 'executor-2' with resources of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 on slave 78046f05-cfb7-4478-8385-528382f71b9e-S1 at slave(112)@172.17.0.1:38193 (maintenance-host-2) W1203 18:20:23.264315 365 master.cpp:6276] Removing task e0957182-29d5-4591-938c-583b94075cac with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host) in non-terminal state TASK_RUNNING I1203 18:20:23.264825 367 hierarchical.cpp:410] Removed slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 I1203 18:20:23.265086 365 master.cpp:6319] Removing executor 'executor-1' with resources of framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 on slave 78046f05-cfb7-4478-8385-528382f71b9e-S0 at slave(111)@172.17.0.1:38193 (maintenance-host) I1203 18:20:23.265804 372 hierarchical.cpp:260] Removed framework 78046f05-cfb7-4478-8385-528382f71b9e-0000 I1203 18:20:23.267813 367 slave.cpp:3243] [email protected]:38193 exited W1203 18:20:23.267844 367 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected I1203 18:20:23.267884 365 slave.cpp:3243] [email protected]:38193 exited W1203 18:20:23.267940 365 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected F1203 18:20:23.267940 341 logging.cpp:55] RAW: Pure virtual method called @ 0x7f628594a4ae google::LogMessage::Fail() @ 0x7f628594fa04 google::RawLog__() @ 0x7f6284b9f030 __cxa_pure_virtual @ 0x9eb126 mesos::internal::tests::Cluster::Slaves::shutdown() @ 0xf0fb90 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0xf0fb52 mesos::internal::tests::MesosTest::Shutdown() @ 0xf0c12b mesos::internal::tests::MesosTest::TearDown() @ 0x15037ca testing::internal::HandleSehExceptionsInMethodIfSupported<>() @ 0x14fe6a4 testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x14dfcc6 testing::Test::Run() @ 0x14e0400 testing::TestInfo::Run() @ 0x14e0a46 testing::TestCase::Run() @ 0x14e7320 testing::internal::UnitTestImpl::RunAllTests() @ 0x15043ef testing::internal::HandleSehExceptionsInMethodIfSupported<>() @ 0x14ff21a testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x14e6066 testing::UnitTest::Run() @ 0xd0eba0 RUN_ALL_TESTS() @ 0xd0e77e main @ 0x7f62800d3af5 __libc_start_main @ 0x939379 (unknown) make[4]: *** [check-local] Aborted make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.27.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1449165142-11808 Untagged: mesos-1449165142-11808:latest Deleted: db54fc17d43deb93a90e8fabb7824e24f491aafdfabd44cf81a28768e7498566 Deleted: 28ad8a4d4601593fd69c107f55038fb162cb367a553e5cd1c46d59cf898afed6 Deleted: a5760177aedb0acea3825781c16f792b06232601577db17da79670a23a8116de Deleted: 99bebc3204f26ca4a07830e98ac2c646f4b7188df0674758191a99d8c58d88ad Deleted: 9ff79d453151387cfe33d186170f9771af1b94420d5acd2917e642a56a8aa0ba Deleted: 406ad7284996fbb203714adaf5e0f71d2b66aaf90bf1f2ddef4983389ae73307 Deleted: acfc0dc0a0f449b7471e73c200f401cd5983b972548402d786f366eba1a7f3b1 Deleted: 6bfe4589d9179945d0a56ee8e1405f4d2b0dbdbc97d2d80ba41b371fe92bbe63 Deleted: 394453c00da5c216f70ec2ea855be7c5881914c5f9e8d6b5ac83aeacf6a9f73b Deleted: 3f817f3f0e4d357fb448a08ccf88cf269c41ab6ae2ddebb346ad3c5a1fca2d99 Deleted: 5819519622c001b957e40e81e38b8492649b96ab52157f952a28c72b5c716a82 Deleted: b5aacb8d1b68aafaba0d668495a0e1c6e61a15c7224f8e667241e2748688ef52 Build step 'Execute shell' marked build as failure
