See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4334/display/redirect>
------------------------------------------ [...truncated 29.20 MB...] 3: I1017 22:39:18.017730 26240 hierarchical.cpp:2035] No inverse offers to send out! 3: I1017 22:39:18.017768 26240 hierarchical.cpp:1488] Performed allocation for 1 agents in 988044ns 3: I1017 22:39:18.018177 26238 master.cpp:7766] Sending 1 offers to framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 3: I1017 22:39:18.053735 26250 scheduler.cpp:738] Enqueuing event SUBSCRIBED received from https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.054285 26239 scheduler.cpp:738] Enqueuing event HEARTBEAT received from https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.054903 26246 scheduler.cpp:738] Enqueuing event OFFERS received from https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.056700 26245 scheduler.cpp:249] Sending ACCEPT call to https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.058112 26242 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1017 22:39:18.098453 26235 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:59148 3: I1017 22:39:18.099783 26235 master.cpp:9389] Removing offer 3c052876-1b13-4208-9cd6-6bdb824242fc-O0 3: I1017 22:39:18.099961 26235 master.cpp:4196] Processing ACCEPT call for offers: [ 3c052876-1b13-4208-9cd6-6bdb824242fc-O0 ] on agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) for framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 3: I1017 22:39:18.100062 26235 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 6c2371be-e68f-464d-8c29-3c6784ae14de 3: W1017 22:39:18.102325 26231 validation.cpp:1338] Executor 'default' for task '6c2371be-e68f-464d-8c29-3c6784ae14de' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. 3: W1017 22:39:18.102360 26231 validation.cpp:1350] Executor 'default' for task '6c2371be-e68f-464d-8c29-3c6784ae14de' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. 3: I1017 22:39:18.102870 26231 master.cpp:10135] Adding task 6c2371be-e68f-464d-8c29-3c6784ae14de 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 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.103504 26231 master.cpp:4879] Launching task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 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 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.104267 26232 slave.cpp:1747] Got assigned task '6c2371be-e68f-464d-8c29-3c6784ae14de' for framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.104928 26232 slave.cpp:2015] Authorizing task '6c2371be-e68f-464d-8c29-3c6784ae14de' for framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.104984 26232 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 6c2371be-e68f-464d-8c29-3c6784ae14de 3: I1017 22:39:18.105468 26231 hierarchical.cpp:887] Updated allocation of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 on agent 3c052876-1b13-4208-9cd6-6bdb824242fc-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] 3: I1017 22:39:18.105878 26233 slave.cpp:2183] Launching task '6c2371be-e68f-464d-8c29-3c6784ae14de' for framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.109004 26233 paths.cpp:605] Trying to chown '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' to user 'mesos' 3: I1017 22:39:18.109282 26233 slave.cpp:7283] Launching executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 with resources [] in work directory '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' 3: I1017 22:39:18.110580 26233 slave.cpp:2411] Queued task '6c2371be-e68f-464d-8c29-3c6784ae14de' for executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.110740 26233 slave.cpp:944] Successfully attached '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' to virtual path '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/latest' 3: I1017 22:39:18.110788 26233 slave.cpp:944] Successfully attached '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' to virtual path '/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/latest' 3: I1017 22:39:18.110817 26233 slave.cpp:944] Successfully attached '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' to virtual path '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' 3: I1017 22:39:18.111239 26233 slave.cpp:2874] Launching container ea409137-b08c-42b6-829b-24c2bc1b50e0 for executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.114413 26242 executor.cpp:192] Version: 1.5.0 3: I1017 22:39:18.127985 26235 executor.cpp:410] Connected with the agent 3: I1017 22:39:18.128598 26248 executor.cpp:307] Sending SUBSCRIBE call to https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.129961 26231 process.cpp:3929] Handling HTTP event for process 'slave(767)' with path: '/slave(767)/api/v1/executor' 3: I1017 22:39:18.171505 26251 http.cpp:1185] HTTP POST for /slave(767)/api/v1/executor from 172.17.0.2:59156 3: I1017 22:39:18.171746 26251 slave.cpp:3743] Received Subscribe request for HTTP executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.173171 26251 slave.cpp:2613] Sending queued task '6c2371be-e68f-464d-8c29-3c6784ae14de' to executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (via HTTP) 3: I1017 22:39:18.214531 26245 executor.cpp:725] Enqueuing event SUBSCRIBED received from https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.215415 26252 executor.cpp:725] Enqueuing event LAUNCH received from https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.216187 26237 executor.cpp:307] Sending UPDATE call to https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.217520 26234 process.cpp:3929] Handling HTTP event for process 'slave(767)' with path: '/slave(767)/api/v1/executor' 3: I1017 22:39:18.255843 26241 http.cpp:1185] HTTP POST for /slave(767)/api/v1/executor from 172.17.0.2:59158 3: I1017 22:39:18.256194 26241 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.256955 26233 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.257009 26233 status_update_manager.cpp:500] Creating StatusUpdate stream for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.257529 26233 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 to the agent 3: I1017 22:39:18.257761 26232 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 to [email protected]:37214 3: I1017 22:39:18.257966 26232 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.258312 26249 master.cpp:6993] Status update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 from agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.258435 26249 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 976f59a1-87bd-44ea-9dbc-104456120643) for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.258894 26236 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.258916 26249 master.cpp:9157] Updating the state of task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1017 22:39:18.259747 26231 scheduler.cpp:738] Enqueuing event UPDATE received from https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.260844 26237 scheduler.cpp:249] Sending TEARDOWN call to https://172.17.0.2:37214/master/api/v1/scheduler 3: I1017 22:39:18.262264 26247 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1017 22:39:18.302798 26235 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:59148 3: I1017 22:39:18.303030 26235 master.cpp:8459] Removing framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 3: I1017 22:39:18.303063 26235 master.cpp:3299] Deactivating framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 3: I1017 22:39:18.303269 26250 hierarchical.cpp:412] Deactivated framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.303323 26244 slave.cpp:3211] Asked to shut down framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 by [email protected]:37214 3: I1017 22:39:18.303357 26244 slave.cpp:3236] Shutting down framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.303366 26235 master.cpp:9157] Updating the state of task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) 3: I1017 22:39:18.303397 26244 slave.cpp:5743] Shutting down executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (via HTTP) 3: I1017 22:39:18.304056 26242 executor.cpp:725] Enqueuing event SHUTDOWN received from https://172.17.0.2:37214/slave(767)/api/v1/executor 3: I1017 22:39:18.304481 26231 hierarchical.cpp:1163] 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 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 from framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.304253 26235 master.cpp:9251] Removing task 6c2371be-e68f-464d-8c29-3c6784ae14de 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 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 on agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.304837 26235 master.cpp:9280] Removing executor 'default' with resources [] of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 on agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.305439 26236 hierarchical.cpp:355] Removed framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: E1017 22:39:18.305750 26241 scheduler.cpp:711] End-Of-File received from master. The master closed the event stream 3: I1017 22:39:18.306223 26232 scheduler.cpp:470] Re-detecting master 3: I1017 22:39:18.306574 26232 scheduler.cpp:421] Ignoring disconnection attempt from stale connection 3: I1017 22:39:18.306604 26232 scheduler.cpp:421] Ignoring disconnection attempt from stale connection 3: I1017 22:39:18.306872 26237 scheduler.cpp:496] New master detected at [email protected]:37214 3: I1017 22:39:18.309061 26237 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1017 22:39:18.312186 26251 slave.cpp:5408] Executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 exited with status 0 3: I1017 22:39:18.312319 26251 slave.cpp:5512] Cleaning up executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (via HTTP) 3: W1017 22:39:18.312527 26248 master.cpp:7110] Ignoring unknown exited executor 'default' of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 on agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.312655 26248 gc.cpp:90] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default/runs/ea409137-b08c-42b6-829b-24c2bc1b50e0' for gc 6.99999638287111days in the future 3: I1017 22:39:18.312827 26251 slave.cpp:5619] Cleaning up framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.312860 26248 gc.cpp:90] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000/executors/default' for gc 6.9999963799763days in the future 3: I1017 22:39:18.312916 26242 status_update_manager.cpp:285] Closing status update streams for framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.312965 26242 status_update_manager.cpp:531] Cleaning up status update stream for task 6c2371be-e68f-464d-8c29-3c6784ae14de of framework 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 3: I1017 22:39:18.313026 26248 gc.cpp:90] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_0_GBkA4h/slaves/3c052876-1b13-4208-9cd6-6bdb824242fc-S0/frameworks/3c052876-1b13-4208-9cd6-6bdb824242fc-0000' for gc 6.99999637810074days in the future 3: I1017 22:39:18.313449 26234 slave.cpp:869] Agent terminating 3: I1017 22:39:18.313673 26241 master.cpp:1303] Agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) disconnected 3: I1017 22:39:18.313690 26241 master.cpp:3336] Disconnecting agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.313788 26241 master.cpp:3355] Deactivating agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 at slave(767)@172.17.0.2:37214 (5e87c181c147) 3: I1017 22:39:18.313875 26240 hierarchical.cpp:690] Agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 deactivated 3: I1017 22:39:18.317210 18192 master.cpp:1145] Master terminating 3: I1017 22:39:18.317872 26230 hierarchical.cpp:626] Removed agent 3c052876-1b13-4208-9cd6-6bdb824242fc-S0 3: W1017 22:39:18.318482 18192 master.hpp:2882] Failed to close HTTP pipe for 3c052876-1b13-4208-9cd6-6bdb824242fc-0000 (default) 3: [ OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/0 (1034 ms) 3: [ RUN ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/1 3: I1017 22:39:18.325575 18192 process.cpp:1383] libprocess is initialized on 172.17.0.2:34430 with 24 worker threads 3: I1017 22:39:18.327528 18192 cluster.cpp:162] Creating default 'local' authorizer 3: I1017 22:39:18.330206 26254 master.cpp:445] Master c0f324cf-8e10-4706-8b38-fa4319a1fce2 (5e87c181c147) started on 172.17.0.2:34430 3: I1017 22:39:18.330250 26254 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/2E2cBy/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="/usr/local/share/mesos/webui" --work_dir="/tmp/2E2cBy/master" --zk_session_timeout="10secs" 3: I1017 22:39:18.330646 26254 master.cpp:496] Master only allowing authenticated frameworks to register 3: I1017 22:39:18.330657 26254 master.cpp:502] Master only allowing authenticated agents to register 3: I1017 22:39:18.330663 26254 master.cpp:508] Master only allowing authenticated HTTP frameworks to register 3: I1017 22:39:18.330670 26254 credentials.hpp:37] Loading credentials for authentication from '/tmp/2E2cBy/credentials' 3: I1017 22:39:18.331048 26254 master.cpp:552] Using default 'crammd5' authenticator 3: I1017 22:39:18.331212 26254 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1017 22:39:18.331383 26254 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1017 22:39:18.331512 26254 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1017 22:39:18.331638 26254 master.cpp:631] Authorization enabled 3: I1017 22:39:18.331784 26259 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1017 22:39:18.331830 26264 whitelist_watcher.cpp:77] No whitelist given 3: I1017 22:39:18.334692 26268 master.cpp:2198] Elected as the leading master! 3: I1017 22:39:18.334718 26268 master.cpp:1687] Recovering from registrar 3: I1017 22:39:18.334931 26267 registrar.cpp:347] Recovering registrar 3: I1017 22:39:18.336866 26271 registrar.cpp:391] Successfully fetched the registry (0B) in 1.903872ms 3: I1017 22:39:18.337015 26271 registrar.cpp:495] Applied 1 operations in 44799ns; attempting to update the registry 3: I1017 22:39:18.337548 26261 registrar.cpp:552] Successfully updated the registry in 463872ns 3: I1017 22:39:18.337657 26261 registrar.cpp:424] Successfully recovered registrar 3: I1017 22:39:18.338021 26261 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1017 22:39:18.338125 26261 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1017 22:39:18.343544 18192 process.cpp:3194] Attempted to spawn already running process [email protected]:34430 3: I1017 22:39:18.343850 18192 cluster.cpp:448] Creating default 'local' authorizer 3: I1017 22:39:18.346282 26275 slave.cpp:254] Mesos agent started on (768)@172.17.0.2:34430 3: I1017 22:39:18.346310 26275 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_rEVWeI/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_rEVWeI/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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_rEVWeI/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_rEVWeI/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_rEVWeI/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_rEVWeI/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/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_rEVWeI" --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_3j53pD" --zk_session_timeout="10secs" 3: I1017 22:39:18.346758 26275 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_rEVWeI/credential' 3: I1017 22:39:18.346935 26275 slave.cpp:287] Agent using credential for: test-principal 3: I1017 22:39:18.346949 26275 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_rEVWeI/http_credentials' 3: I1017 22:39:18.347084 18192 scheduler.cpp:190] Version: 1.5.0 3: I1017 22:39:18.347252 26275 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1017 22:39:18.347373 26275 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1017 22:39:18.347390 26276 scheduler.cpp:313] Using default 'basic' HTTP authenticatee 3: I1017 22:39:18.347568 26275 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1017 22:39:18.347656 26275 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1017 22:39:18.347718 26265 scheduler.cpp:496] New master detected at [email protected]:34430 3: I1017 22:39:18.347822 26275 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1017 22:39:18.347909 26275 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1017 22:39:18.348875 26265 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1017 22:39:18.349716 26275 slave.cpp:585] Agent resources: [{"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"}] 3: I1017 22:39:18.349964 26275 slave.cpp:593] Agent attributes: [ ] 3: I1017 22:39:18.349975 26275 slave.cpp:602] Agent hostname: 5e87c181c147 3: I1017 22:39:18.350200 26263 status_update_manager.cpp:177] Pausing sending status updates 3: I1017 22:39:18.351084 26261 scheduler.cpp:387] Connected with the master at http://172.17.0.2:34430/master/api/v1/scheduler 3: I1017 22:39:18.351814 26260 state.cpp:64] Recovering state from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_3j53pD/meta' 3: I1017 22:39:18.352349 26272 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:34430/master/api/v1/scheduler 3: I1017 22:39:18.357415 26271 status_update_manager.cpp:203] Recovering status update manager 3: I1017 22:39:18.357831 26265 slave.cpp:6310] Finished recovery 3: I1017 22:39:18.358322 26265 slave.cpp:6492] Querying resource estimator for oversubscribable resources 3: I1017 22:39:18.358570 26265 status_update_manager.cpp:177] Pausing sending status updates 3: I1017 22:39:18.358717 26264 slave.cpp:993] New master detected at [email protected]:34430 3: I1017 22:39:18.358770 26270 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1017 22:39:18.358785 26264 slave.cpp:1028] Detecting new master 3: I1017 22:39:18.358881 26264 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator 3: I1017 22:39:18.360157 26258 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44788 3: I1017 22:39:18.361907 26258 master.cpp:2580] Received subscription request for HTTP framework 'default' 3: I1017 22:39:18.361943 26258 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1017 22:39:18.362399 26263 master.cpp:2716] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1017 22:39:18.363119 26256 master.hpp:349] Sending heartbeat to framework c0f324cf-8e10-4706-8b38-fa4319a1fce2-0000 3: I1017 22:39:18.363212 26259 hierarchical.cpp:303] Added framework c0f324cf-8e10-4706-8b38-fa4319a1fce2-0000 3: I1017 22:39:18.363378 26259 hierarchical.cpp:1945] No allocations performed 3: I1017 22:39:18.363416 26259 hierarchical.cpp:2035] No inverse offers to send out! 3: I1017 22:39:18.363456 26259 hierarchical.cpp:1488] Performed allocation for 0 agents in 126355ns 3: I1017 22:39:18.364045 26259 scheduler.cpp:738] Enqueuing event SUBSCRIBED received from http://172.17.0.2:34430/master/api/v1/scheduler 3: I1017 22:39:18.364399 26259 scheduler.cpp:738] Enqueuing event HEARTBEAT received from http://172.17.0.2:34430/master/api/v1/scheduler 3: *** Aborted at 1508279958 (unix time) try "date -d @1508279958" if you are using GNU date *** 3: I1017 22:39:18.366111 26277 slave.cpp:1055] Authenticating with master [email protected]:34430 3: PC: @ 0x2b5543a7e6d0 process::EventQueue::Consumer::empty() 3: *** SIGSEGV (@0x8) received by PID 18192 (TID 0x2b5550fd5700) from PID 8; stack trace: *** 3: @ 0x2b5544762330 (unknown) 3: I1017 22:39:18.369292 26277 slave.cpp:1064] Using default CRAM-MD5 authenticatee 3: I1017 22:39:18.369549 26256 authenticatee.cpp:121] Creating new client SASL connection 3: I1017 22:39:18.369860 26256 master.cpp:7936] Authenticating slave(768)@172.17.0.2:34430 3: I1017 22:39:18.369957 26269 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1402)@172.17.0.2:34430 3: I1017 22:39:18.370194 26264 authenticator.cpp:98] Creating new server SASL connection 3: @ 0x2b5543a7e6d0 process::EventQueue::Consumer::empty() 3: I1017 22:39:18.370456 26264 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1017 22:39:18.370477 26264 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1017 22:39:18.370570 26264 authenticator.cpp:204] Received SASL authentication start 3: I1017 22:39:18.370611 26264 authenticator.cpp:326] Authentication requires more steps 3: I1017 22:39:18.370694 26264 authenticatee.cpp:259] Received SASL authentication step 3: I1017 22:39:18.370784 26264 authenticator.cpp:232] Received SASL authentication step 3: I1017 22:39:18.370805 26264 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5e87c181c147' server FQDN: '5e87c181c147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1017 22:39:18.370815 26264 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1017 22:39:18.370849 26264 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1017 22:39:18.370865 26264 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5e87c181c147' server FQDN: '5e87c181c147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1017 22:39:18.370874 26264 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1017 22:39:18.370880 26264 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1017 22:39:18.370894 26264 authenticator.cpp:318] Authentication success 3: I1017 22:39:18.371058 26264 authenticatee.cpp:299] Authentication success 3: I1017 22:39:18.371134 26274 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(768)@172.17.0.2:34430 3: I1017 22:39:18.371165 26259 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1402)@172.17.0.2:34430 3: I1017 22:39:18.371251 26264 slave.cpp:1147] Successfully authenticated with master [email protected]:34430 3: I1017 22:39:18.371493 26264 slave.cpp:1626] Will retry registration in 16.293724ms if necessary 3: I1017 22:39:18.371773 26264 master.cpp:5801] Received register agent message from slave(768)@172.17.0.2:34430 (5e87c181c147) 3: I1017 22:39:18.371800 26264 master.cpp:3838] Authorizing agent with principal 'test-principal' 3: @ 0x2b5543a5ff39 process::ProcessManager::resume() 3: I1017 22:39:18.372236 26273 master.cpp:5861] Authorized registration of agent at slave(768)@172.17.0.2:34430 (5e87c181c147) 3: I1017 22:39:18.372342 26273 master.cpp:5954] Registering agent at slave(768)@172.17.0.2:34430 (5e87c181c147) with id c0f324cf-8e10-4706-8b38-fa4319a1fce2-S0 3: @ 0x2b5543a6ff01 process::ProcessManager::init_threads()::$_9::operator()() 3: I1017 22:39:18.372710 26257 registrar.cpp:495] Applied 1 operations in 79759ns; attempting to update the registry 3: @ 0x2b5543a6fe45 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE 3: @ 0x2b5543a6fe15 std::_Bind_simple<>::operator()() 3: @ 0x2b5543a6fdec std::thread::_Impl<>::_M_run() 3: @ 0x2b55483bea60 (unknown) 3: @ 0x2b554475a184 start_thread 3: I1017 22:39:18.374385 26257 registrar.cpp:552] Successfully updated the registry in 1.624064ms 3: @ 0x2b5548c2affd (unknown) 3/3 Test #3: MesosTests .......................***Exception: SegFault295.23 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 333.99 sec The following tests FAILED: 3 - MesosTests (SEGFAULT) Errors while running CTest make[3]: *** [CMakeFiles/check] Error 8 make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1508278287-4484 Untagged: mesos-1508278287-4484:latest Deleted: sha256:bbb3831095b59e7311c8ab1fdd53d8c1b975cd15ba4a2fb57818cea2767384b2 Deleted: sha256:4be4cf739b62e7e54f754af7aafdc71326f72238790fd9680e617e96b133f34f Deleted: sha256:f04a58b877466816b00f229e8eeb95ee72461adb4dab069f4028d594fc24eaf9 Deleted: sha256:f6077a67600f4587c423a0b82c2e5f308e7ea9375ce9c2b0cc41ea5e8d3d99e6 Deleted: sha256:906df7dd978215042322fb88fa0f213007677b93d326e985f837a2bb53b077f5 Deleted: sha256:73ae3ab9fbddae6f8614013471911c7844593e1b941a5696af28c3ea2b670d0a Deleted: sha256:fa181bcd598674102e1b6a0aefbb6a984d11c5387bfd6b09c7027405f85dd716 Deleted: sha256:53912773818fe39825b8365b71809f4762a1c96862efbc3b2e2e8d76eb24cda0 Deleted: sha256:2b7537ebc4e19e1370d6478bf5a9b600ce0bc6d9ecd3665a14a3c313c483661b Deleted: sha256:0f3efb16db104f8c2a8051007a4cb71be186150456d0bbecbc921caadbb8515e Deleted: sha256:c39447a1fa4cb8ba58e28b5e75d6710f12b3daf8d5cf287a83a00ae671fc79e2 Deleted: sha256:aeddda34c920f696ad7b03903558b182e10f333753275cb3b52f8ceddff7cb44 Deleted: sha256:fb196fd0cc3b54534b4ebc83f98105fd18904cc8cd332791b0771faa51485712 Deleted: sha256:db6768c02e1109b8b87128232dab62283b095ac220fb2376b04fbb18ce6ee669 Deleted: sha256:09ed9bbfd6fa0f255c65639887109552cba5d50fb1d57c60a88d072742ed61d7 Deleted: sha256:31977f38095f27dcb347143d03d766d6fb9c3d882f8d858b63ba5765e94d3a00 Deleted: sha256:f9b840d3bcc17d7262a72de1440b5ae6167ce3a5fadfe4eae16205c1da79ecba Deleted: sha256:16fd6c8c30cbec103f60d3eae244969186d0ff07053b15fbc49b37133149a595 Deleted: sha256:5b355141cb7d835097328b83448e6cea2c8dddccc740cc8e827ba418794e57ee Deleted: sha256:6f5c9a0e4743696cc1b29c7580163a724847ec7fd6de721cd066882f1563608a Deleted: sha256:83543a5b3833d4fd091f109030f1b54490c87674a0f58ffacda5705dd2c36998 Deleted: sha256:5eb632e9cd428e7b3066a133c91f50e791cf511cc835ab54b45e1c550e60db33 Deleted: sha256:8b24e4e5e3b8a2db87bc2b31ac8b0ffc07999f613e355a97dfcd3269db938983 Deleted: sha256:0cca15eee541643bb935865994f428a12c277c42b414cad91e115bb991c56144 Deleted: sha256:bb90edbe2db890b3f1784e70b8da2678353efeae38c0146a64d6adfb32397546 Deleted: sha256:28bed85bb149e9c5c6fcc272a58c62054904f90e3bd5148c61a54c779675e5aa Deleted: sha256:ff14654c6e50366346de75e74f27ff2f8c8ce1821c5f5963e58ee919886f9f34 Deleted: sha256:9923a29b463a1b741aabcf2343edcafb71cfddfd5e3752ba9e2da2971bf12203 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
