See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4320/display/redirect>
------------------------------------------ [...truncated 22.35 MB...] I1013 13:19:36.608641 5493 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7e57fc0ae998' server FQDN: '7e57fc0ae998' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1013 13:19:36.608669 5493 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1013 13:19:36.608724 5493 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1013 13:19:36.608760 5493 authenticator.cpp:318] Authentication success I1013 13:19:36.608893 5485 authenticatee.cpp:299] Authentication success I1013 13:19:36.609066 5487 master.cpp:7966] Successfully authenticated principal 'test-principal' at [email protected]:42197 I1013 13:19:36.609086 5493 state.cpp:64] Recovering state from '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/meta' I1013 13:19:36.609103 5485 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1056)@172.17.0.3:42197 I1013 13:19:36.609552 5481 sched.cpp:502] Successfully authenticated with master [email protected]:42197 I1013 13:19:36.609577 5483 status_update_manager.cpp:203] Recovering status update manager I1013 13:19:36.609585 5481 sched.cpp:820] Sending SUBSCRIBE call to [email protected]:42197 I1013 13:19:36.609740 5481 sched.cpp:853] Will retry registration in 906.553302ms if necessary I1013 13:19:36.609971 5491 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at [email protected]:42197 I1013 13:19:36.610080 5491 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I1013 13:19:36.610095 5495 slave.cpp:6310] Finished recovery I1013 13:19:36.610746 5489 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] I1013 13:19:36.610805 5495 slave.cpp:6492] Querying resource estimator for oversubscribable resources I1013 13:19:36.611083 5490 status_update_manager.cpp:177] Pausing sending status updates I1013 13:19:36.611147 5492 slave.cpp:993] New master detected at [email protected]:42197 I1013 13:19:36.611291 5492 slave.cpp:1028] Detecting new master I1013 13:19:36.611649 5483 sched.cpp:747] Framework registered with 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.611659 5492 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator I1013 13:19:36.611744 5483 sched.cpp:761] Scheduler::registered took 57516ns I1013 13:19:36.611768 5485 hierarchical.cpp:303] Added framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.612004 5485 hierarchical.cpp:1943] No allocations performed I1013 13:19:36.612058 5485 hierarchical.cpp:2033] No inverse offers to send out! I1013 13:19:36.612123 5485 hierarchical.cpp:1486] Performed allocation for 0 agents in 162018ns I1013 13:19:36.621726 5495 slave.cpp:1055] Authenticating with master [email protected]:42197 I1013 13:19:36.621806 5495 slave.cpp:1064] Using default CRAM-MD5 authenticatee I1013 13:19:36.622051 5487 authenticatee.cpp:121] Creating new client SASL connection I1013 13:19:36.622370 5484 master.cpp:7936] Authenticating slave(520)@172.17.0.3:42197 I1013 13:19:36.622524 5490 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1057)@172.17.0.3:42197 I1013 13:19:36.622781 5493 authenticator.cpp:98] Creating new server SASL connection I1013 13:19:36.623014 5481 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1013 13:19:36.623045 5481 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1013 13:19:36.623186 5482 authenticator.cpp:204] Received SASL authentication start I1013 13:19:36.623250 5482 authenticator.cpp:326] Authentication requires more steps I1013 13:19:36.623383 5492 authenticatee.cpp:259] Received SASL authentication step I1013 13:19:36.623541 5483 authenticator.cpp:232] Received SASL authentication step I1013 13:19:36.623584 5483 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7e57fc0ae998' server FQDN: '7e57fc0ae998' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1013 13:19:36.623603 5483 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1013 13:19:36.623634 5483 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1013 13:19:36.623662 5483 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '7e57fc0ae998' server FQDN: '7e57fc0ae998' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1013 13:19:36.623678 5483 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1013 13:19:36.623694 5483 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1013 13:19:36.623713 5483 authenticator.cpp:318] Authentication success I1013 13:19:36.623807 5496 authenticatee.cpp:299] Authentication success I1013 13:19:36.623862 5488 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(520)@172.17.0.3:42197 I1013 13:19:36.623914 5483 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1057)@172.17.0.3:42197 I1013 13:19:36.624135 5489 slave.cpp:1147] Successfully authenticated with master [email protected]:42197 I1013 13:19:36.624366 5489 slave.cpp:1626] Will retry registration in 18.363757ms if necessary I1013 13:19:36.624577 5487 master.cpp:5801] Received register agent message from slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.624723 5487 master.cpp:3838] Authorizing agent with principal 'test-principal' I1013 13:19:36.625147 5490 master.cpp:5861] Authorized registration of agent at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.625283 5490 master.cpp:5954] Registering agent at slave(520)@172.17.0.3:42197 (7e57fc0ae998) with id 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 I1013 13:19:36.625756 5493 registrar.cpp:495] Applied 1 operations in 61104ns; attempting to update the registry I1013 13:19:36.626461 5493 registrar.cpp:552] Successfully updated the registry in 637952ns I1013 13:19:36.626744 5494 master.cpp:6001] Admitted agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.627355 5485 slave.cpp:4966] Received ping from slave-observer(499)@172.17.0.3:42197 I1013 13:19:36.627543 5485 slave.cpp:1193] Registered with master [email protected]:42197; given agent ID 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 I1013 13:19:36.627696 5495 status_update_manager.cpp:184] Resuming sending status updates I1013 13:19:36.627449 5494 master.cpp:6032] Registered agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] I1013 13:19:36.627954 5485 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/meta/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/slave.info' I1013 13:19:36.628008 5488 hierarchical.cpp:593] Added agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 (7e57fc0ae998) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) I1013 13:19:36.628448 5485 slave.cpp:1262] Forwarding total oversubscribed resources {} I1013 13:19:36.628660 5483 master.cpp:6817] Received update of agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) with total oversubscribed resources {} I1013 13:19:36.628852 5483 master.cpp:6828] Ignoring update on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) as it reports no changes I1013 13:19:36.629482 5488 hierarchical.cpp:2033] No inverse offers to send out! I1013 13:19:36.629555 5488 hierarchical.cpp:1486] Performed allocation for 1 agents in 1.324117ms I1013 13:19:36.630064 5489 master.cpp:7766] Sending 1 offers to framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 I1013 13:19:36.630764 5487 sched.cpp:917] Scheduler::resourceOffers took 121207ns I1013 13:19:36.633258 5496 master.cpp:9389] Removing offer 8b471042-bc38-46eb-a2ca-b88c2d78032a-O0 I1013 13:19:36.633443 5496 master.cpp:4196] Processing ACCEPT call for offers: [ 8b471042-bc38-46eb-a2ca-b88c2d78032a-O0 ] on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 I1013 13:19:36.633615 5496 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 W1013 13:19:36.636307 5496 validation.cpp:1338] Executor 'default' for task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1013 13:19:36.636350 5496 validation.cpp:1350] Executor 'default' for task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1013 13:19:36.637053 5496 master.cpp:10135] Adding task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.637892 5496 master.cpp:4879] Launching task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.638720 5495 slave.cpp:1747] Got assigned task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.639559 5495 slave.cpp:2015] Authorizing task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.639638 5495 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 I1013 13:19:36.640592 5489 slave.cpp:2183] Launching task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.640710 5494 hierarchical.cpp:887] Updated allocation of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 from cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] I1013 13:19:36.641304 5489 paths.cpp:605] Trying to chown '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' to user 'mesos' I1013 13:19:36.641569 5489 slave.cpp:7271] Launching executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 with resources [] in work directory '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' I1013 13:19:36.642144 5489 slave.cpp:2874] Launching container 97eb90b0-85a4-46cc-a35c-bfe410e3e161 for executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.642577 5489 slave.cpp:2411] Queued task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' for executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.642693 5489 slave.cpp:944] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' to virtual path '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/latest' I1013 13:19:36.642765 5489 slave.cpp:944] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' to virtual path '/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/latest' I1013 13:19:36.642832 5489 slave.cpp:944] Successfully attached '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' to virtual path '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' W1013 13:19:36.645181 5482 process.cpp:3194] Attempted to spawn already running process [email protected]:42197 I1013 13:19:36.645411 5482 exec.cpp:162] Version: 1.5.0 I1013 13:19:36.645684 5481 exec.cpp:212] Executor started at: executor(131)@172.17.0.3:42197 with pid 5480 I1013 13:19:36.645920 5481 slave.cpp:3941] Got registration for executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 from executor(131)@172.17.0.3:42197 I1013 13:19:36.646525 5493 exec.cpp:237] Executor registered on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 I1013 13:19:36.646733 5493 exec.cpp:249] Executor::registered took 160054ns I1013 13:19:36.647413 5481 slave.cpp:2613] Sending queued task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' to executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 at executor(131)@172.17.0.3:42197 I1013 13:19:36.647895 5486 exec.cpp:331] Executor asked to run task '8a202d0b-66da-486b-9f5b-17e5c71a9b04' I1013 13:19:36.648033 5486 exec.cpp:340] Executor::launchTask took 106426ns I1013 13:19:36.648190 5486 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.648555 5496 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 from executor(131)@172.17.0.3:42197 I1013 13:19:36.649242 5495 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.649293 5495 status_update_manager.cpp:500] Creating StatusUpdate stream for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.650113 5495 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 to the agent I1013 13:19:36.650444 5488 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 to [email protected]:42197 I1013 13:19:36.650666 5488 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.650718 5488 slave.cpp:4786] Sending acknowledgement for status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 to executor(131)@172.17.0.3:42197 I1013 13:19:36.650785 5487 master.cpp:6993] Status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 from agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.650840 5487 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.650883 5494 exec.cpp:388] Executor received status update acknowledgement 32b7147b-49d1-4406-9d4f-0fc6202b325f for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.651041 5487 master.cpp:9157] Updating the state of task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1013 13:19:36.651309 5488 sched.cpp:1025] Scheduler::statusUpdate took 89021ns I1013 13:19:36.651587 5488 master.cpp:5566] Processing ACKNOWLEDGE call 32b7147b-49d1-4406-9d4f-0fc6202b325f for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 W1013 13:19:36.652292 5492 slave.cpp:3646] Ignoring status update acknowledgement message from [email protected]:1 because it is not the expected master: [email protected]:42197 I1013 13:19:36.654539 5493 hierarchical.cpp:1943] No allocations performed I1013 13:19:36.654592 5493 hierarchical.cpp:2033] No inverse offers to send out! I1013 13:19:36.654664 5493 hierarchical.cpp:1486] Performed allocation for 1 agents in 234867ns W1013 13:19:36.654695 5483 status_update_manager.cpp:478] Resending status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.654745 5483 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 to the agent I1013 13:19:36.654976 5485 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 to [email protected]:42197 I1013 13:19:36.655293 5495 master.cpp:6993] Status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 from agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.655341 5495 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.655520 5495 master.cpp:9157] Updating the state of task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1013 13:19:36.655737 5494 sched.cpp:1025] Scheduler::statusUpdate took 79859ns I1013 13:19:36.655972 5484 master.cpp:5566] Processing ACKNOWLEDGE call 32b7147b-49d1-4406-9d4f-0fc6202b325f for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 I1013 13:19:36.656256 5489 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.656497 5488 slave.cpp:3679] Status update manager successfully handled status update acknowledgement (UUID: 32b7147b-49d1-4406-9d4f-0fc6202b325f) for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.656755 5480 sched.cpp:2005] Asked to stop the driver I1013 13:19:36.656844 5490 sched.cpp:1187] Stopping framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.657066 5492 master.cpp:8447] Processing TEARDOWN call for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 I1013 13:19:36.657097 5492 master.cpp:8459] Removing framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 I1013 13:19:36.657114 5492 master.cpp:3299] Deactivating framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (default) at [email protected]:42197 I1013 13:19:36.657218 5486 hierarchical.cpp:412] Deactivated framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.657284 5492 master.cpp:9157] Updating the state of task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I1013 13:19:36.657297 5486 slave.cpp:3211] Asked to shut down framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 by [email protected]:42197 I1013 13:19:36.657325 5486 slave.cpp:3236] Shutting down framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.657367 5486 slave.cpp:5743] Shutting down executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 at executor(131)@172.17.0.3:42197 I1013 13:19:36.657470 5485 exec.cpp:435] Executor asked to shutdown I1013 13:19:36.657555 5485 exec.cpp:450] Executor::shutdown took 62871ns I1013 13:19:36.657757 5487 slave.cpp:5008] Got exited event for executor(131)@172.17.0.3:42197 I1013 13:19:36.658169 5492 master.cpp:9251] Removing task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.658509 5490 slave.cpp:5408] Executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 exited with status 0 I1013 13:19:36.658615 5490 slave.cpp:5512] Cleaning up executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 at executor(131)@172.17.0.3:42197 I1013 13:19:36.658646 5483 hierarchical.cpp:1161] Recovered cpus(allocated: *):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 from framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.658783 5492 master.cpp:9280] Removing executor 'default' with resources [] of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.659045 5488 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default/runs/97eb90b0-85a4-46cc-a35c-bfe410e3e161' for gc 6.99999237284741days in the future I1013 13:19:36.659241 5490 slave.cpp:5619] Cleaning up framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 W1013 13:19:36.659286 5492 master.cpp:7110] Ignoring unknown exited executor 'default' of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 on agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.659257 5491 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000/executors/default' for gc 6.99999237084444days in the future I1013 13:19:36.659406 5493 status_update_manager.cpp:285] Closing status update streams for framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.659413 5495 hierarchical.cpp:355] Removed framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.659471 5493 status_update_manager.cpp:531] Cleaning up status update stream for task 8a202d0b-66da-486b-9f5b-17e5c71a9b04 of framework 8b471042-bc38-46eb-a2ca-b88c2d78032a-0000 I1013 13:19:36.659528 5490 slave.cpp:869] Agent terminating I1013 13:19:36.659529 5481 gc.cpp:90] Scheduling '/tmp/SlaveTest_IgnoreNonLeaderStatusUpdateAcknowledgement_UHvpFg/slaves/8b471042-bc38-46eb-a2ca-b88c2d78032a-S0/frameworks/8b471042-bc38-46eb-a2ca-b88c2d78032a-0000' for gc 6.99999236760296days in the future *** Aborted at 1507900776 (unix time) try "date -d @1507900776" if you are using GNU date *** I1013 13:19:36.659708 5486 master.cpp:1303] Agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) disconnected I1013 13:19:36.659729 5486 master.cpp:3336] Disconnecting agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.659780 5486 master.cpp:3355] Deactivating agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 at slave(520)@172.17.0.3:42197 (7e57fc0ae998) I1013 13:19:36.659924 5494 hierarchical.cpp:690] Agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 deactivated PC: @ 0x2acee8bac404 __GI___pthread_mutex_lock *** SIGSEGV (@0x0) received by PID 5480 (TID 0x2acef2f26700) from PID 0; stack trace: *** @ 0x2acf3a5beaf7 (unknown) @ 0x2acf3a5c3449 (unknown) @ 0x2acee8bb2330 (unknown) @ 0x2acee8bac404 __GI___pthread_mutex_lock @ 0x2acedc808f5c __gthread_mutex_lock() I1013 13:19:36.663841 5489 master.cpp:1145] Master terminating I1013 13:19:36.664583 5494 hierarchical.cpp:626] Removed agent 8b471042-bc38-46eb-a2ca-b88c2d78032a-S0 [ OK ] SlaveTest.IgnoreNonLeaderStatusUpdateAcknowledgement (84 ms) [ RUN ] SlaveTest.MetricsInMetricsEndpoint I1013 13:19:36.673692 5480 cluster.cpp:162] Creating default 'local' authorizer @ 0x2acedc80b134 std::mutex::lock() I1013 13:19:36.676502 5489 master.cpp:445] Master bcce3f6a-673a-4bb5-be55-05c705ba39d0 (7e57fc0ae998) started on 172.17.0.3:42197 I1013 13:19:36.676568 5489 master.cpp:447] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/WBjSjP/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.5.0/_inst/share/mesos/webui" --work_dir="/tmp/WBjSjP/master" --zk_session_timeout="10secs" I1013 13:19:36.676981 5489 master.cpp:496] Master only allowing authenticated frameworks to register I1013 13:19:36.676997 5489 master.cpp:502] Master only allowing authenticated agents to register I1013 13:19:36.677008 5489 master.cpp:508] Master only allowing authenticated HTTP frameworks to register I1013 13:19:36.677022 5489 credentials.hpp:37] Loading credentials for authentication from '/tmp/WBjSjP/credentials' I1013 13:19:36.677361 5489 master.cpp:552] Using default 'crammd5' authenticator I1013 13:19:36.677520 5489 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I1013 13:19:36.677692 5489 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I1013 13:19:36.677817 5489 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I1013 13:19:36.677947 5489 master.cpp:631] Authorization enabled I1013 13:19:36.678093 5490 hierarchical.cpp:171] Initialized hierarchical allocator process I1013 13:19:36.678125 5494 whitelist_watcher.cpp:77] No whitelist given I1013 13:19:36.680526 5484 master.cpp:2198] Elected as the leading master! I1013 13:19:36.680553 5484 master.cpp:1687] Recovering from registrar @ 0x2acedc80d65c _ZZ11synchronizeISt5mutexE12SynchronizedIT_EPS2_ENKUlPS0_E_clES5_ I1013 13:19:36.680696 5485 registrar.cpp:347] Recovering registrar I1013 13:19:36.681187 5485 registrar.cpp:391] Successfully fetched the registry (0B) in 454912ns I1013 13:19:36.681290 5485 registrar.cpp:495] Applied 1 operations in 25053ns; attempting to update the registry I1013 13:19:36.681773 5485 registrar.cpp:552] Successfully updated the registry in 428800ns I1013 13:19:36.681884 5485 registrar.cpp:424] Successfully recovered registrar I1013 13:19:36.682240 5488 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1013 13:19:36.682250 5487 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover @ 0x2acedc80d67b _ZZ11synchronizeISt5mutexE12SynchronizedIT_EPS2_ENUlPS0_E_4_FUNES5_ @ 0x2acedc80fc78 Synchronized<>::Synchronized() @ 0x2acedc80d721 synchronize<>() @ 0x2acee6425c23 process::EventQueue::empty() @ 0x2acee64259c7 process::EventQueue::Consumer::empty() @ 0x2acee641277a process::ProcessManager::resume() @ 0x2acee640eeb4 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv @ 0x2acee64206b6 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE @ 0x2acee642060d _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv @ 0x2acee64205a6 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv @ 0x2acee8433a60 (unknown) @ 0x2acee8baa184 start_thread @ 0x2acee8ebdffd (unknown) make[4]: *** [check-local] Segmentation fault make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.5.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1507897768-32586 Untagged: mesos-1507897768-32586:latest Deleted: sha256:91510f9e29065d3f9db7fe44d2c9c8f8ab01f5f5b1b353d61dbd64bcbd4f05ad Deleted: sha256:4e07819fceeaac08ca3f00b9663ab14e674e8f781eacc8a2aa304c5485f8084f Deleted: sha256:7f24678c195a2830b6d1031ddcef7f97a0e53bdd4934ebb40fd2cbb8697b5878 Deleted: sha256:3a0f97e94e3e3ae50eadd4995e4c517c4174e71ebd9ddd21546e1fd4e334f58f Deleted: sha256:ddd1cc40897f421aaea4037d1a8986dc10c514a3849bfe54b6ee2834e77c6fbc Deleted: sha256:6c0fc5e232741e37a93f93ba196c479b366fc67a604868b821832c7e8ba06741 Deleted: sha256:79424c88665f8882c901cbd0dfa554170cd756dd384d30f2d6df44df98fcd8bc Deleted: sha256:4d3297ac86359852156b30a1621f1062e0e616aa6b03bf99bf99dbb7fadfea9b Deleted: sha256:0d0abd842282ed82b8e58b439ebc8fc15bc05049e6048b5e0ce26b87cbbf8db8 Deleted: sha256:ae1b92ff01048301d53d633f4e215378bc0ddb4aa0a5a7d70c2e4fc3c2002438 Deleted: sha256:40e2e441f23ebcf43e4fc2a1625d700faf274acccd90b7fefdfd55a32c983eeb Deleted: sha256:a77167566f24839c10de6dc7accf0b31520dbdb9c6f4d9045aae426caf4adc62 Deleted: sha256:cb67687f381ec9af1777ff68427f806bfa59432449c5007c04be6de450fb66d9 Deleted: sha256:8043e709903c8b2bfbcec061f5ea82e7b073d81c32d820c6fc9546ecc16b26c6 Deleted: sha256:1ace1e59a2e31048a31f2b5bddd17df235d00889f01eb33af020eb8f0dc9ed89 Deleted: sha256:51adcfd8d34605119810f3e2e37bd611707e8e2d4b615313138cf6d1fc15a82d Deleted: sha256:98124d28a481e46776eacd7cfdfb1c1ab77d5b75bd2724098ea24ee00b0c7bf7 Deleted: sha256:c09604e024cf8235b915d851a5682f59574e7424c6f5a4d47ca7b8ab634b8d1e Deleted: sha256:dd37a3d040f266aa0f0b7cd70a4e9d9dbc0c8d24441b3508032ef81c959a7d0e Deleted: sha256:2262c940c0e5b555f970a452057292cffef0e28860f8efeb84955282fea78517 Deleted: sha256:3ecca8be49cfa4dd5406adc515612c5979687615a991a4de1f5024a60d6d1913 Deleted: sha256:b529b92231c6ef2f1e962e06bbb8964c0ac8f071c699074ac2ab9495a9ab9403 Deleted: sha256:6fe0ac3bdebf3bc5c29a2881c36dcdedbdde9fdc07766a337cf096016b7290a8 Deleted: sha256:6854f279fec79e8f09e95cb8501c4c1720ea3d6644cae4c94f17d527de83acda Deleted: sha256:8b3f92b4892104858f814ce3fbb18833ee6633e1ab276d31a0fa0190ac4e2715 Deleted: sha256:f8844749465f4fc0b9962e21dcbf5eb8a599a735d7f8f4192ad1e7f8c88f7883 Deleted: sha256:f4e95df1d13d2ca44bae537b4d83b619f222c8a0719f30f936aef6bfdd6ad4ed Deleted: sha256:1fa9f79e6562e67c014a42c7155635f1202eeceb7e1996d76c4b4baaf4df48e0 Deleted: sha256:dea1945146b96542e6e20642830c78df702d524a113605a906397db1db022703 Build step 'Execute shell' marked build as failure
