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/03 21:10:15 UTC
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1317
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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2:59124
I1203 20:10:00.754590 28737 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2:59124
I1203 20:10:00.769433 28748 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2:59124
I1203 20:10:00.851130 28746 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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] master@172.17.0.2: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] master@172.17.0.2: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
Jenkins build is back to normal : Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1318
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1318/changes>
Re: Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1317
Posted by Benjamin Mahler <be...@gmail.com>.
https://issues.apache.org/jira/browse/MESOS-4059
Sorry Joseph on my last email I didn't see your ticket. Would be great to
reply to all build failures for a particular test because it makes it clear
when a build failure is being handled.
On Thu, Dec 3, 2015 at 12:10 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> 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 master@172.17.0.2: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
> master@172.17.0.2: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
> master@172.17.0.2:59124
> I1203 20:10:00.754590 28737 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2:59124
> I1203 20:10:00.769433 28748 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.2: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
> master@172.17.0.2: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
> master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2: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 master@172.17.0.2:59124
> I1203 20:10:00.851130 28746 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.2: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
> master@172.17.0.2: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
> master@172.17.0.2: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
> master@172.17.0.2: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] master@172.17.0.2: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] master@172.17.0.2: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
>