See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3798/display/redirect>
------------------------------------------ [...truncated 29.58 MB...] I0614 14:35:33.406298 23683 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0614 14:35:33.406317 23683 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c66652dbe0e4' server FQDN: 'c66652dbe0e4' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0614 14:35:33.406327 23683 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0614 14:35:33.406335 23683 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0614 14:35:33.406352 23683 authenticator.cpp:318] Authentication success I0614 14:35:33.406422 23679 authenticatee.cpp:299] Authentication success I0614 14:35:33.406476 23678 master.cpp:7495] Successfully authenticated principal 'test-principal' at slave(763)@172.17.0.3:58408 I0614 14:35:33.406585 23673 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1422)@172.17.0.3:58408 I0614 14:35:33.406741 23681 slave.cpp:1102] Successfully authenticated with master [email protected]:58408 I0614 14:35:33.406925 23681 slave.cpp:1522] Will retry registration in 15.832071ms if necessary I0614 14:35:33.407055 23677 master.cpp:5467] Received register agent message from slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.407086 23677 master.cpp:3699] Authorizing agent with principal 'test-principal' I0614 14:35:33.407414 23678 master.cpp:5527] Authorized registration of agent at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.407516 23678 master.cpp:5606] Registering agent at slave(763)@172.17.0.3:58408 (c66652dbe0e4) with id 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 I0614 14:35:33.407826 23682 registrar.cpp:493] Applied 1 operations in 64408ns; attempting to update the registry I0614 14:35:33.408944 23681 scheduler.cpp:361] Connected with the master at https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.410059 23676 scheduler.cpp:243] Sending SUBSCRIBE call to https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.410177 23682 registrar.cpp:550] Successfully updated the registry in 2.287104ms I0614 14:35:33.410325 23676 master.cpp:5653] Admitted agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.410758 23674 slave.cpp:4825] Received ping from slave-observer(696)@172.17.0.3:58408 I0614 14:35:33.410842 23676 master.cpp:5684] Registered agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0614 14:35:33.410918 23674 slave.cpp:1148] Registered with master [email protected]:58408; given agent ID 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 I0614 14:35:33.411008 23684 status_update_manager.cpp:184] Resuming sending status updates I0614 14:35:33.411108 23682 hierarchical.cpp:587] Added agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 (c66652dbe0e4) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0614 14:35:33.411195 23681 process.cpp:3778] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0614 14:35:33.411229 23674 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/meta/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/slave.info' I0614 14:35:33.411371 23682 hierarchical.cpp:1908] No allocations performed I0614 14:35:33.411417 23682 hierarchical.cpp:1493] Performed allocation for 1 agents in 166347ns I0614 14:35:33.411548 23674 slave.cpp:1206] Forwarding total oversubscribed resources {} I0614 14:35:33.411681 23675 master.cpp:6338] Received update of agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) with total oversubscribed resources {} I0614 14:35:33.450505 23676 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:42337 I0614 14:35:33.452900 23676 master.cpp:2511] Received subscription request for HTTP framework 'default' I0614 14:35:33.452947 23676 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0614 14:35:33.453380 23676 master.cpp:2647] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0614 14:35:33.454102 23686 master.hpp:2202] Sending heartbeat to 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.454313 23674 hierarchical.cpp:301] Added framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.455498 23674 hierarchical.cpp:1998] No inverse offers to send out! I0614 14:35:33.455565 23674 hierarchical.cpp:1493] Performed allocation for 1 agents in 1.115965ms I0614 14:35:33.456003 23685 master.cpp:7295] Sending 1 offers to framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) I0614 14:35:33.493912 23677 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.494881 23681 scheduler.cpp:676] Enqueuing event HEARTBEAT received from https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.495844 23677 scheduler.cpp:676] Enqueuing event OFFERS received from https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.498463 23680 scheduler.cpp:243] Sending ACCEPT call to https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.499621 23674 process.cpp:3778] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0614 14:35:33.538645 23673 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:42338 I0614 14:35:33.539999 23673 master.cpp:3914] Processing ACCEPT call for offers: [ 85163a58-2cca-40c4-83cb-511f1cb77d50-O0 ] on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) for framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) I0614 14:35:33.540125 23673 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 W0614 14:35:33.542580 23673 validation.cpp:1284] Executor 'default' for task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0614 14:35:33.542624 23673 validation.cpp:1296] Executor 'default' for task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0614 14:35:33.543268 23673 master.cpp:9314] Adding task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.543694 23673 master.cpp:4569] Launching task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.544324 23674 slave.cpp:1632] Got assigned task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' for framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.545162 23674 slave.cpp:1913] Authorizing task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' for framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.545264 23674 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 I0614 14:35:33.546347 23675 slave.cpp:2100] Launching task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' for framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.546978 23677 hierarchical.cpp:912] Updated allocation of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-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] I0614 14:35:33.547142 23675 paths.cpp:573] Trying to chown '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default/runs/aea0e1b3-e581-43b8-8a30-bfd6f7699a8a' to user 'mesos' I0614 14:35:33.547451 23675 slave.cpp:7086] Launching executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 with resources {} in work directory '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default/runs/aea0e1b3-e581-43b8-8a30-bfd6f7699a8a' I0614 14:35:33.548379 23675 slave.cpp:2329] Queued task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' for executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.548487 23675 slave.cpp:898] Successfully attached file '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default/runs/aea0e1b3-e581-43b8-8a30-bfd6f7699a8a' I0614 14:35:33.548545 23675 slave.cpp:898] Successfully attached file '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default/runs/aea0e1b3-e581-43b8-8a30-bfd6f7699a8a' I0614 14:35:33.548866 23675 slave.cpp:2795] Launching container aea0e1b3-e581-43b8-8a30-bfd6f7699a8a for executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.551764 23674 executor.cpp:192] Version: 1.4.0 I0614 14:35:33.572227 23682 executor.cpp:410] Connected with the agent I0614 14:35:33.573000 23672 executor.cpp:307] Sending SUBSCRIBE call to https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.574174 23687 process.cpp:3778] Handling HTTP event for process 'slave(763)' with path: '/slave(763)/api/v1/executor' I0614 14:35:33.616632 23681 http.cpp:1115] HTTP POST for /slave(763)/api/v1/executor from 172.17.0.3:42339 I0614 14:35:33.616861 23681 slave.cpp:3609] Received Subscribe request for HTTP executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.618275 23681 slave.cpp:2542] Sending queued task 'bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1' to executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (via HTTP) I0614 14:35:33.658776 23683 executor.cpp:725] Enqueuing event SUBSCRIBED received from https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.659756 23684 executor.cpp:725] Enqueuing event LAUNCH received from https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.662989 23685 executor.cpp:307] Sending UPDATE call to https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.664441 23684 process.cpp:3778] Handling HTTP event for process 'slave(763)' with path: '/slave(763)/api/v1/executor' I0614 14:35:33.703827 23679 http.cpp:1115] HTTP POST for /slave(763)/api/v1/executor from 172.17.0.3:42340 I0614 14:35:33.704113 23679 slave.cpp:4295] Handling status update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.704836 23684 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.704905 23684 status_update_manager.cpp:500] Creating StatusUpdate stream for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.705605 23684 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 to the agent I0614 14:35:33.705921 23674 slave.cpp:4735] Forwarding the update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 to [email protected]:58408 I0614 14:35:33.706198 23674 slave.cpp:4629] Status update manager successfully handled status update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.706367 23682 master.cpp:6483] Status update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 from agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.706454 23682 master.cpp:6545] Forwarding status update TASK_RUNNING (UUID: cbd23f3f-3302-4eae-b10d-890bec12b11d) for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.706934 23682 master.cpp:8556] Updating the state of task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0614 14:35:33.707409 23678 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.708008 23678 scheduler.cpp:676] Enqueuing event UPDATE received from https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.709885 23676 scheduler.cpp:243] Sending TEARDOWN call to https://172.17.0.3:58408/master/api/v1/scheduler I0614 14:35:33.710973 23672 process.cpp:3778] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0614 14:35:33.750603 23672 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:42338 I0614 14:35:33.750720 23672 master.cpp:7988] Removing framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) I0614 14:35:33.750747 23672 master.cpp:3200] Deactivating framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) I0614 14:35:33.750941 23672 master.cpp:8556] Updating the state of task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0614 14:35:33.750953 23675 hierarchical.cpp:410] Deactivated framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.751021 23674 slave.cpp:3098] Asked to shut down framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 by [email protected]:58408 I0614 14:35:33.751070 23674 slave.cpp:3123] Shutting down framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.751124 23674 slave.cpp:5590] Shutting down executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (via HTTP) I0614 14:35:33.751763 23672 master.cpp:8650] Removing task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.752146 23686 executor.cpp:725] Enqueuing event SHUTDOWN received from https://172.17.0.3:58408/slave(763)/api/v1/executor I0614 14:35:33.752207 23672 master.cpp:8679] Removing executor 'default' with resources {} of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.752267 23673 hierarchical.cpp:1177] 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 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 from framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.753270 23683 hierarchical.cpp:353] Removed framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 E0614 14:35:33.753549 23685 scheduler.cpp:649] End-Of-File received from master. The master closed the event stream I0614 14:35:33.754112 23676 scheduler.cpp:444] Re-detecting master I0614 14:35:33.755743 23676 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I0614 14:35:33.755797 23676 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I0614 14:35:33.755905 23676 scheduler.cpp:470] New master detected at [email protected]:58408 I0614 14:35:33.755939 23676 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0614 14:35:33.759176 23684 slave.cpp:5268] Executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 exited with status 0 I0614 14:35:33.759356 23684 slave.cpp:5368] Cleaning up executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (via HTTP) W0614 14:35:33.759512 23675 master.cpp:6600] Ignoring unknown exited executor 'default' of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 on agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.759834 23679 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default/runs/aea0e1b3-e581-43b8-8a30-bfd6f7699a8a' for gc 6.99999120833185days in the future I0614 14:35:33.760174 23684 slave.cpp:5464] Cleaning up framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.760171 23677 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000/executors/default' for gc 6.99999120401481days in the future I0614 14:35:33.760560 23677 status_update_manager.cpp:285] Closing status update streams for framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.760619 23677 status_update_manager.cpp:531] Cleaning up status update stream for task bd22c2ef-1db7-4d21-abe1-942e9b6b6fd1 of framework 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 I0614 14:35:33.760769 23673 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_a5Z6Xs/slaves/85163a58-2cca-40c4-83cb-511f1cb77d50-S0/frameworks/85163a58-2cca-40c4-83cb-511f1cb77d50-0000' for gc 6.99999119663111days in the future I0614 14:35:33.761234 23678 slave.cpp:817] Agent terminating I0614 14:35:33.761951 23684 master.cpp:1314] Agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) disconnected I0614 14:35:33.762079 23684 master.cpp:3237] Disconnecting agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.762162 23684 master.cpp:3256] Deactivating agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 at slave(763)@172.17.0.3:58408 (c66652dbe0e4) I0614 14:35:33.762327 23687 hierarchical.cpp:715] Agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 deactivated I0614 14:35:33.765908 23680 master.cpp:1156] Master terminating I0614 14:35:33.766683 23675 hierarchical.cpp:620] Removed agent 85163a58-2cca-40c4-83cb-511f1cb77d50-S0 W0614 14:35:33.768064 5248 master.hpp:2685] Failed to close HTTP pipe for 85163a58-2cca-40c4-83cb-511f1cb77d50-0000 (default) [ OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/0 (590 ms) [ RUN ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/1 E0614 14:35:33.774005 23688 process.cpp:968] Failed to accept socket: future discarded W0614 14:35:33.777961 23688 process.cpp:3103] Attempted to spawn a process (__http_connection__(1173)@172.17.0.3:58408) after finalizing libprocess! *** Aborted at 1497450933 (unix time) try "date -d @1497450933" if you are using GNU date *** I0614 14:35:33.780436 5248 process.cpp:1282] libprocess is initialized on 172.17.0.3:51002 with 16 worker threads I0614 14:35:33.782776 5248 cluster.cpp:162] Creating default 'local' authorizer I0614 14:35:33.786151 23696 master.cpp:436] Master a0a2b709-2d31-40b0-98c7-c4ffadab498b (c66652dbe0e4) started on 172.17.0.3:51002 I0614 14:35:33.786218 23696 master.cpp:438] 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/0FdVKf/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.4.0/_inst/share/mesos/webui" --work_dir="/tmp/0FdVKf/master" --zk_session_timeout="10secs" I0614 14:35:33.786670 23696 master.cpp:488] Master only allowing authenticated frameworks to register I0614 14:35:33.786687 23696 master.cpp:502] Master only allowing authenticated agents to register I0614 14:35:33.786697 23696 master.cpp:515] Master only allowing authenticated HTTP frameworks to register I0614 14:35:33.786708 23696 credentials.hpp:37] Loading credentials for authentication from '/tmp/0FdVKf/credentials' I0614 14:35:33.787050 23696 master.cpp:560] Using default 'crammd5' authenticator I0614 14:35:33.787201 23696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0614 14:35:33.787771 23696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0614 14:35:33.787987 23696 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0614 14:35:33.788142 23696 master.cpp:640] Authorization enabled I0614 14:35:33.788355 23699 whitelist_watcher.cpp:77] No whitelist given I0614 14:35:33.788374 23703 hierarchical.cpp:169] Initialized hierarchical allocator process I0614 14:35:33.790818 23696 master.cpp:2159] Elected as the leading master! I0614 14:35:33.790851 23696 master.cpp:1698] Recovering from registrar I0614 14:35:33.791064 23698 registrar.cpp:345] Recovering registrar I0614 14:35:33.793332 23690 registrar.cpp:389] Successfully fetched the registry (0B) in 2.201856ms I0614 14:35:33.793460 23690 registrar.cpp:493] Applied 1 operations in 36929ns; attempting to update the registry I0614 14:35:33.795522 23690 registrar.cpp:550] Successfully updated the registry in 1.997056ms I0614 14:35:33.795629 23690 registrar.cpp:422] Successfully recovered registrar I0614 14:35:33.796236 23696 hierarchical.cpp:207] Skipping recovery of hierarchical allocator: nothing to recover I0614 14:35:33.796219 23692 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register PC: @ 0x2b24f54e8bf8 process::PID<>::PID() I0614 14:35:33.800519 5248 cluster.cpp:448] Creating default 'local' authorizer I0614 14:35:33.802325 23703 slave.cpp:248] Mesos agent started on (764)@172.17.0.3:51002 I0614 14:35:33.802374 23703 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_CCtgLX" I0614 14:35:33.802884 23703 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/credential' I0614 14:35:33.802999 5248 scheduler.cpp:184] Version: 1.4.0 I0614 14:35:33.803117 23703 slave.cpp:281] Agent using credential for: test-principal I0614 14:35:33.803153 23703 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_kAVCLF/http_credentials' I0614 14:35:33.803565 23703 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0614 14:35:33.803701 23701 scheduler.cpp:470] New master detected at [email protected]:51002 I0614 14:35:33.803771 23703 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0614 14:35:33.804069 23703 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0614 14:35:33.804167 23703 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0614 14:35:33.804395 23703 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0614 14:35:33.804489 23703 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0614 14:35:33.805428 23701 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0614 14:35:33.805912 23703 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0614 14:35:33.806010 23703 slave.cpp:560] Agent attributes: [ ] I0614 14:35:33.806036 23703 slave.cpp:565] Agent hostname: c66652dbe0e4 I0614 14:35:33.806241 23701 status_update_manager.cpp:177] Pausing sending status updates I0614 14:35:33.807929 23696 state.cpp:62] Recovering state from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_CCtgLX/meta' I0614 14:35:33.808461 23700 status_update_manager.cpp:203] Recovering status update manager I0614 14:35:33.808933 23701 slave.cpp:6127] Finished recovery I0614 14:35:33.809523 23701 slave.cpp:6309] Querying resource estimator for oversubscribable resources I0614 14:35:33.809784 23701 slave.cpp:945] New master detected at [email protected]:51002 I0614 14:35:33.809813 23695 status_update_manager.cpp:177] Pausing sending status updates I0614 14:35:33.809870 23701 slave.cpp:980] Detecting new master I0614 14:35:33.810035 23701 slave.cpp:6323] Received oversubscribable resources {} from the resource estimator *** SIGSEGV (@0x0) received by PID 5248 (TID 0x2b2502ecd700) from PID 0; stack trace: *** @ 0x2b2515fd5417 (unknown) @ 0x2b2515fd96f9 (unknown) @ 0x2b24f7f97330 (unknown) @ 0x2b24f54e8bf8 process::PID<>::PID() @ 0x2b24f54c5e7c process::Process<>::self() @ 0x2b24f54aab5a process::ProcessManager::spawn() @ 0x2b24f54b1396 process::spawn() @ 0x2b24f53e6445 process::spawn<>() @ 0x2b24f53e638b process::http::Connection::Data::Data() @ 0x2b24f53e6326 _ZN9__gnu_cxx13new_allocatorIN7process4http10Connection4DataEE9constructIS4_JRKNS1_7network8internal6SocketINS7_7AddressEEEEEEvPT_DpOT0_ @ 0x2b24f53e62cd _ZNSt16allocator_traitsISaIN7process4http10Connection4DataEEE12_S_constructIS3_JRKNS0_7network8internal6SocketINS7_7AddressEEEEEENSt9enable_ifIXsr18__construct_helperIT_DpT0_EE5valueEvE4typeERS4_PSF_DpOSG_ @ 0x2b24f53e5f20 _ZNSt16allocator_traitsISaIN7process4http10Connection4DataEEE9constructIS3_JRKNS0_7network8internal6SocketINS7_7AddressEEEEEEDTcl12_S_constructfp_fp0_spclsr3stdE7forwardIT0_Efp1_EEERS4_PT_DpOSE_ @ 0x2b24f53e5e4a _ZNSt23_Sp_counted_ptr_inplaceIN7process4http10Connection4DataESaIS3_ELN9__gnu_cxx12_Lock_policyE2EEC2IJRKNS0_7network8internal6SocketINS9_7AddressEEEEEES4_DpOT_ @ 0x2b24f53e5cfb _ZN9__gnu_cxx13new_allocatorISt23_Sp_counted_ptr_inplaceIN7process4http10Connection4DataESaIS5_ELNS_12_Lock_policyE2EEE9constructIS8_JKS6_RKNS2_7network8internal6SocketINSC_7AddressEEEEEEvPT_DpOT0_ @ 0x2b24f53e5c62 _ZNSt16allocator_traitsISaISt23_Sp_counted_ptr_inplaceIN7process4http10Connection4DataESaIS4_ELN9__gnu_cxx12_Lock_policyE2EEEE12_S_constructIS8_JKS5_RKNS1_7network8internal6SocketINSD_7AddressEEEEEENSt9enable_ifIXsr18__construct_helperIT_DpT0_EE5valueEvE4typeERS9_PSL_DpOSM_ @ 0x2b24f53e5b55 _ZNSt16allocator_traitsISaISt23_Sp_counted_ptr_inplaceIN7process4http10Connection4DataESaIS4_ELN9__gnu_cxx12_Lock_policyE2EEEE9constructIS8_JKS5_RKNS1_7network8internal6SocketINSD_7AddressEEEEEEDTcl12_S_constructfp_fp0_spclsr3stdE7forwardIT0_Efp1_EEERS9_PT_DpOSK_ @ 0x2b24f53e59c8 _ZNSt14__shared_countILN9__gnu_cxx12_Lock_policyE2EEC2IN7process4http10Connection4DataESaIS7_EJRKNS4_7network8internal6SocketINS9_7AddressEEEEEESt19_Sp_make_shared_tagPT_RKT0_DpOT1_ @ 0x2b24f53e58df _ZNSt12__shared_ptrIN7process4http10Connection4DataELN9__gnu_cxx12_Lock_policyE2EEC2ISaIS3_EJRKNS0_7network8internal6SocketINS9_7AddressEEEEEESt19_Sp_make_shared_tagRKT_DpOT0_ @ 0x2b24f53e586d _ZNSt10shared_ptrIN7process4http10Connection4DataEEC2ISaIS3_EJRKNS0_7network8internal6SocketINS7_7AddressEEEEEESt19_Sp_make_shared_tagRKT_DpOT0_ @ 0x2b24f53e57af _ZSt15allocate_sharedIN7process4http10Connection4DataESaIS3_EJRKNS0_7network8internal6SocketINS5_7AddressEEEEESt10shared_ptrIT_ERKT0_DpOT1_ @ 0x2b24f53c789d _ZSt11make_sharedIN7process4http10Connection4DataEJRKNS0_7network8internal6SocketINS4_7AddressEEEEESt10shared_ptrIT_EDpOT0_ @ 0x2b24f5380676 process::http::Connection::Connection() @ 0x2b24f53b57cf process::http::connect()::$_1::operator()() @ 0x2b24f53b540f std::_Function_handler<>::_M_invoke() @ 0x2b24f461bc4d std::function<>::operator()() @ 0x2b24f461bbf0 _ZNSt5_BindIFSt8functionIFN7process6FutureINS1_4http10ConnectionEEEvEEvEE6__callIS5_JRK7NothingEJEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE @ 0x2b24f461bba5 _ZNSt5_BindIFSt8functionIFN7process6FutureINS1_4http10ConnectionEEEvEEvEEclIJRK7NothingES5_EET0_DpOT_ @ 0x2b24f461b844 std::_Function_handler<>::_M_invoke() @ 0x2b24f461d8ba std::function<>::operator()() @ 0x2b24f461c481 process::internal::thenf<>() @ 0x2b24f461d153 _ZNSt5_BindIFPFvRKSt8functionIFN7process6FutureINS1_4http10ConnectionEEERK7NothingEERKSt10shared_ptrINS1_7PromiseIS4_EEERKNS2_IS6_EEESA_SG_St12_PlaceholderILi1EEEE6__callIvJSL_EJLm0ELm1ELm2EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE @ 0x2b24f461d096 _ZNSt5_BindIFPFvRKSt8functionIFN7process6FutureINS1_4http10ConnectionEEERK7NothingEERKSt10shared_ptrINS1_7PromiseIS4_EEERKNS2_IS6_EEESA_SG_St12_PlaceholderILi1EEEEclIJSL_EvEET0_DpOT_ make[4]: *** [check-local] Segmentation fault make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.4.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1497447530-9619 Untagged: mesos-1497447530-9619:latest Deleted: sha256:b8f177bffc409473fa5422e5953796d5b591900b94d9616f8e8a3d8f230993f4 Deleted: sha256:6a8d8dcf6c0b73a48890b8361555aa2f8802f311f325cbcd6405eafde34e307b Deleted: sha256:cbfc9717ad77123b0c7fe1e7d55843a76d36dd45d698d5bdcdfe3ae4ebc8b118 Deleted: sha256:7805a03bc7da1158429a5db7fba202c086a277c10240eacc8a83d46202589563 Deleted: sha256:7dd2a2209d193a8c6082d0f4e222e8abae232197873cdc935c22c8652ef7549b Deleted: sha256:1fe591330933ba758e40f398936a65a452fa555b43744f8291663dd8074f2e95 Deleted: sha256:2d7083a99736869ed957dd9344c43427823a25f3f7f5f030f2897ace2616f070 Deleted: sha256:be5dd55973eb084ab92dcdc23f01c3e1f20a83390d5603bcaa28831d4998f5e3 Deleted: sha256:8433591818508629c1ff039d50095960d555bc2ad455de87dcd957ce5d7281ea Deleted: sha256:dc144132bd690cc94f65d2f8beb165502a103513433e55d237e30279879d761f Deleted: sha256:73fe3595f6099b873a0ad09ff4b80f02767507640aa08700972d751a946463fd Deleted: sha256:b7a7b1541aca34308f1fd1e1258ddbb65259b9517b5750fd99b150b4e0f29b9a Deleted: sha256:070625ca8894824094e69559ba11da3e537dbeb2c800e99e7ee742eb97abbaec Deleted: sha256:ef28ead22469756f37a7e81e2d501b3fcc72b9223c4c47a434f4711a01916efd Deleted: sha256:75b606a2accd66298be786eb3249ae5ef0ce050c9a5d2d122fac8bcec5b3d63f Deleted: sha256:f03368b05c38c8372b3d95bbaa6e80152de4c9ef5973d98ca817d5928a137050 Deleted: sha256:6690f3eaa9fc0beedc9b998aa1c869052ddad50b65c8c4742166ec9d6642d0a1 Deleted: sha256:f81e326887ae7cc248f31348ed17f550f06e651bc5d76358296296b5cc1d9863 Deleted: sha256:ec29bab67d7f4bbd1fb682b63145ff8571cd38b002f0bac4bbbf575f364357f1 Deleted: sha256:9aa957476fde09214efdac5d05f5f9ee4a9d3fad7b295a3b85985334bd55e351 Deleted: sha256:9ed738d140afeb683f76b711dcf1157610d60d1a5d3ea13c26f8e0f539fc7113 Deleted: sha256:2962cf54e30288c12b472a378404b833b24c88aecfcbc64d64ee4d429fdaff49 Deleted: sha256:36f3dfe529ecb6b28515533041acbc2a74546495ebd3d98c848914660db8703b Deleted: sha256:8aa4616429a622f622a17d62f8438241cf60890b885b7085757eb7df13b23ad9 Build step 'Execute shell' marked build as failure
