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 19:25:19 UTC
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1315
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1315/changes>
Changes:
[bernd] Cleanup a leaked reference to a test process living in the stack.
[bernd] Added documentation about terminate/wait in Processes destructors.
[bernd] Fixed test flakiness in FetcherTest and FetcherCacheTest.
[toenshoff] Made unary c-tors for `Unauthorized` response explicit.
[joris.van.remoortere] Fixed flakey test: MasterMaintenanceTest.InverseOffersFilters.
------------------------------------------
[...truncated 66992 lines...]
I1203 18:25:04.446012 28738 log.cpp:702] Attempting to truncate the log to 5
I1203 18:25:04.446660 28738 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6
I1203 18:25:04.447217 28744 master.cpp:4085] Registered slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1203 18:25:04.447616 28738 hierarchical.cpp:380] Added slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
I1203 18:25:04.447907 28738 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.448030 28738 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.448137 28738 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 425173ns
I1203 18:25:04.448531 28732 slave.cpp:902] Registered with master master@172.17.0.2:39622; given slave ID c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
I1203 18:25:04.448559 28732 fetcher.cpp:79] Clearing fetcher cache
I1203 18:25:04.448976 28744 status_update_manager.cpp:181] Resuming sending status updates
I1203 18:25:04.448978 28732 slave.cpp:925] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/meta/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/slave.info'
I1203 18:25:04.449422 28744 replica.cpp:538] Replica received write request for position 6 from (3812)@172.17.0.2:39622
I1203 18:25:04.449538 28732 slave.cpp:961] Forwarding total oversubscribed resources
I1203 18:25:04.449640 28732 slave.cpp:3197] Received ping from slave-observer(116)@172.17.0.2:39622
I1203 18:25:04.449825 28732 master.cpp:4427] Received update of slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2) with total oversubscribed resources
I1203 18:25:04.450196 28744 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 569101ns
I1203 18:25:04.450283 28744 replica.cpp:713] Persisted action at 6
I1203 18:25:04.450387 28736 hierarchical.cpp:434] Slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: )
I1203 18:25:04.450614 28736 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.450670 28736 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.450697 28736 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 272707ns
I1203 18:25:04.451419 28744 replica.cpp:692] Replica received learned notice for position 6 from @0.0.0.0:0
I1203 18:25:04.452167 28744 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 721533ns
I1203 18:25:04.452246 28744 leveldb.cpp:399] Deleting ~2 keys from leveldb took 69041ns
I1203 18:25:04.452378 28744 replica.cpp:713] Persisted action at 6
I1203 18:25:04.452502 28744 replica.cpp:698] Replica learned TRUNCATE action at position 6
I1203 18:25:04.454433 28744 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
I1203 18:25:04.454607 28744 http.cpp:336] HTTP POST for /master/maintenance/schedule from 172.17.0.2:51244
I1203 18:25:04.455843 28744 registrar.cpp:439] Applied 1 operations in 227194ns; attempting to update the 'registry'
I1203 18:25:04.457535 28737 log.cpp:683] Attempting to append 734 bytes to the log
I1203 18:25:04.457907 28737 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7
I1203 18:25:04.459543 28737 replica.cpp:538] Replica received write request for position 7 from (3813)@172.17.0.2:39622
I1203 18:25:04.460285 28737 leveldb.cpp:341] Persisting action (753 bytes) to leveldb took 542873ns
I1203 18:25:04.460388 28737 replica.cpp:713] Persisted action at 7
I1203 18:25:04.468075 28746 replica.cpp:692] Replica received learned notice for position 7 from @0.0.0.0:0
I1203 18:25:04.468783 28746 leveldb.cpp:341] Persisting action (755 bytes) to leveldb took 556908ns
I1203 18:25:04.468886 28746 replica.cpp:713] Persisted action at 7
I1203 18:25:04.469027 28746 replica.cpp:698] Replica learned APPEND action at position 7
I1203 18:25:04.472160 28739 registrar.cpp:484] Successfully updated the 'registry' in 16.000256ms
I1203 18:25:04.472617 28746 log.cpp:702] Attempting to truncate the log to 7
I1203 18:25:04.473223 28746 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8
I1203 18:25:04.474680 28733 replica.cpp:538] Replica received write request for position 8 from (3814)@172.17.0.2:39622
I1203 18:25:04.473700 28739 master.cpp:4483] Updating unavailability of slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2), starting at 2399.11012475529weeks
I1203 18:25:04.475405 28739 master.cpp:4483] Updating unavailability of slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host), starting at 2399.11012475529weeks
I1203 18:25:04.475798 28731 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.475878 28731 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.475916 28731 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 332768ns
I1203 18:25:04.476156 28731 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.476210 28731 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.476253 28731 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 in 249523ns
I1203 18:25:04.476445 28739 master.cpp:4483] Updating unavailability of slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host), starting at 2399.11012475529weeks
I1203 18:25:04.476567 28733 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.499863ms
I1203 18:25:04.476610 28733 replica.cpp:713] Persisted action at 8
I1203 18:25:04.476780 28731 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.476835 28731 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.476871 28731 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 in 250866ns
I1203 18:25:04.477419 28733 replica.cpp:692] Replica received learned notice for position 8 from @0.0.0.0:0
I1203 18:25:04.477540 28739 master.cpp:4483] Updating unavailability of slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2), starting at 2399.11012475529weeks
I1203 18:25:04.477749 28733 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 312165ns
I1203 18:25:04.477813 28733 leveldb.cpp:399] Deleting ~2 keys from leveldb took 48215ns
I1203 18:25:04.477834 28733 replica.cpp:713] Persisted action at 8
I1203 18:25:04.477874 28733 replica.cpp:698] Replica learned TRUNCATE action at position 8
I1203 18:25:04.478059 28745 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.478113 28745 hierarchical.cpp:1155] No inverse offers to send out!
I1203 18:25:04.478139 28745 hierarchical.cpp:973] Performed allocation for slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 282601ns
I1203 18:25:04.485801 28713 scheduler.cpp:154] Version: 0.27.0
I1203 18:25:04.486544 28731 scheduler.cpp:236] New master detected at master@172.17.0.2:39622
I1203 18:25:04.497105 28742 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:39622
I1203 18:25:04.500171 28742 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.500269 28742 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51245
I1203 18:25:04.500524 28742 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1203 18:25:04.500558 28742 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1203 18:25:04.501369 28742 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1203 18:25:04.502502 28742 hierarchical.cpp:220] Added framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.504004 28732 master.hpp:1583] Sending heartbeat to c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.505080 28746 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:39622
I1203 18:25:04.505374 28736 master.cpp:5137] Sending 2 offers to framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
I1203 18:25:04.505616 28742 hierarchical.cpp:957] Performed allocation for 2 slaves in 3.096168ms
I1203 18:25:04.506080 28737 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:39622
I1203 18:25:04.506461 28737 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1203 18:25:04.507187 28736 master.cpp:5227] Sending 2 inverse offers to framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
I1203 18:25:04.510395 28734 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.2:39622
I1203 18:25:04.511852 28736 scheduler.cpp:457] Enqueuing event OFFERS received from master@172.17.0.2:39622
I1203 18:25:04.519803 28738 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.520179 28738 hierarchical.cpp:957] Performed allocation for 2 slaves in 1.155669ms
I1203 18:25:04.529461 28741 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.2:39622
I1203 18:25:04.532944 28738 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.533030 28738 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51246
I1203 18:25:04.533910 28738 master.cpp:3010] Processing ACCEPT call for offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O0 ] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host) for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
I1203 18:25:04.533983 28738 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task 26305fdd-edb0-4764-8b8a-2558f2b2d81b as user 'mesos'
W1203 18:25:04.536110 28738 validation.cpp:420] Executor executor-1 for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1203 18:25:04.536183 28738 validation.cpp:432] Executor executor-1 for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1203 18:25:04.536908 28746 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.2:39622
I1203 18:25:04.537322 28738 master.hpp:176] Adding task 26305fdd-edb0-4764-8b8a-2558f2b2d81b with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 (maintenance-host)
I1203 18:25:04.537531 28738 master.cpp:3403] Launching task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host)
I1203 18:25:04.538158 28746 slave.cpp:1292] Got assigned task 26305fdd-edb0-4764-8b8a-2558f2b2d81b for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.538915 28746 slave.cpp:1411] Launching task 26305fdd-edb0-4764-8b8a-2558f2b2d81b for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.539091 28740 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.539154 28740 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51247
I1203 18:25:04.539899 28740 master.cpp:3010] Processing ACCEPT call for offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O1 ] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2) for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
I1203 18:25:04.539959 28740 master.cpp:2742] Authorizing framework principal 'test-principal' to launch task cafa992b-2be6-49fc-b727-bb3d55f1511d as user 'mesos'
W1203 18:25:04.541446 28731 validation.cpp:420] Executor executor-2 for task cafa992b-2be6-49fc-b727-bb3d55f1511d uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W1203 18:25:04.541494 28731 validation.cpp:432] Executor executor-2 for task cafa992b-2be6-49fc-b727-bb3d55f1511d uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I1203 18:25:04.541916 28731 master.hpp:176] Adding task cafa992b-2be6-49fc-b727-bb3d55f1511d with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2)
I1203 18:25:04.542186 28731 master.cpp:3403] Launching task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2)
I1203 18:25:04.542621 28740 slave.cpp:1292] Got assigned task cafa992b-2be6-49fc-b727-bb3d55f1511d for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.543220 28740 slave.cpp:1411] Launching task cafa992b-2be6-49fc-b727-bb3d55f1511d for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.559017 28746 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a' to user 'mesos'
I1203 18:25:04.562865 28740 paths.cpp:434] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c' to user 'mesos'
I1203 18:25:04.571883 28746 slave.cpp:5042] Launching executor executor-1 of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a'
I1203 18:25:04.571960 28740 slave.cpp:5042] Launching executor executor-2 of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c'
I1203 18:25:04.574251 28740 exec.cpp:134] Version: 0.27.0
I1203 18:25:04.574630 28732 exec.cpp:184] Executor started at: executor(48)@172.17.0.2:39622 with pid 28713
I1203 18:25:04.574880 28740 slave.cpp:1629] Queuing task 'cafa992b-2be6-49fc-b727-bb3d55f1511d' for executor 'executor-2' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.575081 28740 slave.cpp:2406] Got registration for executor 'executor-2' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(48)@172.17.0.2:39622
I1203 18:25:04.576000 28740 slave.cpp:1794] Sending queued task 'cafa992b-2be6-49fc-b727-bb3d55f1511d' to executor 'executor-2' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 at executor(48)@172.17.0.2:39622
I1203 18:25:04.576120 28746 exec.cpp:134] Version: 0.27.0
I1203 18:25:04.576207 28745 exec.cpp:208] Executor registered on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
I1203 18:25:04.576243 28731 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c'
I1203 18:25:04.576272 28731 exec.cpp:184] Executor started at: executor(49)@172.17.0.2:39622 with pid 28713
I1203 18:25:04.576289 28745 exec.cpp:220] Executor::registered took 42155ns
I1203 18:25:04.576496 28746 slave.cpp:1629] Queuing task '26305fdd-edb0-4764-8b8a-2558f2b2d81b' for executor 'executor-1' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.576531 28745 exec.cpp:295] Executor asked to run task 'cafa992b-2be6-49fc-b727-bb3d55f1511d'
I1203 18:25:04.576599 28746 slave.cpp:680] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a'
I1203 18:25:04.576653 28745 exec.cpp:304] Executor::launchTask took 110839ns
I1203 18:25:04.576653 28746 slave.cpp:2406] Got registration for executor 'executor-1' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(49)@172.17.0.2:39622
I1203 18:25:04.576758 28745 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.577268 28745 exec.cpp:208] Executor registered on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
I1203 18:25:04.577370 28745 exec.cpp:220] Executor::registered took 36175ns
I1203 18:25:04.577371 28731 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(48)@172.17.0.2:39622
I1203 18:25:04.577510 28746 slave.cpp:1794] Sending queued task '26305fdd-edb0-4764-8b8a-2558f2b2d81b' to executor 'executor-1' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 at executor(49)@172.17.0.2:39622
I1203 18:25:04.577916 28746 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.578044 28746 status_update_manager.cpp:497] Creating StatusUpdate stream for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.578086 28731 exec.cpp:295] Executor asked to run task '26305fdd-edb0-4764-8b8a-2558f2b2d81b'
I1203 18:25:04.578284 28731 exec.cpp:304] Executor::launchTask took 69408ns
I1203 18:25:04.578366 28731 exec.cpp:517] Executor sending status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.578517 28746 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to the slave
I1203 18:25:04.578568 28731 slave.cpp:2763] Handling status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(49)@172.17.0.2:39622
I1203 18:25:04.578970 28731 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.579010 28731 status_update_manager.cpp:497] Creating StatusUpdate stream for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.579059 28737 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to master@172.17.0.2:39622
I1203 18:25:04.579345 28731 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to the slave
I1203 18:25:04.579344 28737 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.579468 28737 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to executor(48)@172.17.0.2:39622
I1203 18:25:04.579728 28731 slave.cpp:3115] Forwarding the update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to master@172.17.0.2:39622
I1203 18:25:04.579774 28737 master.cpp:4572] Status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2)
I1203 18:25:04.579819 28737 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.579879 28731 slave.cpp:3009] Status update manager successfully handled status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.580005 28740 exec.cpp:341] Executor received status update acknowledgement 6afd012e-8e88-41b2-8239-a9b852d07ca1 for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.579922 28731 slave.cpp:3025] Sending acknowledgement for status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to executor(49)@172.17.0.2:39622
I1203 18:25:04.580652 28737 master.cpp:6224] Updating the state of task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1203 18:25:04.581056 28737 master.cpp:4572] Status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host)
I1203 18:25:04.581106 28737 master.cpp:4620] Forwarding status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.580662 28734 exec.cpp:341] Executor received status update acknowledgement 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6 for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:04.581707 28737 master.cpp:6224] Updating the state of task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1203 18:25:04.582212 28737 scheduler.cpp:457] Enqueuing event UPDATE received from master@172.17.0.2:39622
I1203 18:25:04.585041 28745 scheduler.cpp:457] Enqueuing event UPDATE received from master@172.17.0.2:39622
I1203 18:25:04.605078 28745 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.2:39622
I1203 18:25:04.609073 28739 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.609181 28739 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51248
I1203 18:25:04.609382 28739 master.cpp:3729] Processing ACKNOWLEDGE call 6afd012e-8e88-41b2-8239-a9b852d07ca1 for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
I1203 18:25:04.609817 28732 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
W1203 18:25:04.610076 28732 status_update_manager.cpp:762] Unexpected status update acknowledgement (received 6afd012e-8e88-41b2-8239-a9b852d07ca1, expecting 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
E1203 18:25:04.610339 28736 slave.cpp:2339] Failed to handle status update acknowledgement (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000: Duplicate acknowledgement
I1203 18:25:04.614711 28733 scheduler.cpp:298] Sending ACKNOWLEDGE call to master@172.17.0.2:39622
I1203 18:25:04.618331 28736 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.618434 28736 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51249
I1203 18:25:04.618621 28736 master.cpp:3729] Processing ACKNOWLEDGE call 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6 for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
I1203 18:25:04.619472 28736 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
W1203 18:25:04.619717 28736 status_update_manager.cpp:762] Unexpected status update acknowledgement (received 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6, expecting 6afd012e-8e88-41b2-8239-a9b852d07ca1) for update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
E1203 18:25:04.620014 28736 slave.cpp:2339] Failed to handle status update acknowledgement (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000: Duplicate acknowledgement
I1203 18:25:04.621562 28736 scheduler.cpp:298] Sending DECLINE call to master@172.17.0.2:39622
I1203 18:25:04.626117 28735 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.626325 28735 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51250
I1203 18:25:04.626641 28735 master.cpp:3455] Processing DECLINE call for offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O3 ] for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
I1203 18:25:04.627543 28742 hierarchical.cpp:1062] No resources available to allocate!
I1203 18:25:04.627910 28742 hierarchical.cpp:957] Performed allocation for 2 slaves in 2.470458ms
I1203 18:25:04.628186 28742 hierarchical.cpp:699] Framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 filtered inverse offers from slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 for 101secs
I1203 18:25:04.628924 28744 scheduler.cpp:298] Sending ACCEPT call to master@172.17.0.2:39622
I1203 18:25:04.631536 28734 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1203 18:25:04.631605 28734 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:51251
W1203 18:25:04.632104 28734 master.cpp:2973] ACCEPT call used invalid offers '[ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O2 ]': Offer c7900911-cc7a-4dde-92e7-48fe82cddd9e-O2 is no longer valid
2015-12-03 18:25:04,893:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
2015-12-03 18:25:08,229:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
2015-12-03 18:25:11,565:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
2015-12-03 18:25:14,901:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
2015-12-03 18:25:18,235:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
../../src/tests/master_maintenance_tests.cpp:1559: Failure
Failed to wait 15secs for event
I1203 18:25:19.639402 28740 master.cpp:938] Master terminating
W1203 18:25:19.639631 28740 master.cpp:6276] Removing task cafa992b-2be6-49fc-b727-bb3d55f1511d with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2) in non-terminal state TASK_RUNNING
I1203 18:25:19.640571 28740 master.cpp:6319] Removing executor 'executor-2' with resources of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622 (maintenance-host-2)
W1203 18:25:19.641091 28740 master.cpp:6276] Removing task 26305fdd-edb0-4764-8b8a-2558f2b2d81b with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host) in non-terminal state TASK_RUNNING
I1203 18:25:19.641700 28740 master.cpp:6319] Removing executor 'executor-1' with resources of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622 (maintenance-host)
I1203 18:25:19.643367 28733 hierarchical.cpp:410] Removed slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
I1203 18:25:19.644150 28733 hierarchical.cpp:410] Removed slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
I1203 18:25:19.644635 28733 hierarchical.cpp:260] Removed framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
I1203 18:25:19.645395 28739 slave.cpp:3243] master@172.17.0.2:39622 exited
W1203 18:25:19.646028 28739 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected
I1203 18:25:19.644919 28740 slave.cpp:3243] master@172.17.0.2:39622 exited
W1203 18:25:19.647006 28740 slave.cpp:3246] Master disconnected! Waiting for a new master to be elected
*** Aborted at 1449167119 (unix time) try "date -d @1449167119" if you are using GNU date ***
PC: @ 0x8c31fe mesos::internal::tests::Cluster::Slaves::shutdown()
*** SIGSEGV (@0x500010047) received by PID 28713 (TID 0x2ab9021fd940) from PID 65607; stack trace: ***
@ 0x2ab9138ae0b7 os::Linux::chained_handler()
@ 0x2ab9138b2219 JVM_handle_linux_signal
@ 0x2ab907859340 (unknown)
@ 0x8c31fe mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0xf23fdb mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0xf23f75 mesos::internal::tests::MesosTest::Shutdown()
@ 0xf1f8c7 mesos::internal::tests::MesosTest::TearDown()
@ 0x1662d53 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x1650621 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x163117a testing::Test::Run()
@ 0x1631dab testing::TestInfo::Run()
@ 0x16324f7 testing::TestCase::Run()
@ 0x1639c52 testing::internal::UnitTestImpl::RunAllTests()
@ 0x1660513 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x1652641 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x163990b testing::UnitTest::Run()
@ 0xca5141 RUN_ALL_TESTS()
@ 0xca3fe6 main
@ 0x2ab907a88ec5 (unknown)
@ 0x7e703c (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]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1449165127-27928
Untagged: mesos-1449165127-27928:latest
Deleted: 68398f9a0db7903300dc04219d37e2f1311403ec5bd07ca2c8cce66fa7d9b144
Deleted: 278319a9edd2a62e434a62ad98fa3b18f9811d498fd10e8974fc32ee8d345baa
Deleted: e14b8acf0a7efc2a3c0af1f5240eeab99dc3651116c6500debbe2b63904bf0b2
Deleted: 0b54a8f2cc78e4fcce0b1d72efaad9498a063c6b5de3c903ff45630e057f8c0c
Deleted: bd00cedbdc24f0dd143fcbdfac2907b9782ae4de069faa8d5d1e2989c9eb7488
Deleted: dd1b5d5a8e55241acb46bef8580e3b67d39f494633f2e2a680fc42de4f6b018c
Deleted: f641a9d42f53729b85eec03ced3062ee9f72f46d996beeb433d6151daea3d6df
Deleted: b84d74f5e45b228074e6d72f4bbdf63c1d8cf398341cf0ccaafe8c561332182f
Deleted: 7ac9ba7138ad17281e99ef4c4411d577b6c9c3e34290039d204f733dc139ab78
Deleted: 97886a6833a78e4666d2bded68b5898489fee41901b37091c1556d29f434fca8
Deleted: 9e8f93adf5df5ca1eb63366a2b8c5a939d6ff25072ebf33622f91aef8e4a9fa6
Deleted: 68549af3aca27bc40f40d00732b3e83df14c7e4117e4224beb1aebcf7a00f6ab
Deleted: 97ee89720e239d38c7e4eefead19309c5ced22d7ac8584cb993a045b3a9ef674
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1315
Posted by Joseph Wu <jo...@mesosphere.io>.
This specific flakiness is tracked here:
https://issues.apache.org/jira/browse/MESOS-4059
On Thu, Dec 3, 2015 at 10:25 AM, 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/1315/changes
> >
>
> Changes:
>
> [bernd] Cleanup a leaked reference to a test process living in the stack.
>
> [bernd] Added documentation about terminate/wait in Processes destructors.
>
> [bernd] Fixed test flakiness in FetcherTest and FetcherCacheTest.
>
> [toenshoff] Made unary c-tors for `Unauthorized` response explicit.
>
> [joris.van.remoortere] Fixed flakey test:
> MasterMaintenanceTest.InverseOffersFilters.
>
> ------------------------------------------
> [...truncated 66992 lines...]
> I1203 18:25:04.446012 28738 log.cpp:702] Attempting to truncate the log to
> 5
> I1203 18:25:04.446660 28738 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 6
> I1203 18:25:04.447217 28744 master.cpp:4085] Registered slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000]
> I1203 18:25:04.447616 28738 hierarchical.cpp:380] Added slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2) with
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: )
> I1203 18:25:04.447907 28738 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.448030 28738 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.448137 28738 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 425173ns
> I1203 18:25:04.448531 28732 slave.cpp:902] Registered with master
> master@172.17.0.2:39622; given slave ID
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
> I1203 18:25:04.448559 28732 fetcher.cpp:79] Clearing fetcher cache
> I1203 18:25:04.448976 28744 status_update_manager.cpp:181] Resuming
> sending status updates
> I1203 18:25:04.448978 28732 slave.cpp:925] Checkpointing SlaveInfo to
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/meta/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/
> slave.info'
> I1203 18:25:04.449422 28744 replica.cpp:538] Replica received write
> request for position 6 from (3812)@172.17.0.2:39622
> I1203 18:25:04.449538 28732 slave.cpp:961] Forwarding total oversubscribed
> resources
> I1203 18:25:04.449640 28732 slave.cpp:3197] Received ping from
> slave-observer(116)@172.17.0.2:39622
> I1203 18:25:04.449825 28732 master.cpp:4427] Received update of slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2) with total oversubscribed resources
> I1203 18:25:04.450196 28744 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 569101ns
> I1203 18:25:04.450283 28744 replica.cpp:713] Persisted action at 6
> I1203 18:25:04.450387 28736 hierarchical.cpp:434] Slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2) updated with
> oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024;
> ports(*):[31000-32000], allocated: )
> I1203 18:25:04.450614 28736 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.450670 28736 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.450697 28736 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 272707ns
> I1203 18:25:04.451419 28744 replica.cpp:692] Replica received learned
> notice for position 6 from @0.0.0.0:0
> I1203 18:25:04.452167 28744 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 721533ns
> I1203 18:25:04.452246 28744 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 69041ns
> I1203 18:25:04.452378 28744 replica.cpp:713] Persisted action at 6
> I1203 18:25:04.452502 28744 replica.cpp:698] Replica learned TRUNCATE
> action at position 6
> I1203 18:25:04.454433 28744 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/maintenance/schedule'
> I1203 18:25:04.454607 28744 http.cpp:336] HTTP POST for
> /master/maintenance/schedule from 172.17.0.2:51244
> I1203 18:25:04.455843 28744 registrar.cpp:439] Applied 1 operations in
> 227194ns; attempting to update the 'registry'
> I1203 18:25:04.457535 28737 log.cpp:683] Attempting to append 734 bytes to
> the log
> I1203 18:25:04.457907 28737 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 7
> I1203 18:25:04.459543 28737 replica.cpp:538] Replica received write
> request for position 7 from (3813)@172.17.0.2:39622
> I1203 18:25:04.460285 28737 leveldb.cpp:341] Persisting action (753 bytes)
> to leveldb took 542873ns
> I1203 18:25:04.460388 28737 replica.cpp:713] Persisted action at 7
> I1203 18:25:04.468075 28746 replica.cpp:692] Replica received learned
> notice for position 7 from @0.0.0.0:0
> I1203 18:25:04.468783 28746 leveldb.cpp:341] Persisting action (755 bytes)
> to leveldb took 556908ns
> I1203 18:25:04.468886 28746 replica.cpp:713] Persisted action at 7
> I1203 18:25:04.469027 28746 replica.cpp:698] Replica learned APPEND action
> at position 7
> I1203 18:25:04.472160 28739 registrar.cpp:484] Successfully updated the
> 'registry' in 16.000256ms
> I1203 18:25:04.472617 28746 log.cpp:702] Attempting to truncate the log to
> 7
> I1203 18:25:04.473223 28746 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 8
> I1203 18:25:04.474680 28733 replica.cpp:538] Replica received write
> request for position 8 from (3814)@172.17.0.2:39622
> I1203 18:25:04.473700 28739 master.cpp:4483] Updating unavailability of
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@
> 172.17.0.2:39622 (maintenance-host-2), starting at 2399.11012475529weeks
> I1203 18:25:04.475405 28739 master.cpp:4483] Updating unavailability of
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@
> 172.17.0.2:39622 (maintenance-host), starting at 2399.11012475529weeks
> I1203 18:25:04.475798 28731 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.475878 28731 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.475916 28731 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 332768ns
> I1203 18:25:04.476156 28731 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.476210 28731 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.476253 28731 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 in 249523ns
> I1203 18:25:04.476445 28739 master.cpp:4483] Updating unavailability of
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@
> 172.17.0.2:39622 (maintenance-host), starting at 2399.11012475529weeks
> I1203 18:25:04.476567 28733 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 1.499863ms
> I1203 18:25:04.476610 28733 replica.cpp:713] Persisted action at 8
> I1203 18:25:04.476780 28731 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.476835 28731 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.476871 28731 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 in 250866ns
> I1203 18:25:04.477419 28733 replica.cpp:692] Replica received learned
> notice for position 8 from @0.0.0.0:0
> I1203 18:25:04.477540 28739 master.cpp:4483] Updating unavailability of
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@
> 172.17.0.2:39622 (maintenance-host-2), starting at 2399.11012475529weeks
> I1203 18:25:04.477749 28733 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 312165ns
> I1203 18:25:04.477813 28733 leveldb.cpp:399] Deleting ~2 keys from leveldb
> took 48215ns
> I1203 18:25:04.477834 28733 replica.cpp:713] Persisted action at 8
> I1203 18:25:04.477874 28733 replica.cpp:698] Replica learned TRUNCATE
> action at position 8
> I1203 18:25:04.478059 28745 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.478113 28745 hierarchical.cpp:1155] No inverse offers to
> send out!
> I1203 18:25:04.478139 28745 hierarchical.cpp:973] Performed allocation for
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 in 282601ns
> I1203 18:25:04.485801 28713 scheduler.cpp:154] Version: 0.27.0
> I1203 18:25:04.486544 28731 scheduler.cpp:236] New master detected at
> master@172.17.0.2:39622
> I1203 18:25:04.497105 28742 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.2:39622
> I1203 18:25:04.500171 28742 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.500269 28742 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51245
> I1203 18:25:04.500524 28742 master.cpp:1899] Received subscription request
> for HTTP framework 'default'
> I1203 18:25:04.500558 28742 master.cpp:1676] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1203 18:25:04.501369 28742 master.cpp:1991] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1203 18:25:04.502502 28742 hierarchical.cpp:220] Added framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.504004 28732 master.hpp:1583] Sending heartbeat to
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.505080 28746 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.2:39622
> I1203 18:25:04.505374 28736 master.cpp:5137] Sending 2 offers to framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
> I1203 18:25:04.505616 28742 hierarchical.cpp:957] Performed allocation for
> 2 slaves in 3.096168ms
> I1203 18:25:04.506080 28737 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.2:39622
> I1203 18:25:04.506461 28737 master_maintenance_tests.cpp:172] Ignoring
> HEARTBEAT event
> I1203 18:25:04.507187 28736 master.cpp:5227] Sending 2 inverse offers to
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
> I1203 18:25:04.510395 28734 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.2:39622
> I1203 18:25:04.511852 28736 scheduler.cpp:457] Enqueuing event OFFERS
> received from master@172.17.0.2:39622
> I1203 18:25:04.519803 28738 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.520179 28738 hierarchical.cpp:957] Performed allocation for
> 2 slaves in 1.155669ms
> I1203 18:25:04.529461 28741 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.2:39622
> I1203 18:25:04.532944 28738 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.533030 28738 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51246
> I1203 18:25:04.533910 28738 master.cpp:3010] Processing ACCEPT call for
> offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O0 ] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622
> (maintenance-host) for framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> (default)
> I1203 18:25:04.533983 28738 master.cpp:2742] Authorizing framework
> principal 'test-principal' to launch task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b as user 'mesos'
> W1203 18:25:04.536110 28738 validation.cpp:420] Executor executor-1 for
> task 26305fdd-edb0-4764-8b8a-2558f2b2d81b uses less CPUs (None) than the
> minimum required (0.01). Please update your executor, as this will be
> mandatory in future releases.
> W1203 18:25:04.536183 28738 validation.cpp:432] Executor executor-1 for
> task 26305fdd-edb0-4764-8b8a-2558f2b2d81b uses less memory (None) than the
> minimum required (32MB). Please update your executor, as this will be
> mandatory in future releases.
> I1203 18:25:04.536908 28746 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.2:39622
> I1203 18:25:04.537322 28738 master.hpp:176] Adding task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 (maintenance-host)
> I1203 18:25:04.537531 28738 master.cpp:3403] Launching task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) with resources
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622
> (maintenance-host)
> I1203 18:25:04.538158 28746 slave.cpp:1292] Got assigned task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.538915 28746 slave.cpp:1411] Launching task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.539091 28740 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.539154 28740 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51247
> I1203 18:25:04.539899 28740 master.cpp:3010] Processing ACCEPT call for
> offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O1 ] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2) for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
> I1203 18:25:04.539959 28740 master.cpp:2742] Authorizing framework
> principal 'test-principal' to launch task
> cafa992b-2be6-49fc-b727-bb3d55f1511d as user 'mesos'
> W1203 18:25:04.541446 28731 validation.cpp:420] Executor executor-2 for
> task cafa992b-2be6-49fc-b727-bb3d55f1511d uses less CPUs (None) than the
> minimum required (0.01). Please update your executor, as this will be
> mandatory in future releases.
> W1203 18:25:04.541494 28731 validation.cpp:432] Executor executor-2 for
> task cafa992b-2be6-49fc-b727-bb3d55f1511d uses less memory (None) than the
> minimum required (32MB). Please update your executor, as this will be
> mandatory in future releases.
> I1203 18:25:04.541916 28731 master.hpp:176] Adding task
> cafa992b-2be6-49fc-b727-bb3d55f1511d with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 (maintenance-host-2)
> I1203 18:25:04.542186 28731 master.cpp:3403] Launching task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) with resources
> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2)
> I1203 18:25:04.542621 28740 slave.cpp:1292] Got assigned task
> cafa992b-2be6-49fc-b727-bb3d55f1511d for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.543220 28740 slave.cpp:1411] Launching task
> cafa992b-2be6-49fc-b727-bb3d55f1511d for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.559017 28746 paths.cpp:434] Trying to chown
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a'
> to user 'mesos'
> I1203 18:25:04.562865 28740 paths.cpp:434] Trying to chown
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c'
> to user 'mesos'
> I1203 18:25:04.571883 28746 slave.cpp:5042] Launching executor executor-1
> of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 with resources in
> work directory
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a'
> I1203 18:25:04.571960 28740 slave.cpp:5042] Launching executor executor-2
> of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 with resources in
> work directory
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c'
> I1203 18:25:04.574251 28740 exec.cpp:134] Version: 0.27.0
> I1203 18:25:04.574630 28732 exec.cpp:184] Executor started at:
> executor(48)@172.17.0.2:39622 with pid 28713
> I1203 18:25:04.574880 28740 slave.cpp:1629] Queuing task
> 'cafa992b-2be6-49fc-b727-bb3d55f1511d' for executor 'executor-2' of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.575081 28740 slave.cpp:2406] Got registration for executor
> 'executor-2' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from
> executor(48)@172.17.0.2:39622
> I1203 18:25:04.576000 28740 slave.cpp:1794] Sending queued task
> 'cafa992b-2be6-49fc-b727-bb3d55f1511d' to executor 'executor-2' of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 at executor(48)@
> 172.17.0.2:39622
> I1203 18:25:04.576120 28746 exec.cpp:134] Version: 0.27.0
> I1203 18:25:04.576207 28745 exec.cpp:208] Executor registered on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
> I1203 18:25:04.576243 28731 slave.cpp:680] Successfully attached file
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_3H2fr1/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-2/runs/fdeb01b9-8c56-4508-92b6-0fe11ea9598c'
> I1203 18:25:04.576272 28731 exec.cpp:184] Executor started at:
> executor(49)@172.17.0.2:39622 with pid 28713
> I1203 18:25:04.576289 28745 exec.cpp:220] Executor::registered took 42155ns
> I1203 18:25:04.576496 28746 slave.cpp:1629] Queuing task
> '26305fdd-edb0-4764-8b8a-2558f2b2d81b' for executor 'executor-1' of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.576531 28745 exec.cpp:295] Executor asked to run task
> 'cafa992b-2be6-49fc-b727-bb3d55f1511d'
> I1203 18:25:04.576599 28746 slave.cpp:680] Successfully attached file
> '/tmp/MasterMaintenanceTest_InverseOffersFilters_QT4Wpx/slaves/c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0/frameworks/c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000/executors/executor-1/runs/ea81f571-9880-45a2-a9cc-dcf32b83b74a'
> I1203 18:25:04.576653 28745 exec.cpp:304] Executor::launchTask took
> 110839ns
> I1203 18:25:04.576653 28746 slave.cpp:2406] Got registration for executor
> 'executor-1' of framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from
> executor(49)@172.17.0.2:39622
> I1203 18:25:04.576758 28745 exec.cpp:517] Executor sending status update
> TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.577268 28745 exec.cpp:208] Executor registered on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
> I1203 18:25:04.577370 28745 exec.cpp:220] Executor::registered took 36175ns
> I1203 18:25:04.577371 28731 slave.cpp:2763] Handling status update
> TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(48)@
> 172.17.0.2:39622
> I1203 18:25:04.577510 28746 slave.cpp:1794] Sending queued task
> '26305fdd-edb0-4764-8b8a-2558f2b2d81b' to executor 'executor-1' of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 at executor(49)@
> 172.17.0.2:39622
> I1203 18:25:04.577916 28746 status_update_manager.cpp:320] Received status
> update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.578044 28746 status_update_manager.cpp:497] Creating
> StatusUpdate stream for task cafa992b-2be6-49fc-b727-bb3d55f1511d of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.578086 28731 exec.cpp:295] Executor asked to run task
> '26305fdd-edb0-4764-8b8a-2558f2b2d81b'
> I1203 18:25:04.578284 28731 exec.cpp:304] Executor::launchTask took 69408ns
> I1203 18:25:04.578366 28731 exec.cpp:517] Executor sending status update
> TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.578517 28746 status_update_manager.cpp:374] Forwarding
> update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to the slave
> I1203 18:25:04.578568 28731 slave.cpp:2763] Handling status update
> TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from executor(49)@
> 172.17.0.2:39622
> I1203 18:25:04.578970 28731 status_update_manager.cpp:320] Received status
> update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.579010 28731 status_update_manager.cpp:497] Creating
> StatusUpdate stream for task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of
> framework c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.579059 28737 slave.cpp:3115] Forwarding the update
> TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to master@172.17.0.2:39622
> I1203 18:25:04.579345 28731 status_update_manager.cpp:374] Forwarding
> update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to the slave
> I1203 18:25:04.579344 28737 slave.cpp:3009] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.579468 28737 slave.cpp:3025] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for
> task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to executor(48)@172.17.0.2:39622
> I1203 18:25:04.579728 28731 slave.cpp:3115] Forwarding the update
> TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to master@172.17.0.2:39622
> I1203 18:25:04.579774 28737 master.cpp:4572] Status update TASK_RUNNING
> (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2)
> I1203 18:25:04.579819 28737 master.cpp:4620] Forwarding status update
> TASK_RUNNING (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.579879 28731 slave.cpp:3009] Status update manager
> successfully handled status update TASK_RUNNING (UUID:
> 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.580005 28740 exec.cpp:341] Executor received status update
> acknowledgement 6afd012e-8e88-41b2-8239-a9b852d07ca1 for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.579922 28731 slave.cpp:3025] Sending acknowledgement for
> status update TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for
> task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 to executor(49)@172.17.0.2:39622
> I1203 18:25:04.580652 28737 master.cpp:6224] Updating the state of task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> I1203 18:25:04.581056 28737 master.cpp:4572] Status update TASK_RUNNING
> (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 from slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622
> (maintenance-host)
> I1203 18:25:04.581106 28737 master.cpp:4620] Forwarding status update
> TASK_RUNNING (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.580662 28734 exec.cpp:341] Executor received status update
> acknowledgement 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6 for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:04.581707 28737 master.cpp:6224] Updating the state of task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (latest state: TASK_RUNNING,
> status update state: TASK_RUNNING)
> I1203 18:25:04.582212 28737 scheduler.cpp:457] Enqueuing event UPDATE
> received from master@172.17.0.2:39622
> I1203 18:25:04.585041 28745 scheduler.cpp:457] Enqueuing event UPDATE
> received from master@172.17.0.2:39622
> I1203 18:25:04.605078 28745 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.2:39622
> I1203 18:25:04.609073 28739 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.609181 28739 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51248
> I1203 18:25:04.609382 28739 master.cpp:3729] Processing ACKNOWLEDGE call
> 6afd012e-8e88-41b2-8239-a9b852d07ca1 for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
> I1203 18:25:04.609817 28732 status_update_manager.cpp:392] Received status
> update acknowledgement (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for
> task 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> W1203 18:25:04.610076 28732 status_update_manager.cpp:762] Unexpected
> status update acknowledgement (received
> 6afd012e-8e88-41b2-8239-a9b852d07ca1, expecting
> 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for update TASK_RUNNING (UUID:
> 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> E1203 18:25:04.610339 28736 slave.cpp:2339] Failed to handle status update
> acknowledgement (UUID: 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000: Duplicate acknowledgement
> I1203 18:25:04.614711 28733 scheduler.cpp:298] Sending ACKNOWLEDGE call to
> master@172.17.0.2:39622
> I1203 18:25:04.618331 28736 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.618434 28736 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51249
> I1203 18:25:04.618621 28736 master.cpp:3729] Processing ACKNOWLEDGE call
> 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6 for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default) on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
> I1203 18:25:04.619472 28736 status_update_manager.cpp:392] Received status
> update acknowledgement (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for
> task cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> W1203 18:25:04.619717 28736 status_update_manager.cpp:762] Unexpected
> status update acknowledgement (received
> 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6, expecting
> 6afd012e-8e88-41b2-8239-a9b852d07ca1) for update TASK_RUNNING (UUID:
> 6afd012e-8e88-41b2-8239-a9b852d07ca1) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> E1203 18:25:04.620014 28736 slave.cpp:2339] Failed to handle status update
> acknowledgement (UUID: 82fc7a7b-e64a-4f4d-ab74-76abac42b4e6) for task
> cafa992b-2be6-49fc-b727-bb3d55f1511d of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000: Duplicate acknowledgement
> I1203 18:25:04.621562 28736 scheduler.cpp:298] Sending DECLINE call to
> master@172.17.0.2:39622
> I1203 18:25:04.626117 28735 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.626325 28735 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51250
> I1203 18:25:04.626641 28735 master.cpp:3455] Processing DECLINE call for
> offers: [ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O3 ] for framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 (default)
> I1203 18:25:04.627543 28742 hierarchical.cpp:1062] No resources available
> to allocate!
> I1203 18:25:04.627910 28742 hierarchical.cpp:957] Performed allocation for
> 2 slaves in 2.470458ms
> I1203 18:25:04.628186 28742 hierarchical.cpp:699] Framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 filtered inverse offers from
> slave c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 for 101secs
> I1203 18:25:04.628924 28744 scheduler.cpp:298] Sending ACCEPT call to
> master@172.17.0.2:39622
> I1203 18:25:04.631536 28734 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1203 18:25:04.631605 28734 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:51251
> W1203 18:25:04.632104 28734 master.cpp:2973] ACCEPT call used invalid
> offers '[ c7900911-cc7a-4dde-92e7-48fe82cddd9e-O2 ]': Offer
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-O2 is no longer valid
> 2015-12-03
> 18:25:04,893:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> 2015-12-03
> 18:25:08,229:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> 2015-12-03
> 18:25:11,565:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> 2015-12-03
> 18:25:14,901:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> 2015-12-03
> 18:25:18,235:28713(0x2ab9e880b700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:47838] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> ../../src/tests/master_maintenance_tests.cpp:1559: Failure
> Failed to wait 15secs for event
> I1203 18:25:19.639402 28740 master.cpp:938] Master terminating
> W1203 18:25:19.639631 28740 master.cpp:6276] Removing task
> cafa992b-2be6-49fc-b727-bb3d55f1511d with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2) in non-terminal state TASK_RUNNING
> I1203 18:25:19.640571 28740 master.cpp:6319] Removing executor
> 'executor-2' with resources of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1 at slave(112)@172.17.0.2:39622
> (maintenance-host-2)
> W1203 18:25:19.641091 28740 master.cpp:6276] Removing task
> 26305fdd-edb0-4764-8b8a-2558f2b2d81b with resources cpus(*):2; mem(*):1024;
> disk(*):1024; ports(*):[31000-32000] of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622
> (maintenance-host) in non-terminal state TASK_RUNNING
> I1203 18:25:19.641700 28740 master.cpp:6319] Removing executor
> 'executor-1' with resources of framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000 on slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0 at slave(111)@172.17.0.2:39622
> (maintenance-host)
> I1203 18:25:19.643367 28733 hierarchical.cpp:410] Removed slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S1
> I1203 18:25:19.644150 28733 hierarchical.cpp:410] Removed slave
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-S0
> I1203 18:25:19.644635 28733 hierarchical.cpp:260] Removed framework
> c7900911-cc7a-4dde-92e7-48fe82cddd9e-0000
> I1203 18:25:19.645395 28739 slave.cpp:3243] master@172.17.0.2:39622 exited
> W1203 18:25:19.646028 28739 slave.cpp:3246] Master disconnected! Waiting
> for a new master to be elected
> I1203 18:25:19.644919 28740 slave.cpp:3243] master@172.17.0.2:39622 exited
> W1203 18:25:19.647006 28740 slave.cpp:3246] Master disconnected! Waiting
> for a new master to be elected
> *** Aborted at 1449167119 (unix time) try "date -d @1449167119" if you are
> using GNU date ***
> PC: @ 0x8c31fe
> mesos::internal::tests::Cluster::Slaves::shutdown()
> *** SIGSEGV (@0x500010047) received by PID 28713 (TID 0x2ab9021fd940) from
> PID 65607; stack trace: ***
> @ 0x2ab9138ae0b7 os::Linux::chained_handler()
> @ 0x2ab9138b2219 JVM_handle_linux_signal
> @ 0x2ab907859340 (unknown)
> @ 0x8c31fe
> mesos::internal::tests::Cluster::Slaves::shutdown()
> @ 0xf23fdb
> mesos::internal::tests::MesosTest::ShutdownSlaves()
> @ 0xf23f75 mesos::internal::tests::MesosTest::Shutdown()
> @ 0xf1f8c7 mesos::internal::tests::MesosTest::TearDown()
> @ 0x1662d53
> testing::internal::HandleSehExceptionsInMethodIfSupported<>()
> @ 0x1650621
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x163117a testing::Test::Run()
> @ 0x1631dab testing::TestInfo::Run()
> @ 0x16324f7 testing::TestCase::Run()
> @ 0x1639c52 testing::internal::UnitTestImpl::RunAllTests()
> @ 0x1660513
> testing::internal::HandleSehExceptionsInMethodIfSupported<>()
> @ 0x1652641
> testing::internal::HandleExceptionsInMethodIfSupported<>()
> @ 0x163990b testing::UnitTest::Run()
> @ 0xca5141 RUN_ALL_TESTS()
> @ 0xca3fe6 main
> @ 0x2ab907a88ec5 (unknown)
> @ 0x7e703c (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]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1449165127-27928
> Untagged: mesos-1449165127-27928:latest
> Deleted: 68398f9a0db7903300dc04219d37e2f1311403ec5bd07ca2c8cce66fa7d9b144
> Deleted: 278319a9edd2a62e434a62ad98fa3b18f9811d498fd10e8974fc32ee8d345baa
> Deleted: e14b8acf0a7efc2a3c0af1f5240eeab99dc3651116c6500debbe2b63904bf0b2
> Deleted: 0b54a8f2cc78e4fcce0b1d72efaad9498a063c6b5de3c903ff45630e057f8c0c
> Deleted: bd00cedbdc24f0dd143fcbdfac2907b9782ae4de069faa8d5d1e2989c9eb7488
> Deleted: dd1b5d5a8e55241acb46bef8580e3b67d39f494633f2e2a680fc42de4f6b018c
> Deleted: f641a9d42f53729b85eec03ced3062ee9f72f46d996beeb433d6151daea3d6df
> Deleted: b84d74f5e45b228074e6d72f4bbdf63c1d8cf398341cf0ccaafe8c561332182f
> Deleted: 7ac9ba7138ad17281e99ef4c4411d577b6c9c3e34290039d204f733dc139ab78
> Deleted: 97886a6833a78e4666d2bded68b5898489fee41901b37091c1556d29f434fca8
> Deleted: 9e8f93adf5df5ca1eb63366a2b8c5a939d6ff25072ebf33622f91aef8e4a9fa6
> Deleted: 68549af3aca27bc40f40d00732b3e83df14c7e4117e4224beb1aebcf7a00f6ab
> Deleted: 97ee89720e239d38c7e4eefead19309c5ced22d7ac8584cb993a045b3a9ef674
> Build step 'Execute shell' marked build as failure
>
Jenkins build is back to normal : Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1316
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/1316/changes>