See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2526/changes>
Changes: [vinodkone] Included v1 Executor Protos in maven jar. [vinodkone] Updated CHANGELOG for 0.28.3. ------------------------------------------ [...truncated 69232 lines...] I0719 00:17:27.763370 3505 replica.cpp:712] Persisted action at 3 I0719 00:17:27.764569 3498 replica.cpp:691] Replica received learned notice for position 3 from @0.0.0.0:0 I0719 00:17:27.805754 3498 leveldb.cpp:341] Persisting action (364 bytes) to leveldb took 41.130821ms I0719 00:17:27.805845 3498 replica.cpp:712] Persisted action at 3 I0719 00:17:27.805882 3498 replica.cpp:697] Replica learned APPEND action at position 3 I0719 00:17:27.808087 3506 registrar.cpp:484] Successfully updated the 'registry' in 82.553088ms I0719 00:17:27.808670 3506 registrar.cpp:439] Applied 1 operations in 307746ns; attempting to update the 'registry' I0719 00:17:27.809206 3499 master.cpp:4274] Registered slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0719 00:17:27.809345 3496 slave.cpp:3502] Received ping from slave-observer(115)@172.17.0.9:59942 I0719 00:17:27.810160 3496 slave.cpp:974] Registered with master [email protected]:59942; given slave ID 9c3eef48-0222-4e76-8f88-b17328b03134-S0 I0719 00:17:27.809795 3497 hierarchical.cpp:473] Added slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: ) I0719 00:17:27.809371 3498 log.cpp:702] Attempting to truncate the log to 3 I0719 00:17:27.810250 3496 fetcher.cpp:81] Clearing fetcher cache I0719 00:17:27.810583 3504 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4 I0719 00:17:27.810770 3498 status_update_manager.cpp:181] Resuming sending status updates I0719 00:17:27.810860 3497 hierarchical.cpp:1482] No resources available to allocate! I0719 00:17:27.810984 3496 slave.cpp:997] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffers_07hHCP/meta/slaves/9c3eef48-0222-4e76-8f88-b17328b03134-S0/slave.info' I0719 00:17:27.811272 3497 hierarchical.cpp:1179] Performed allocation for slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 in 913715ns I0719 00:17:27.811398 3496 slave.cpp:1033] Forwarding total oversubscribed resources I0719 00:17:27.811606 3496 master.cpp:4618] Received update of slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) with total oversubscribed resources I0719 00:17:27.811903 3504 replica.cpp:537] Replica received write request for position 4 from (4573)@172.17.0.9:59942 I0719 00:17:27.812082 3496 hierarchical.cpp:537] Slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 (maintenance-host) updated with oversubscribed resources (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: ) I0719 00:17:27.812377 3496 hierarchical.cpp:1482] No resources available to allocate! I0719 00:17:27.812481 3496 hierarchical.cpp:1179] Performed allocation for slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 in 248778ns I0719 00:17:27.847879 3504 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 35.917843ms I0719 00:17:27.847968 3504 replica.cpp:712] Persisted action at 4 I0719 00:17:27.849535 3499 replica.cpp:691] Replica received learned notice for position 4 from @0.0.0.0:0 I0719 00:17:27.889807 3499 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.216285ms I0719 00:17:27.890151 3499 leveldb.cpp:399] Deleting ~2 keys from leveldb took 100153ns I0719 00:17:27.890193 3499 replica.cpp:712] Persisted action at 4 I0719 00:17:27.890246 3499 replica.cpp:697] Replica learned TRUNCATE action at position 4 I0719 00:17:27.891911 3508 log.cpp:683] Attempting to append 465 bytes to the log I0719 00:17:27.892351 3502 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5 I0719 00:17:27.893497 3502 replica.cpp:537] Replica received write request for position 5 from (4574)@172.17.0.9:59942 I0719 00:17:27.943025 3502 leveldb.cpp:341] Persisting action (484 bytes) to leveldb took 49.470966ms I0719 00:17:27.943099 3502 replica.cpp:712] Persisted action at 5 I0719 00:17:27.944284 3502 replica.cpp:691] Replica received learned notice for position 5 from @0.0.0.0:0 I0719 00:17:27.982102 3502 leveldb.cpp:341] Persisting action (486 bytes) to leveldb took 37.773405ms I0719 00:17:27.982189 3502 replica.cpp:712] Persisted action at 5 I0719 00:17:27.982226 3502 replica.cpp:697] Replica learned APPEND action at position 5 I0719 00:17:27.985280 3507 registrar.cpp:484] Successfully updated the 'registry' in 176.528896ms I0719 00:17:27.985976 3507 log.cpp:702] Attempting to truncate the log to 5 I0719 00:17:27.986448 3500 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6 I0719 00:17:27.987751 3500 replica.cpp:537] Replica received write request for position 6 from (4575)@172.17.0.9:59942 I0719 00:17:27.988323 3508 master.cpp:4674] Updating unavailability of slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host), starting at 2431.71672241086weeks I0719 00:17:27.988669 3511 hierarchical.cpp:1482] No resources available to allocate! I0719 00:17:27.988705 3511 hierarchical.cpp:1179] Performed allocation for slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 in 175794ns I0719 00:17:27.991082 3501 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/maintenance/status' I0719 00:17:27.991628 3511 http.cpp:312] HTTP GET for /master/maintenance/status from 172.17.0.9:34614 I0719 00:17:27.994009 3477 scheduler.cpp:172] Version: 0.28.3 I0719 00:17:27.994650 3507 scheduler.cpp:437] New master detected at [email protected]:59942 I0719 00:17:27.995841 3508 scheduler.cpp:337] Connected with the master at http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:27.996855 3510 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:27.997640 3498 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0719 00:17:27.997992 3498 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.9:34615 I0719 00:17:27.998165 3498 master.cpp:1973] Received subscription request for HTTP framework 'default' I0719 00:17:27.998234 3498 master.cpp:1750] Authorizing framework principal 'test-principal' to receive offers for role '*' I0719 00:17:27.998528 3498 master.cpp:2064] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0719 00:17:27.999285 3507 hierarchical.cpp:266] Added framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:27.999869 3498 master.hpp:1661] Sending heartbeat to 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.000095 3507 hierarchical.cpp:1159] Performed allocation for 1 slaves in 781790ns I0719 00:17:28.000651 3498 master.cpp:5324] Sending 1 offers to framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.001286 3498 master.cpp:5414] Sending 1 inverse offers to framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.002379 3501 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.003273 3501 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.004319 3501 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event I0719 00:17:28.004501 3506 scheduler.cpp:612] Enqueuing event OFFERS received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.006479 3509 scheduler.cpp:612] Enqueuing event OFFERS received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.010931 3496 scheduler.cpp:230] Sending ACCEPT call to http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.012035 3510 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0719 00:17:28.012686 3503 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.9:34616 I0719 00:17:28.014562 3503 master.cpp:3104] Processing ACCEPT call for offers: [ 9c3eef48-0222-4e76-8f88-b17328b03134-O0 ] on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) for framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.014616 3503 master.cpp:2776] Authorizing framework principal 'test-principal' to launch task 91455559-520f-47c3-8a58-e864f3542d1f as user 'mesos' W0719 00:17:28.017665 3497 validation.cpp:404] Executor default for task 91455559-520f-47c3-8a58-e864f3542d1f uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0719 00:17:28.017720 3497 validation.cpp:416] Executor default for task 91455559-520f-47c3-8a58-e864f3542d1f uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0719 00:17:28.018157 3497 master.hpp:177] Adding task 91455559-520f-47c3-8a58-e864f3542d1f with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 (maintenance-host) I0719 00:17:28.018405 3497 master.cpp:3589] Launching task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) I0719 00:17:28.018918 3497 slave.cpp:1371] Got assigned task 91455559-520f-47c3-8a58-e864f3542d1f for framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.019583 3497 slave.cpp:1490] Launching task 91455559-520f-47c3-8a58-e864f3542d1f for framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.020337 3497 paths.cpp:528] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffers_07hHCP/slaves/9c3eef48-0222-4e76-8f88-b17328b03134-S0/frameworks/9c3eef48-0222-4e76-8f88-b17328b03134-0000/executors/default/runs/ed31425f-23d4-47ea-b6d8-f1ad4a2f999f' to user 'mesos' I0719 00:17:28.026947 3500 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.924159ms I0719 00:17:28.027063 3500 replica.cpp:712] Persisted action at 6 I0719 00:17:28.030797 3501 replica.cpp:691] Replica received learned notice for position 6 from @0.0.0.0:0 I0719 00:17:28.039664 3497 slave.cpp:5362] Launching executor default of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 with resources in work directory '/tmp/MasterMaintenanceTest_InverseOffers_07hHCP/slaves/9c3eef48-0222-4e76-8f88-b17328b03134-S0/frameworks/9c3eef48-0222-4e76-8f88-b17328b03134-0000/executors/default/runs/ed31425f-23d4-47ea-b6d8-f1ad4a2f999f' I0719 00:17:28.044641 3497 exec.cpp:143] Version: 0.28.3 I0719 00:17:28.044983 3509 exec.cpp:193] Executor started at: executor(47)@172.17.0.9:59942 with pid 3477 I0719 00:17:28.046618 3497 slave.cpp:1708] Queuing task '91455559-520f-47c3-8a58-e864f3542d1f' for executor 'default' of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.046809 3497 slave.cpp:749] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffers_07hHCP/slaves/9c3eef48-0222-4e76-8f88-b17328b03134-S0/frameworks/9c3eef48-0222-4e76-8f88-b17328b03134-0000/executors/default/runs/ed31425f-23d4-47ea-b6d8-f1ad4a2f999f' I0719 00:17:28.046919 3497 slave.cpp:2639] Got registration for executor 'default' of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 from executor(47)@172.17.0.9:59942 I0719 00:17:28.048717 3503 exec.cpp:217] Executor registered on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 I0719 00:17:28.048784 3503 exec.cpp:229] Executor::registered took 37694ns I0719 00:17:28.049648 3497 slave.cpp:1873] Sending queued task '91455559-520f-47c3-8a58-e864f3542d1f' to executor 'default' of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 at executor(47)@172.17.0.9:59942 I0719 00:17:28.050043 3510 exec.cpp:304] Executor asked to run task '91455559-520f-47c3-8a58-e864f3542d1f' I0719 00:17:28.050137 3510 exec.cpp:313] Executor::launchTask took 68655ns I0719 00:17:28.050231 3510 exec.cpp:526] Executor sending status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.050559 3497 slave.cpp:3022] Handling status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 from executor(47)@172.17.0.9:59942 I0719 00:17:28.051213 3506 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.051259 3506 status_update_manager.cpp:497] Creating StatusUpdate stream for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.052762 3506 status_update_manager.cpp:374] Forwarding update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 to the slave I0719 00:17:28.053284 3497 slave.cpp:3420] Forwarding the update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 to [email protected]:59942 I0719 00:17:28.053719 3498 master.cpp:4763] Status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 from slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) I0719 00:17:28.053767 3498 master.cpp:4811] Forwarding status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.055598 3498 master.cpp:6421] Updating the state of task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0719 00:17:28.056500 3497 slave.cpp:3314] Status update manager successfully handled status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.056551 3497 slave.cpp:3330] Sending acknowledgement for status update TASK_RUNNING (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 to executor(47)@172.17.0.9:59942 I0719 00:17:28.056800 3509 exec.cpp:350] Executor received status update acknowledgement 41cd3f18-bec8-4a4f-b80a-6a193055db16 for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.057207 3497 scheduler.cpp:612] Enqueuing event UPDATE received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.061167 3497 scheduler.cpp:230] Sending ACKNOWLEDGE call to http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.062580 3497 scheduler.cpp:230] Sending DECLINE call to http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.062772 3504 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0719 00:17:28.063330 3504 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.9:34616 I0719 00:17:28.063822 3504 master.cpp:3918] Processing ACKNOWLEDGE call 41cd3f18-bec8-4a4f-b80a-6a193055db16 for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 I0719 00:17:28.065462 3504 status_update_manager.cpp:392] Received status update acknowledgement (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.065850 3504 slave.cpp:2408] Status update manager successfully handled status update acknowledgement (UUID: 41cd3f18-bec8-4a4f-b80a-6a193055db16) for task 91455559-520f-47c3-8a58-e864f3542d1f of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.068527 3506 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0719 00:17:28.069049 3506 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.9:34616 I0719 00:17:28.069242 3506 master.cpp:3641] Processing DECLINE call for offers: [ 9c3eef48-0222-4e76-8f88-b17328b03134-O1 ] for framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.070477 3501 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 39.618434ms I0719 00:17:28.070585 3501 leveldb.cpp:399] Deleting ~2 keys from leveldb took 63738ns I0719 00:17:28.070614 3501 replica.cpp:712] Persisted action at 6 I0719 00:17:28.070650 3501 replica.cpp:697] Replica learned TRUNCATE action at position 6 I0719 00:17:28.084812 3510 hierarchical.cpp:1482] No resources available to allocate! I0719 00:17:28.085170 3510 hierarchical.cpp:1159] Performed allocation for 1 slaves in 593271ns I0719 00:17:28.085532 3510 master.cpp:5414] Sending 1 inverse offers to framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.088310 3511 scheduler.cpp:612] Enqueuing event OFFERS received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.097661 3507 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/maintenance/status' I0719 00:17:28.098206 3507 http.cpp:312] HTTP GET for /master/maintenance/status from 172.17.0.9:34617 I0719 00:17:28.104166 3507 scheduler.cpp:230] Sending ACCEPT call to http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.105623 3504 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0719 00:17:28.106145 3504 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.9:34616 W0719 00:17:28.107692 3504 master.cpp:3067] ACCEPT call used invalid offers '[ 9c3eef48-0222-4e76-8f88-b17328b03134-O2 ]': Offer 9c3eef48-0222-4e76-8f88-b17328b03134-O2 is no longer valid I0719 00:17:28.119266 3507 hierarchical.cpp:1482] No resources available to allocate! I0719 00:17:28.119611 3507 hierarchical.cpp:1159] Performed allocation for 1 slaves in 551294ns I0719 00:17:28.119772 3509 master.cpp:5414] Sending 1 inverse offers to framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 (default) I0719 00:17:28.121332 3509 scheduler.cpp:612] Enqueuing event OFFERS received from http://172.17.0.9:59942/master/api/v1/scheduler I0719 00:17:28.131728 3503 process.cpp:3175] Handling HTTP event for process 'master' with path: '/master/maintenance/status' I0719 00:17:28.132258 3503 http.cpp:312] HTTP GET for /master/maintenance/status from 172.17.0.9:34618 I0719 00:17:28.136662 3500 master.cpp:1026] Master terminating W0719 00:17:28.136809 3500 master.cpp:6473] Removing task 91455559-520f-47c3-8a58-e864f3542d1f with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) in non-terminal state TASK_RUNNING I0719 00:17:28.137734 3496 hierarchical.cpp:505] Removed slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 I0719 00:17:28.138641 3500 master.cpp:6516] Removing executor 'default' with resources of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 on slave 9c3eef48-0222-4e76-8f88-b17328b03134-S0 at slave(115)@172.17.0.9:59942 (maintenance-host) I0719 00:17:28.139551 3496 hierarchical.cpp:326] Removed framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.140226 3500 slave.cpp:3548] [email protected]:59942 exited W0719 00:17:28.140252 3500 slave.cpp:3551] Master disconnected! Waiting for a new master to be elected I0719 00:17:28.144425 3477 slave.cpp:668] Slave terminating I0719 00:17:28.144520 3477 slave.cpp:2075] Asked to shut down framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 by @0.0.0.0:0 I0719 00:17:28.144556 3477 slave.cpp:2100] Shutting down framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 I0719 00:17:28.144623 3477 slave.cpp:4193] Shutting down executor 'default' of framework 9c3eef48-0222-4e76-8f88-b17328b03134-0000 at executor(47)@172.17.0.9:59942 E0719 00:17:28.148311 3498 scheduler.cpp:585] End-Of-File received from master. The master closed the event stream I0719 00:17:28.149287 3501 scheduler.cpp:419] Re-detecting master I0719 00:17:28.149968 3501 scheduler.cpp:370] Ignoring disconnection attempt from stale connection I0719 00:17:28.150007 3501 scheduler.cpp:370] Ignoring disconnection attempt from stale connection F0719 00:17:28.150007 3501 logging.cpp:64] RAW: Pure virtual method called [ OK ] MasterMaintenanceTest.InverseOffers (920 ms) [ RUN ] MasterMaintenanceTest.InverseOffersFilters @ 0x7f4b610175c6 google::LogMessage::Fail() @ 0x7f4b6101caa8 google::RawLog__() @ 0x7f4b6015a2ac __cxa_pure_virtual @ 0x173c17f testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() @ 0xf3ee89 testing::internal::FunctionMockerBase<>::InvokeWith() @ 0xf39041 testing::internal::FunctionMocker<>::Invoke() @ 0xf3834a mesos::internal::tests::MasterMaintenanceTest::Callbacks::disconnected() @ 0xf504c7 _ZNKSt7_Mem_fnIMN5mesos8internal5tests21MasterMaintenanceTest9CallbacksEFvvEEclIJEvEEvRS4_DpOT_ @ 0xf4ca9e _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests21MasterMaintenanceTest9CallbacksEFvvEESt17reference_wrapperIS5_EEE6__callIvJEJLm0EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE @ 0xf48796 std::_Bind<>::operator()<>() I0719 00:17:28.254993 3477 leveldb.cpp:174] Opened db in 96.082149ms @ 0xf43985 std::_Function_handler<>::_M_invoke() @ 0x9a6e12 std::function<>::operator()() @ 0x7f4b600afbc9 process::AsyncExecutorProcess::execute<>() @ 0x7f4b600b7321 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvvEEPvS5_S8_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSD_FSA_T1_T2_ET3_T4_ENKUlPNS_11ProcessBaseEE_clESO_ I0719 00:17:28.294162 3477 leveldb.cpp:181] Compacted db in 39.085746ms I0719 00:17:28.294368 3477 leveldb.cpp:196] Created db iterator in 26777ns I0719 00:17:28.294509 3477 leveldb.cpp:202] Seeked to beginning of db in 3011ns I0719 00:17:28.294602 3477 leveldb.cpp:271] Iterated through 0 keys in the db in 497ns I0719 00:17:28.294735 3477 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0719 00:17:28.295711 3498 recover.cpp:447] Starting replica recovery I0719 00:17:28.296253 3498 recover.cpp:473] Replica is in EMPTY status I @ 0x7f4b600c3053 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvvEEPvS9_SC_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSH_FSE_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ 0719 00:17:28.298571 3496 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (4585)@172.17.0.9:59942 I0719 00:17:28.300884 3496 recover.cpp:193] Received a recover response from a replica in EMPTY status I0719 00:17:28.301486 3506 master.cpp:375] Master 22d3df90-391c-4ab6-bf42-937a3a844cee (daf9db43fc8f) started on 172.17.0.9:59942 I0719 00:17:28.301509 3506 master.cpp:377] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Zx4CVh/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.3/_inst/share/mesos/webui" --work_dir="/tmp/Zx4CVh/master" --zk_session_timeout="10secs" I0719 00:17:28.302047 3506 master.cpp:424] Master allowing unauthenticated frameworks to register I0719 00:17:28.302059 3506 master.cpp:427] Master only allowing authenticated slaves to register I0719 00:17:28.302069 3506 credentials.hpp:35] Loading credentials for authentication from '/tmp/Zx4CVh/credentials' I0719 00:17:28.302608 3506 master.cpp:467] Using default 'crammd5' authenticator I0719 00:17:28.302968 3506 master.cpp:536] Using default 'basic' HTTP authenticator I0719 00:17:28.303170 3499 recover.cpp:564] Updating replica status to STARTING I0719 00:17:28.303391 3506 master.cpp:570] Authorization enabled I0719 00:17:28.303767 3503 hierarchical.cpp:145] Initialized hierarchical allocator process I0719 00:17:28.303791 3497 whitelist_watcher.cpp:77] No whitelist given I0719 00:17:28.306036 3505 master.cpp:1711] The newly elected leader is [email protected]:59942 with id 22d3df90-391c-4ab6-bf42-937a3a844cee I0719 00:17:28.306071 3505 master.cpp:1724] Elected as the leading master! I0719 00:17:28.306090 3505 master.cpp:1469] Recovering from registrar I0719 00:17:28.306210 3497 registrar.cpp:307] Recovering registrar @ 0x7f4b60f7b7af std::function<>::operator()() @ 0x7f4b60f611a1 process::ProcessBase::visit() @ 0x7f4b60f67036 process::DispatchEvent::visit() @ 0x9a5400 process::ProcessBase::serve() I0719 00:17:28.341254 3496 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 37.954403ms I0719 00:17:28.341342 3496 replica.cpp:320] Persisted replica status to STARTING I0719 00:17:28.341729 3496 recover.cpp:473] Replica is in STARTING status I0719 00:17:28.345476 3498 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (4588)@172.17.0.9:59942 I0719 00:17:28.348677 3496 recover.cpp:193] Received a recover response from a replica in STARTING status I0719 00:17:28.349247 3496 recover.cpp:564] Updating replica status to VOTING @ 0x7f4b60f5d4b3 process::ProcessManager::resume() @ 0x7f4b60f5a5c0 _ZZN7process14ProcessManager12init_threadsEvENKUlRKSt11atomic_boolE_clES3_ @ 0x7f4b60f667f8 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS3_EEE6__callIvIEILm0EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE @ 0x7f4b60f667a8 _ZNSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS3_EEEclIIEvEET0_DpOT_ @ 0x7f4b60f6673a _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS4_EEEvEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE @ 0x7f4b60f66691 _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS4_EEEvEEclEv @ 0x7f4b60f6662a _ZNSt6thread5_ImplISt12_Bind_simpleIFSt5_BindIFZN7process14ProcessManager12init_threadsEvEUlRKSt11atomic_boolE_St17reference_wrapperIS6_EEEvEEE6_M_runEv @ 0x7f4b5bfa7220 (unknown) @ 0x7f4b5c60ddc5 start_thread I0719 00:17:28.402829 3498 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 53.271627ms I0719 00:17:28.402915 3498 replica.cpp:320] Persisted replica status to VOTING I0719 00:17:28.403167 3498 recover.cpp:578] Successfully joined the Paxos group I0719 00:17:28.403367 3498 recover.cpp:462] Recover process terminated I0719 00:17:28.404009 3503 log.cpp:659] Attempting to start the writer I0719 00:17:28.406618 3499 replica.cpp:493] Replica received implicit promise request from (4589)@172.17.0.9:59942 with proposal 1 @ 0x7f4b5b70eced __clone make[4]: *** [check-local] Aborted make[4]: Leaving directory `/mesos/mesos-0.28.3/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-0.28.3/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.28.3/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.28.3/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1468884099-24254 Untagged: mesos-1468884099-24254:latest Deleted: 03db73eff6e71b12c70b7dca04694aad2fce2b805d66d894c704da59efeda95a Deleted: e3af2e5021e63138c08ac54e2386f52fcaf915691b83c65e67e58ee6f615ffe9 Deleted: ff33fe96ca42bf66bc8168d0fb48e312a83e1d5a2398a962f37fc9d165b7b505 Deleted: 6ca9a3f88e8901cd2d57f8b93721a8461aed4030872362374bb34a427042c4df Deleted: 947a2cdc023d50794c5129fa1ae2fe4ce9369e2b6123dcf831ed7ee271e64bad Deleted: 05ba3ab4b5f9dd78374beef6750e0129e05af9a71ebb39e65ed3cae614836541 Deleted: b3c86db0c9f70898df4a8d7ac363a28d63fc337944c97aea4ddc8ceab8652ae6 Deleted: c36b45eb4a0e2a990256383b58c55d2d276f784529a8c227469c46ecd8cc00d4 Deleted: 4ec32ba23bca05a532858421022d764ecca818a82cd7a2e1684383135960a2fa Deleted: 0ab695c25cc87f816712245afd2183234437d270192f3cd157283c96844216e7 Deleted: 259c8ae1a89a55f61360eca4b5cdbb1b3a3b9fb6b7b97e8ebd702ead177b37df Deleted: d57e0d7d3fc31ac8d0e7b09413c10091ba610dc19f53552f77255fdb509aeb5f Deleted: b2ebc36947435b046871464c273fc4fac76e9433f0319c60ddc51f6fc52c79a8 Deleted: 1613df940eb521c46de56d06357509e2de40053d1f0edd30343ec994c96a0d66 Deleted: 051845b8adaae0e207c928874450f609154a1af29ac1326488a1cbc30c54f283 Deleted: 262346e562bf9a4d888385c84fc2c5640973d726a7e41eaa205ad1bf07b132ca Build step 'Execute shell' marked build as failure
