See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1317/changes>
Changes: [joris.van.remoortere] Quota: Updated allocate() in the hierarchical allocator. ------------------------------------------ [...truncated 66921 lines...] I1203 20:10:00.699725 28747 master.cpp:5338] Successfully authenticated principal 'test-principal' at slave(112)@172.17.0.2:59124 I1203 20:10:00.699808 28747 authenticator.cpp:431] Authentication session cleanup for crammd5_authenticatee(288)@172.17.0.2:59124 I1203 20:10:00.700198 28742 slave.cpp:858] Successfully authenticated with master [email protected]:59124 I1203 20:10:00.700353 28742 slave.cpp:1252] Will retry registration in 8.210485ms if necessary I1203 20:10:00.700698 28742 master.cpp:4017] Registering slave at slave(112)@172.17.0.2:59124 (maintenance-host-2) with id 52a08176-53ce-4791-8efa-0de027849fe4-S1 I1203 20:10:00.701298 28742 registrar.cpp:439] Applied 1 operations in 121263ns; attempting to update the 'registry' I1203 20:10:00.702785 28743 log.cpp:683] Attempting to append 515 bytes to the log I1203 20:10:00.703001 28739 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5 I1203 20:10:00.705103 28739 replica.cpp:538] Replica received write request for position 5 from (3796)@172.17.0.2:59124 I1203 20:10:00.705418 28739 leveldb.cpp:341] Persisting action (534 bytes) to leveldb took 269815ns I1203 20:10:00.705451 28739 replica.cpp:713] Persisted action at 5 I1203 20:10:00.706810 28745 replica.cpp:692] Replica received learned notice for position 5 from @0.0.0.0:0 I1203 20:10:00.707990 28745 leveldb.cpp:341] Persisting action (536 bytes) to leveldb took 1.163208ms I1203 20:10:00.708041 28745 replica.cpp:713] Persisted action at 5 I1203 20:10:00.708071 28745 replica.cpp:698] Replica learned APPEND action at position 5 I1203 20:10:00.711784 28745 slave.cpp:1252] Will retry registration in 9.105101ms if necessary I1203 20:10:00.712334 28743 master.cpp:4005] Ignoring register slave message from slave(112)@172.17.0.2:59124 (maintenance-host-2) as admission is already in progress I1203 20:10:00.713016 28742 log.cpp:702] Attempting to truncate the log to 5 I1203 20:10:00.713289 28742 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6 I1203 20:10:00.714016 28739 registrar.cpp:484] Successfully updated the 'registry' in 12.614144ms I1203 20:10:00.714514 28742 replica.cpp:538] Replica received write request for position 6 from (3797)@172.17.0.2:59124 I1203 20:10:00.715093 28750 slave.cpp:3197] Received ping from slave-observer(115)@172.17.0.2:59124 I1203 20:10:00.715111 28739 master.cpp:4085] Registered slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1203 20:10:00.715380 28750 slave.cpp:902] Registered with master [email protected]:59124; given slave ID 52a08176-53ce-4791-8efa-0de027849fe4-S1 I1203 20:10:00.715409 28750 fetcher.cpp:79] Clearing fetcher cache I1203 20:10:00.715880 28750 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_n1w6Ys/meta/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S1/slave.info' I1203 20:10:00.716008 28749 status_update_manager.cpp:181] Resuming sending status updates I1203 20:10:00.716219 28739 hierarchical.cpp:380] Added slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: ) I1203 20:10:00.716397 28750 slave.cpp:961] Forwarding total oversubscribed resources I1203 20:10:00.716522 28739 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.716575 28739 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.716578 28750 master.cpp:4427] Received update of slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) with total oversubscribed resources I1203 20:10:00.716608 28739 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 in 350185ns I1203 20:10:00.718005 28742 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 3.465859ms I1203 20:10:00.718051 28742 replica.cpp:713] Persisted action at 6 I1203 20:10:00.718312 28750 hierarchical.cpp:434] Slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) I1203 20:10:00.718590 28750 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.718636 28750 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.718662 28750 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 in 308540ns I1203 20:10:00.718961 28750 replica.cpp:692] Replica received learned notice for position 6 from @0.0.0.0:0 I1203 20:10:00.719974 28750 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 996329ns I1203 20:10:00.720057 28750 leveldb.cpp:399] Deleting ~2 keys from leveldb took 63973ns I1203 20:10:00.720087 28750 replica.cpp:713] Persisted action at 6 I1203 20:10:00.720114 28750 replica.cpp:698] Replica learned TRUNCATE action at position 6 I1203 20:10:00.722125 28749 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule' I1203 20:10:00.722213 28749 http.cpp:336] HTTP POST for /master/maintenance/schedule from 172.17.0.2:39024 I1203 20:10:00.723449 28749 registrar.cpp:439] Applied 1 operations in 242341ns; attempting to update the 'registry' I1203 20:10:00.726423 28749 log.cpp:683] Attempting to append 734 bytes to the log I1203 20:10:00.726557 28746 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7 I1203 20:10:00.727521 28742 replica.cpp:538] Replica received write request for position 7 from (3798)@172.17.0.2:59124 I1203 20:10:00.728049 28742 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 493990ns I1203 20:10:00.728080 28742 replica.cpp:713] Persisted action at 7 I1203 20:10:00.729109 28738 replica.cpp:692] Replica received learned notice for position 7 from @0.0.0.0:0 I1203 20:10:00.729534 28738 leveldb.cpp:341] Persisting action (755 bytes) to leveldb took 406209ns I1203 20:10:00.729562 28738 replica.cpp:713] Persisted action at 7 I1203 20:10:00.729581 28738 replica.cpp:698] Replica learned APPEND action at position 7 I1203 20:10:00.732143 28750 registrar.cpp:484] Successfully updated the 'registry' in 8.583936ms I1203 20:10:00.732354 28738 log.cpp:702] Attempting to truncate the log to 7 I1203 20:10:00.732527 28738 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8 I1203 20:10:00.732764 28750 master.cpp:4483] Updating unavailability of slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2), starting at 2399.12051871254weeks I1203 20:10:00.733240 28738 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.733309 28738 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.733338 28738 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 in 356068ns I1203 20:10:00.732918 28750 master.cpp:4483] Updating unavailability of slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host), starting at 2399.12051871254weeks I1203 20:10:00.733733 28750 master.cpp:4483] Updating unavailability of slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host), starting at 2399.12051871254weeks I1203 20:10:00.733860 28750 master.cpp:4483] Updating unavailability of slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2), starting at 2399.12051871254weeks I1203 20:10:00.734056 28752 replica.cpp:538] Replica received write request for position 8 from (3799)@172.17.0.2:59124 I1203 20:10:00.734580 28752 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 461325ns I1203 20:10:00.734612 28752 replica.cpp:713] Persisted action at 8 I1203 20:10:00.734633 28738 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.734679 28738 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.734700 28738 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 in 253338ns I1203 20:10:00.735113 28738 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.735162 28738 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.735184 28738 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 in 401480ns I1203 20:10:00.735420 28738 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.735466 28738 hierarchical.cpp:1311] No inverse offers to send out! I1203 20:10:00.735491 28738 hierarchical.cpp:973] Performed allocation for slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 in 244931ns I1203 20:10:00.735816 28742 replica.cpp:692] Replica received learned notice for position 8 from @0.0.0.0:0 I1203 20:10:00.737114 28742 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.282088ms I1203 20:10:00.737207 28742 leveldb.cpp:399] Deleting ~2 keys from leveldb took 64048ns I1203 20:10:00.737236 28742 replica.cpp:713] Persisted action at 8 I1203 20:10:00.737264 28742 replica.cpp:698] Replica learned TRUNCATE action at position 8 I1203 20:10:00.743475 28719 scheduler.cpp:154] Version: 0.27.0 I1203 20:10:00.744124 28737 scheduler.cpp:236] New master detected at [email protected]:59124 I1203 20:10:00.754590 28737 scheduler.cpp:298] Sending SUBSCRIBE call to [email protected]:59124 I1203 20:10:00.758169 28746 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.758258 28746 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39025 I1203 20:10:00.758508 28746 master.cpp:1899] Received subscription request for HTTP framework 'default' I1203 20:10:00.758540 28746 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*' I1203 20:10:00.759346 28750 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I1203 20:10:00.759867 28743 hierarchical.cpp:220] Added framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.760155 28739 master.hpp:1583] Sending heartbeat to 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.761992 28743 hierarchical.cpp:957] Performed allocation for 2 slaves in 2.111964ms I1203 20:10:00.761996 28739 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from [email protected]:59124 I1203 20:10:00.762712 28742 master.cpp:5137] Sending 2 offers to framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) I1203 20:10:00.762766 28739 scheduler.cpp:457] Enqueuing event HEARTBEAT received from [email protected]:59124 I1203 20:10:00.763114 28739 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event I1203 20:10:00.764091 28742 master.cpp:5227] Sending 2 inverse offers to framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) I1203 20:10:00.765785 28737 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:59124 I1203 20:10:00.769433 28748 scheduler.cpp:457] Enqueuing event OFFERS received from [email protected]:59124 I1203 20:10:00.777725 28748 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.778095 28748 hierarchical.cpp:957] Performed allocation for 2 slaves in 733489ns I1203 20:10:00.788885 28738 scheduler.cpp:298] Sending ACCEPT call to [email protected]:59124 I1203 20:10:00.792490 28737 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.792570 28737 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39026 I1203 20:10:00.793385 28737 master.cpp:3010] Processing ACCEPT call for offers: [ 52a08176-53ce-4791-8efa-0de027849fe4-O0 ] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host) for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) I1203 20:10:00.793437 28737 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 as user 'mesos' W1203 20:10:00.795497 28741 validation.cpp:420] Executor executor-1 for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1203 20:10:00.795794 28741 validation.cpp:432] Executor executor-1 for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1203 20:10:00.796555 28739 scheduler.cpp:298] Sending ACCEPT call to [email protected]:59124 I1203 20:10:00.797438 28741 master.hpp:176] Adding task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 (maintenance-host) I1203 20:10:00.798259 28741 master.cpp:3403] Launching task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host) I1203 20:10:00.799140 28739 slave.cpp:1292] Got assigned task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.799800 28739 slave.cpp:1411] Launching task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.800600 28739 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_0iTwiQ/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S0/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-1/runs/1ee5905d-e301-450f-934e-de4d869ec8f8' to user 'mesos' I1203 20:10:00.804157 28744 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.804251 28744 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39027 I1203 20:10:00.805317 28744 master.cpp:3010] Processing ACCEPT call for offers: [ 52a08176-53ce-4791-8efa-0de027849fe4-O1 ] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) I1203 20:10:00.805374 28744 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff as user 'mesos' W1203 20:10:00.808094 28744 validation.cpp:420] Executor executor-2 for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1203 20:10:00.808176 28744 validation.cpp:432] Executor executor-2 for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1203 20:10:00.808668 28744 master.hpp:176] Adding task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 (maintenance-host-2) I1203 20:10:00.808789 28739 slave.cpp:5042] Launching executor executor-1 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_0iTwiQ/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S0/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-1/runs/1ee5905d-e301-450f-934e-de4d869ec8f8' I1203 20:10:00.809499 28744 master.cpp:3403] Launching task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) I1203 20:10:00.810102 28744 slave.cpp:1292] Got assigned task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.810753 28744 slave.cpp:1411] Launching task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.811920 28744 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_n1w6Ys/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S1/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-2/runs/3c97cfde-c03d-4a2d-86b2-c5bdae8fb5b4' to user 'mesos' I1203 20:10:00.816427 28739 exec.cpp:134] Version: 0.27.0 I1203 20:10:00.816803 28751 exec.cpp:184] Executor started at: executor(48)@172.17.0.2:59124 with pid 28719 I1203 20:10:00.817456 28739 slave.cpp:1629] Queuing task 'e18bc6c4-6d88-4e0d-9860-01ff47f587d4' for executor 'executor-1' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.817637 28739 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_0iTwiQ/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S0/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-1/runs/1ee5905d-e301-450f-934e-de4d869ec8f8' I1203 20:10:00.817728 28739 slave.cpp:2406] Got registration for executor 'executor-1' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from executor(48)@172.17.0.2:59124 I1203 20:10:00.818254 28737 exec.cpp:208] Executor registered on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 I1203 20:10:00.818308 28737 exec.cpp:220] Executor::registered took 36799ns I1203 20:10:00.819108 28739 slave.cpp:1794] Sending queued task 'e18bc6c4-6d88-4e0d-9860-01ff47f587d4' to executor 'executor-1' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 at executor(48)@172.17.0.2:59124 I1203 20:10:00.819556 28748 exec.cpp:295] Executor asked to run task 'e18bc6c4-6d88-4e0d-9860-01ff47f587d4' I1203 20:10:00.819655 28748 exec.cpp:304] Executor::launchTask took 83396ns I1203 20:10:00.819756 28748 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.820089 28739 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from executor(48)@172.17.0.2:59124 I1203 20:10:00.820438 28744 slave.cpp:5042] Launching executor executor-2 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_n1w6Ys/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S1/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-2/runs/3c97cfde-c03d-4a2d-86b2-c5bdae8fb5b4' I1203 20:10:00.820525 28747 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.820570 28747 status_update_manager.cpp:497] Creating StatusUpdate stream for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.821131 28747 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to the slave I1203 20:10:00.821723 28739 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to [email protected]:59124 I1203 20:10:00.822033 28739 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.822080 28739 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to executor(48)@172.17.0.2:59124 I1203 20:10:00.822180 28752 master.cpp:4572] Status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host) I1203 20:10:00.822254 28752 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.822221 28739 exec.cpp:341] Executor received status update acknowledgement af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.822716 28752 master.cpp:6224] Updating the state of task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1203 20:10:00.824349 28744 exec.cpp:134] Version: 0.27.0 I1203 20:10:00.824450 28737 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:59124 I1203 20:10:00.824957 28748 exec.cpp:184] Executor started at: executor(49)@172.17.0.2:59124 with pid 28719 I1203 20:10:00.826544 28744 slave.cpp:1629] Queuing task '36765ec8-b7fd-41b0-84f1-ec2b5d40eeff' for executor 'executor-2' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.827070 28744 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_n1w6Ys/slaves/52a08176-53ce-4791-8efa-0de027849fe4-S1/frameworks/52a08176-53ce-4791-8efa-0de027849fe4-0000/executors/executor-2/runs/3c97cfde-c03d-4a2d-86b2-c5bdae8fb5b4' I1203 20:10:00.827754 28744 slave.cpp:2406] Got registration for executor 'executor-2' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from executor(49)@172.17.0.2:59124 I1203 20:10:00.828375 28741 exec.cpp:208] Executor registered on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 I1203 20:10:00.828433 28741 exec.cpp:220] Executor::registered took 38830ns I1203 20:10:00.828918 28744 slave.cpp:1794] Sending queued task '36765ec8-b7fd-41b0-84f1-ec2b5d40eeff' to executor 'executor-2' of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 at executor(49)@172.17.0.2:59124 I1203 20:10:00.829298 28741 exec.cpp:295] Executor asked to run task '36765ec8-b7fd-41b0-84f1-ec2b5d40eeff' I1203 20:10:00.829449 28741 exec.cpp:304] Executor::launchTask took 92216ns I1203 20:10:00.829555 28741 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.829869 28744 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from executor(49)@172.17.0.2:59124 I1203 20:10:00.830302 28741 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.830479 28741 status_update_manager.cpp:497] Creating StatusUpdate stream for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.830981 28741 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to the slave I1203 20:10:00.831329 28752 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to [email protected]:59124 I1203 20:10:00.831640 28752 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.831684 28752 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 to executor(49)@172.17.0.2:59124 I1203 20:10:00.831851 28752 exec.cpp:341] Executor received status update acknowledgement a0bbdee1-7f66-424c-9b16-2078f5062435 for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.832002 28746 master.cpp:4572] Status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 from slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) I1203 20:10:00.832094 28746 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.832568 28746 master.cpp:6224] Updating the state of task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1203 20:10:00.834748 28746 scheduler.cpp:457] Enqueuing event UPDATE received from [email protected]:59124 I1203 20:10:00.851130 28746 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:59124 I1203 20:10:00.855182 28737 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.855275 28737 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39028 I1203 20:10:00.855458 28737 master.cpp:3729] Processing ACKNOWLEDGE call af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 I1203 20:10:00.856176 28737 status_update_manager.cpp:392] Received status update acknowledgement (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.856642 28737 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: af9f08a2-9ecd-46ad-8fda-b9c78c7cfbbf) for task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.861563 28742 scheduler.cpp:298] Sending ACKNOWLEDGE call to [email protected]:59124 I1203 20:10:00.864126 28749 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.864223 28749 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39029 I1203 20:10:00.864429 28749 master.cpp:3729] Processing ACKNOWLEDGE call a0bbdee1-7f66-424c-9b16-2078f5062435 for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 I1203 20:10:00.865002 28746 status_update_manager.cpp:392] Received status update acknowledgement (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.865470 28746 slave.cpp:2346] Status update manager successfully handled status update acknowledgement (UUID: a0bbdee1-7f66-424c-9b16-2078f5062435) for task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:00.868350 28746 scheduler.cpp:298] Sending DECLINE call to [email protected]:59124 I1203 20:10:00.873241 28738 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.873482 28738 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39030 I1203 20:10:00.873708 28738 master.cpp:3455] Processing DECLINE call for offers: [ 52a08176-53ce-4791-8efa-0de027849fe4-O3 ] for framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 (default) I1203 20:10:00.875504 28740 hierarchical.cpp:1218] No resources available to allocate! I1203 20:10:00.875813 28740 hierarchical.cpp:957] Performed allocation for 2 slaves in 663859ns I1203 20:10:00.876058 28740 hierarchical.cpp:699] Framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 filtered inverse offers from slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 for 101secs I1203 20:10:00.879215 28740 scheduler.cpp:298] Sending ACCEPT call to [email protected]:59124 I1203 20:10:00.886118 28752 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1203 20:10:00.886205 28752 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39031 W1203 20:10:00.886754 28752 master.cpp:2973] ACCEPT call used invalid offers '[ 52a08176-53ce-4791-8efa-0de027849fe4-O2 ]': Offer 52a08176-53ce-4791-8efa-0de027849fe4-O2 is no longer valid 2015-12-03 20:10:01,003:28719(0x2ba56a388700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46469] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 20:10:04,339:28719(0x2ba56a388700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46469] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 20:10:07,675:28719(0x2ba56a388700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46469] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 20:10:11,012:28719(0x2ba56a388700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46469] zk retcode=-4, errno=111(Connection refused): server refused to accept the client 2015-12-03 20:10:14,348:28719(0x2ba56a388700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:46469] 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 20:10:15.898735 28744 master.cpp:938] Master terminating W1203 20:10:15.898943 28744 master.cpp:6276] Removing task 36765ec8-b7fd-41b0-84f1-ec2b5d40eeff with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) in non-terminal state TASK_RUNNING I1203 20:10:15.899755 28744 master.cpp:6319] Removing executor 'executor-2' with resources of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 on slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 at slave(112)@172.17.0.2:59124 (maintenance-host-2) I1203 20:10:15.900296 28746 hierarchical.cpp:410] Removed slave 52a08176-53ce-4791-8efa-0de027849fe4-S1 W1203 20:10:15.901098 28744 master.cpp:6276] Removing task e18bc6c4-6d88-4e0d-9860-01ff47f587d4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host) in non-terminal state TASK_RUNNING I1203 20:10:15.901665 28744 master.cpp:6319] Removing executor 'executor-1' with resources of framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 on slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 at slave(111)@172.17.0.2:59124 (maintenance-host) I1203 20:10:15.902236 28746 hierarchical.cpp:410] Removed slave 52a08176-53ce-4791-8efa-0de027849fe4-S0 I1203 20:10:15.902868 28745 hierarchical.cpp:260] Removed framework 52a08176-53ce-4791-8efa-0de027849fe4-0000 I1203 20:10:15.903507 28744 slave.cpp:3243] [email protected]:59124 exited W1203 20:10:15.903530 28744 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected I1203 20:10:15.903555 28744 slave.cpp:3243] [email protected]:59124 exited W1203 20:10:15.903568 28744 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected *** Aborted at 1449173415 (unix time) try "date -d @1449173415" if you are using GNU date *** PC: @ 0x2ba4bb7fa5b1 (unknown) *** SIGSEGV (@0x2ba4bb7fa5b1) received by PID 28719 (TID 0x2ba4b6de7940) from PID 18446744072560289201; stack trace: *** @ 0x2ba4e47d20b7 os::Linux::chained_handler() @ 0x2ba4e47d6219 JVM_handle_linux_signal @ 0x2ba4bc448340 (unknown) @ 0x2ba4bb7fa5b1 (unknown) make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[4]: *** [check-local] Segmentation fault make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[3]: *** [check-am] Error 2 make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src' make[2]: *** [check] Error 2 make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.27.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1449171430-18182 Untagged: mesos-1449171430-18182:latest Deleted: de4629b16b2319cfd05c00c18f0690a30fa82911f5f7575eb34d4324fe6982c3 Deleted: 8b034fe92a9b8cd9a087d54cd92dc0535ad5876bcf6ba70f8a43b853a5d3fd4d Deleted: 32dab50ccc1c22635e4e3481f46ac162e365dcdbe4ff839cd377ed69ab5351c3 Deleted: 521436f040c936aba68762c511d1be6eee9c6fd51273bd71fa8c27f0a00baf2b Deleted: 788fd89d925db7c214dd8276d3541bbc855644abfd0f5a7570ae1f8df2eff767 Deleted: 415f94466c7ffbca97ab1e1ea5ef7ca69150e0aea3e7adef7260be3a4efd3409 Deleted: 2058350a6df239c0165b8a2373bb00343bedda23cb0fa9b836adf14460c47354 Deleted: fa38df726c2b2378bc10523e839d3c57cd1d39cb749f8da0355fb38ebd87de3d Deleted: a4dd9b0cb3c1a1baa68627b88a7f54b08e3124c617cb0383cffbda31e529bf38 Deleted: 1fd057cf47125b27664f6e963119995bcfb1990cae066998b6d1a23e3ce28430 Deleted: 552b252b913de40ea860b3ce136b71f482cfa4fde89c9cb406a578761984c2a9 Deleted: 68aa6732241f74ec05f4ef58845064f4d588d319e6801fb1f28fd320f6aeb638 Deleted: a3c17287e0f919f2a8eb4be6259f71b48123cfd7a16d8deebde0372611b7d058 Build step 'Execute shell' marked build as failure
