See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,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)/4489/display/redirect?page=changes>
Changes: [yujie.jay] Added an event to publish resources. [yujie.jay] Started `LocalResourceProviderDaemon` after obtaining the slave ID. [yujie.jay] Added an optional `additional_chars` parameter to `http::encode`. ------------------------------------------ [...truncated 30.13 MB...] 3: I1122 05:32:33.414541 20042 master.cpp:6036] Received register agent message from slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.414690 20042 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1122 05:32:33.415076 20047 master.cpp:6098] Authorized registration of agent at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.415184 20047 master.cpp:6191] Registering agent at slave(808)@172.17.0.2:41132 (4ab10b862c73) with id 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 3: I1122 05:32:33.415642 20046 registrar.cpp:495] Applied 1 operations in 66578ns; attempting to update the registry 3: I1122 05:32:33.416268 20046 registrar.cpp:552] Successfully updated the registry in 572928ns 3: I1122 05:32:33.416476 20052 master.cpp:6240] Admitted agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.417227 20041 slave.cpp:1207] Registered with master [email protected]:41132; given agent ID 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 3: I1122 05:32:33.417322 20040 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1122 05:32:33.417145 20052 master.cpp:6273] Registered agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 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"}] 3: I1122 05:32:33.417454 20045 hierarchical.cpp:600] Added agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 (4ab10b862c73) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1122 05:32:33.417567 20041 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/meta/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/slave.info' 3: I1122 05:32:33.417992 20041 slave.cpp:1298] Forwarding total oversubscribed resources {} 3: I1122 05:32:33.418189 20054 master.cpp:7085] Received update of agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) with total oversubscribed resources {} 3: I1122 05:32:33.418347 20054 master.cpp:7103] Ignoring update on agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) as it reports no changes 3: I1122 05:32:33.418602 20045 hierarchical.cpp:1457] Performed allocation for 1 agents in 995016ns 3: I1122 05:32:33.419011 20038 master.cpp:8136] Sending 1 offers to framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.421713 20048 scheduler.cpp:739] Enqueuing event OFFERS received from http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.423161 20055 scheduler.cpp:247] Sending ACCEPT call to http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.425155 20058 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1122 05:32:33.426151 20049 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:48966 3: I1122 05:32:33.427929 20049 master.cpp:10045] Removing offer 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-O0 3: I1122 05:32:33.428082 20049 master.cpp:4230] Processing ACCEPT call for offers: [ 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-O0 ] on agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) for framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.428177 20049 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 1 3: W1122 05:32:33.429927 20049 validation.cpp:1391] Executor 'default' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. 3: W1122 05:32:33.429955 20049 validation.cpp:1403] Executor 'default' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. 3: I1122 05:32:33.430446 20049 master.cpp:10793] Adding task 1 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 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.430968 20049 master.cpp:4978] Launching task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-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 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.431569 20047 slave.cpp:1806] Got assigned task '1' for framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.432183 20047 slave.cpp:2074] Authorizing task '1' for framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.432229 20047 slave.cpp:7270] Authorizing framework principal 'test-principal' to launch task 1 3: I1122 05:32:33.432811 20055 slave.cpp:2242] Launching task '1' for framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.433379 20055 paths.cpp:621] Trying to chown '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' to user 'mesos' 3: I1122 05:32:33.433621 20055 slave.cpp:7744] Launching executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 with resources [] in work directory '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' 3: I1122 05:32:33.434449 20055 slave.cpp:2470] Queued task '1' for executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.434520 20055 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' to virtual path '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/latest' 3: I1122 05:32:33.434551 20055 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' to virtual path '/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/latest' 3: I1122 05:32:33.434576 20055 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' to virtual path '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' 3: I1122 05:32:33.434818 20055 slave.cpp:2933] Launching container 7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51 for executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.437294 20040 executor.cpp:192] Version: 1.5.0 3: W1122 05:32:33.437348 20040 process.cpp:2756] Attempted to spawn already running process [email protected]:41132 3: I1122 05:32:33.439133 20038 executor.cpp:410] Connected with the agent 3: I1122 05:32:33.439638 20044 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.440389 20039 process.cpp:3503] Handling HTTP event for process 'slave(808)' with path: '/slave(808)/api/v1/executor' 3: I1122 05:32:33.442543 20041 http.cpp:1185] HTTP POST for /slave(808)/api/v1/executor from 172.17.0.2:48970 3: I1122 05:32:33.442723 20041 slave.cpp:3868] Received Subscribe request for HTTP executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.443850 20041 slave.cpp:2672] Sending queued task '1' to executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (via HTTP) 3: I1122 05:32:33.444938 20037 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.445464 20042 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.446027 20043 executor.cpp:307] Sending UPDATE call to http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.446686 20040 process.cpp:3503] Handling HTTP event for process 'slave(808)' with path: '/slave(808)/api/v1/executor' 3: I1122 05:32:33.448438 20048 http.cpp:1185] HTTP POST for /slave(808)/api/v1/executor from 172.17.0.2:48972 3: I1122 05:32:33.448578 20048 slave.cpp:4520] Handling status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.449231 20049 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.449287 20049 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.449790 20049 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 to the agent 3: I1122 05:32:33.449990 20047 slave.cpp:5003] Forwarding the update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 to [email protected]:41132 3: I1122 05:32:33.450211 20047 slave.cpp:4896] Task status update manager successfully handled status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.450440 20042 master.cpp:7268] Status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 from agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.450490 20042 master.cpp:7330] Forwarding status update TASK_RUNNING (UUID: 6712c879-4a18-426d-b643-ac02ad7fb2d3) for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.450816 20053 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.451138 20042 master.cpp:9523] Updating the state of task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) 3: I1122 05:32:33.452092 20037 scheduler.cpp:739] Enqueuing event UPDATE received from http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.453563 20040 master.cpp:1425] Framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) disconnected 3: I1122 05:32:33.453598 20040 master.cpp:3333] Deactivating framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.453701 20040 master.cpp:3310] Disconnecting framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.453734 20040 master.cpp:1440] Giving framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 0ns to failover 3: I1122 05:32:33.453749 20051 hierarchical.cpp:419] Deactivated framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.454143 20049 master.cpp:7968] Framework failover timeout, removing framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.454160 20049 master.cpp:8825] Removing framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (default) 3: I1122 05:32:33.454296 20039 slave.cpp:3270] Asked to shut down framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 by [email protected]:41132 3: I1122 05:32:33.454298 20049 master.cpp:9523] Updating the state of task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) 3: I1122 05:32:33.454331 20039 slave.cpp:3295] Shutting down framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.454380 20039 slave.cpp:5879] Shutting down executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (via HTTP) 3: I1122 05:32:33.454963 20043 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.2:41132/slave(808)/api/v1/executor 3: I1122 05:32:33.455266 20056 hierarchical.cpp:1132] 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 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 from framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.455081 20049 master.cpp:9617] Removing task 1 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 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 on agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.455593 20049 master.cpp:9646] Removing executor 'default' with resources [] of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 on agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.456092 20054 hierarchical.cpp:358] Removed framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.456974 20038 slave.cpp:5544] Executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 exited with status 0 3: I1122 05:32:33.457078 20038 slave.cpp:5648] Cleaning up executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 (via HTTP) 3: W1122 05:32:33.457216 20046 master.cpp:7474] Ignoring unknown exited executor 'default' of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 on agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.457361 20050 gc.cpp:90] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default/runs/7d0c2d7f-c371-4ef8-9694-5a6f16eb5c51' for gc 6.99999470754667days in the future 3: I1122 05:32:33.457495 20038 slave.cpp:5755] Cleaning up framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.457506 20050 gc.cpp:90] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000/executors/default' for gc 6.99999470565926days in the future 3: I1122 05:32:33.457566 20058 task_status_update_manager.cpp:289] Closing task status update streams for framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.457603 20058 task_status_update_manager.cpp:538] Cleaning up status update stream for task 1 of framework 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000 3: I1122 05:32:33.457645 20050 gc.cpp:90] Scheduling '/tmp/ContentType_SchedulerTest_TaskRunning_1_PONG5p/slaves/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0/frameworks/564ff52d-61a2-4c8f-ab0e-4e8f4b416600-0000' for gc 6.99999470383111days in the future 3: I1122 05:32:33.457697 20038 slave.cpp:883] Agent terminating 3: I1122 05:32:33.457857 20037 master.cpp:1311] Agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) disconnected 3: I1122 05:32:33.457875 20037 master.cpp:3370] Disconnecting agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.457927 20037 master.cpp:3389] Deactivating agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 at slave(808)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.458009 20040 hierarchical.cpp:697] Agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 deactivated 3: I1122 05:32:33.460814 20060 master.cpp:1153] Master terminating 3: I1122 05:32:33.461412 20056 hierarchical.cpp:633] Removed agent 564ff52d-61a2-4c8f-ab0e-4e8f4b416600-S0 3: [ OK ] ContentType/SchedulerTest.TaskRunning/1 (85 ms) 3: [ RUN ] ContentType/SchedulerTest.TaskGroupRunning/0 3: I1122 05:32:33.467423 20036 cluster.cpp:162] Creating default 'local' authorizer 3: I1122 05:32:33.469947 20059 master.cpp:448] Master e701f277-4922-473f-a4c6-d43df15ba680 (4ab10b862c73) started on 172.17.0.2:41132 3: I1122 05:32:33.469962 20059 master.cpp:450] 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/DIxUHP/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/DIxUHP/master" --zk_session_timeout="10secs" 3: I1122 05:32:33.470258 20059 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1122 05:32:33.470265 20059 master.cpp:505] Master only allowing authenticated agents to register 3: I1122 05:32:33.470270 20059 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1122 05:32:33.470278 20059 credentials.hpp:37] Loading credentials for authentication from '/tmp/DIxUHP/credentials' 3: I1122 05:32:33.470566 20059 master.cpp:555] Using default 'crammd5' authenticator 3: I1122 05:32:33.470724 20059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1122 05:32:33.470883 20059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1122 05:32:33.470995 20059 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1122 05:32:33.471096 20059 master.cpp:634] Authorization enabled 3: I1122 05:32:33.471235 20057 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1122 05:32:33.471258 20050 whitelist_watcher.cpp:77] No whitelist given 3: I1122 05:32:33.473978 20046 master.cpp:2215] Elected as the leading master! 3: I1122 05:32:33.474007 20046 master.cpp:1695] Recovering from registrar 3: I1122 05:32:33.474135 20056 registrar.cpp:347] Recovering registrar 3: I1122 05:32:33.474720 20056 registrar.cpp:391] Successfully fetched the registry (0B) in 552960ns 3: I1122 05:32:33.474839 20056 registrar.cpp:495] Applied 1 operations in 38588ns; attempting to update the registry 3: I1122 05:32:33.475404 20056 registrar.cpp:552] Successfully updated the registry in 512us 3: I1122 05:32:33.475528 20056 registrar.cpp:424] Successfully recovered registrar 3: I1122 05:32:33.475868 20053 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1122 05:32:33.475908 20049 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: W1122 05:32:33.483434 20036 process.cpp:2756] Attempted to spawn already running process [email protected]:41132 3: I1122 05:32:33.484939 20036 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1122 05:32:33.485857 20036 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1122 05:32:33.486038 20036 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1122 05:32:33.486085 20036 provisioner.cpp:259] Using default backend 'copy' 3: I1122 05:32:33.489202 20036 cluster.cpp:448] Creating default 'local' authorizer 3: I1122 05:32:33.492838 20052 slave.cpp:262] Mesos agent started on (809)@172.17.0.2:41132 3: I1122 05:32:33.492858 20052 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/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/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/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/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/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/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/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/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/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/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx" --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/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG" --zk_session_timeout="10secs" 3: I1122 05:32:33.493355 20052 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/credential' 3: I1122 05:32:33.493580 20052 slave.cpp:295] Agent using credential for: test-principal 3: I1122 05:32:33.493597 20052 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/http_credentials' 3: I1122 05:32:33.493880 20052 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1122 05:32:33.494045 20052 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1122 05:32:33.494092 20036 scheduler.cpp:188] Version: 1.5.0 3: I1122 05:32:33.494247 20052 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1122 05:32:33.494328 20052 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1122 05:32:33.494472 20046 scheduler.cpp:311] Using default 'basic' HTTP authenticatee 3: I1122 05:32:33.494503 20052 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1122 05:32:33.494611 20052 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1122 05:32:33.494943 20044 scheduler.cpp:494] New master detected at [email protected]:41132 3: I1122 05:32:33.494967 20044 scheduler.cpp:503] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1122 05:32:33.496119 20052 slave.cpp:593] 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: I1122 05:32:33.496340 20052 slave.cpp:601] Agent attributes: [ ] 3: I1122 05:32:33.496348 20052 slave.cpp:610] Agent hostname: 4ab10b862c73 3: I1122 05:32:33.496470 20038 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1122 05:32:33.497162 20040 scheduler.cpp:385] Connected with the master at http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.498248 20049 state.cpp:64] Recovering state from '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/meta' 3: I1122 05:32:33.498487 20045 scheduler.cpp:247] Sending SUBSCRIBE call to http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.498661 20050 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1122 05:32:33.499009 20048 containerizer.cpp:668] Recovering containerizer 3: I1122 05:32:33.499943 20053 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1122 05:32:33.501127 20045 provisioner.cpp:455] Provisioner recovery complete 3: I1122 05:32:33.501590 20046 slave.cpp:6449] Finished recovery 3: I1122 05:32:33.501641 20054 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:48976 3: I1122 05:32:33.501823 20054 master.cpp:2615] Received subscription request for HTTP framework 'default' 3: I1122 05:32:33.501900 20054 master.cpp:2280] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1122 05:32:33.502260 20037 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1122 05:32:33.502264 20048 slave.cpp:1007] New master detected at [email protected]:41132 3: I1122 05:32:33.502290 20040 master.cpp:2750] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1122 05:32:33.502354 20048 slave.cpp:1042] Detecting new master 3: I1122 05:32:33.503082 20043 hierarchical.cpp:306] Added framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.503324 20043 hierarchical.cpp:1457] Performed allocation for 0 agents in 83302ns 3: I1122 05:32:33.504062 20050 scheduler.cpp:739] Enqueuing event SUBSCRIBED received from http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.504760 20050 scheduler.cpp:739] Enqueuing event HEARTBEAT received from http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.513366 20040 slave.cpp:1069] Authenticating with master [email protected]:41132 3: I1122 05:32:33.513463 20040 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1122 05:32:33.513859 20058 authenticatee.cpp:121] Creating new client SASL connection 3: I1122 05:32:33.514312 20052 master.cpp:8306] Authenticating slave(809)@172.17.0.2:41132 3: I1122 05:32:33.514539 20038 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1470)@172.17.0.2:41132 3: I1122 05:32:33.514986 20057 authenticator.cpp:98] Creating new server SASL connection 3: I1122 05:32:33.515367 20044 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1122 05:32:33.515396 20044 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1122 05:32:33.515522 20059 authenticator.cpp:204] Received SASL authentication start 3: I1122 05:32:33.515588 20059 authenticator.cpp:326] Authentication requires more steps 3: I1122 05:32:33.515682 20043 authenticatee.cpp:259] Received SASL authentication step 3: I1122 05:32:33.515799 20060 authenticator.cpp:232] Received SASL authentication step 3: I1122 05:32:33.515830 20060 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ab10b862c73' server FQDN: '4ab10b862c73' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1122 05:32:33.515841 20060 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1122 05:32:33.515884 20060 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1122 05:32:33.515899 20060 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4ab10b862c73' server FQDN: '4ab10b862c73' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1122 05:32:33.515908 20060 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1122 05:32:33.515911 20060 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1122 05:32:33.515923 20060 authenticator.cpp:318] Authentication success 3: I1122 05:32:33.516000 20053 authenticatee.cpp:299] Authentication success 3: I1122 05:32:33.516108 20045 master.cpp:8336] Successfully authenticated principal 'test-principal' at slave(809)@172.17.0.2:41132 3: I1122 05:32:33.516153 20047 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1470)@172.17.0.2:41132 3: I1122 05:32:33.516307 20039 slave.cpp:1161] Successfully authenticated with master [email protected]:41132 3: I1122 05:32:33.516582 20039 slave.cpp:1685] Will retry registration in 11.331519ms if necessary 3: I1122 05:32:33.516822 20050 master.cpp:6036] Received register agent message from slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.516970 20050 master.cpp:3872] Authorizing agent with principal 'test-principal' 3: I1122 05:32:33.517459 20042 master.cpp:6098] Authorized registration of agent at slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.517581 20042 master.cpp:6191] Registering agent at slave(809)@172.17.0.2:41132 (4ab10b862c73) with id e701f277-4922-473f-a4c6-d43df15ba680-S0 3: I1122 05:32:33.518046 20048 registrar.cpp:495] Applied 1 operations in 74347ns; attempting to update the registry 3: I1122 05:32:33.518865 20056 registrar.cpp:552] Successfully updated the registry in 733184ns 3: I1122 05:32:33.519317 20052 master.cpp:6240] Admitted agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.520462 20057 slave.cpp:1207] Registered with master [email protected]:41132; given agent ID e701f277-4922-473f-a4c6-d43df15ba680-S0 3: I1122 05:32:33.520207 20052 master.cpp:6273] Registered agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) 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"}] 3: I1122 05:32:33.520643 20043 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1122 05:32:33.520854 20038 hierarchical.cpp:600] Added agent e701f277-4922-473f-a4c6-d43df15ba680-S0 (4ab10b862c73) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1122 05:32:33.521097 20057 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/meta/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/slave.info' 3: I1122 05:32:33.521651 20057 slave.cpp:1298] Forwarding total oversubscribed resources {} 3: I1122 05:32:33.521899 20054 master.cpp:7085] Received update of agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) with total oversubscribed resources {} 3: I1122 05:32:33.522081 20054 master.cpp:7103] Ignoring update on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) as it reports no changes 3: I1122 05:32:33.522568 20038 hierarchical.cpp:1457] Performed allocation for 1 agents in 1.41047ms 3: I1122 05:32:33.523000 20037 master.cpp:8136] Sending 1 offers to framework e701f277-4922-473f-a4c6-d43df15ba680-0000 (default) 3: I1122 05:32:33.524734 20041 scheduler.cpp:739] Enqueuing event OFFERS received from http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.526816 20058 scheduler.cpp:247] Sending ACCEPT call to http://172.17.0.2:41132/master/api/v1/scheduler 3: I1122 05:32:33.528672 20053 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1122 05:32:33.530305 20056 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:48974 3: I1122 05:32:33.531754 20056 master.cpp:10045] Removing offer e701f277-4922-473f-a4c6-d43df15ba680-O0 3: I1122 05:32:33.531997 20056 master.cpp:4230] Processing ACCEPT call for offers: [ e701f277-4922-473f-a4c6-d43df15ba680-O0 ] on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) for framework e701f277-4922-473f-a4c6-d43df15ba680-0000 (default) 3: I1122 05:32:33.532157 20056 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 1 3: I1122 05:32:33.532451 20056 master.cpp:3599] Authorizing framework principal 'test-principal' to launch task 2 3: I1122 05:32:33.536957 20056 master.cpp:10793] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.537721 20056 master.cpp:10793] Adding task 2 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.538128 20056 master.cpp:5178] Launching task group { 1, 2 } of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 (default) with resources cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64 on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 at slave(809)@172.17.0.2:41132 (4ab10b862c73) 3: I1122 05:32:33.539894 20049 slave.cpp:1806] Got assigned task group containing tasks [ 1, 2 ] for framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.540441 20048 hierarchical.cpp:1132] Recovered cpus(allocated: *):1.7; mem(allocated: *):928; disk(allocated: *):928; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.3; mem(allocated: *):96; disk(allocated: *):96) on agent e701f277-4922-473f-a4c6-d43df15ba680-S0 from framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.540550 20048 hierarchical.cpp:1178] Framework e701f277-4922-473f-a4c6-d43df15ba680-0000 filtered agent e701f277-4922-473f-a4c6-d43df15ba680-S0 for 5secs 3: I1122 05:32:33.541034 20049 slave.cpp:2074] Authorizing task group containing tasks [ 1, 2 ] for framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.541262 20049 slave.cpp:7270] Authorizing framework principal 'test-principal' to launch task 1 3: I1122 05:32:33.541499 20049 slave.cpp:7270] Authorizing framework principal 'test-principal' to launch task 2 3: I1122 05:32:33.545753 20052 slave.cpp:2242] Launching task group containing tasks [ 1, 2 ] for framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.546958 20052 paths.cpp:621] Trying to chown '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' to user 'mesos' 3: I1122 05:32:33.547680 20052 slave.cpp:7744] Launching executor 'E' of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' 3: I1122 05:32:33.554740 20052 slave.cpp:2470] Queued task group containing tasks [ 1, 2 ] for executor 'E' of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.554978 20052 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' to virtual path '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/latest' 3: I1122 05:32:33.555027 20052 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' to virtual path '/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/latest' 3: I1122 05:32:33.555059 20052 slave.cpp:958] Successfully attached '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' to virtual path '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4' 3: I1122 05:32:33.555634 20052 slave.cpp:2933] Launching container 9162df07-8d99-4218-b463-b9a1a70a20d4 for executor 'E' of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.555968 20060 containerizer.cpp:1195] Starting container 9162df07-8d99-4218-b463-b9a1a70a20d4 3: I1122 05:32:33.557206 20060 containerizer.cpp:1367] Checkpointed ContainerConfig at '/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/containers/9162df07-8d99-4218-b463-b9a1a70a20d4/config' 3: I1122 05:32:33.557246 20060 containerizer.cpp:2919] Transitioning the state of container 9162df07-8d99-4218-b463-b9a1a70a20d4 from PROVISIONING to PREPARING 3: I1122 05:32:33.563088 20040 containerizer.cpp:1836] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-default-executor","--launcher_dir=\/mesos\/build\/src"],"shell":false,"value":"\/mesos\/build\/src\/mesos-default-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:41132"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG\/slaves\/e701f277-4922-473f-a4c6-d43df15ba680-S0\/frameworks\/e701f277-4922-473f-a4c6-d43df15ba680-0000\/executors\/E\/runs\/9162df07-8d99-4218-b463-b9a1a70a20d4"},{"name":"MESOS_EXECUTOR_AUTHENTICATION_TOKEN","type":"VALUE","value":"eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJjaWQiOiI5MTYyZGYwNy04ZDk5LTQyMTgtYjQ2My1iOWExYTcwYTIwZDQiLCJlaWQiOiJFIiwiZmlkIjoiZTcwMWYyNzctNDkyMi00NzNmLWE0YzYtZDQzZGYxNWJhNjgwLTAwMDAifQ._VcKQTzrfSCUwUmd8NQ7F6VLWGqtMrm4XAUdwpDvjDQ"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"E"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"e701f277-4922-473f-a4c6-d43df15ba680-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"e701f277-4922-473f-a4c6-d43df15ba680-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(809)@172.17.0.2:41132"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG\/slaves\/e701f277-4922-473f-a4c6-d43df15ba680-S0\/frameworks\/e701f277-4922-473f-a4c6-d43df15ba680-0000\/executors\/E\/runs\/9162df07-8d99-4218-b463-b9a1a70a20d4"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG\/slaves\/e701f277-4922-473f-a4c6-d43df15ba680-S0\/frameworks\/e701f277-4922-473f-a4c6-d43df15ba680-0000\/executors\/E\/runs\/9162df07-8d99-4218-b463-b9a1a70a20d4"}" --pipe_read="20" --pipe_write="21" --runtime_directory="/tmp/ContentType_SchedulerTest_TaskGroupRunning_0_3YRKNx/containers/9162df07-8d99-4218-b463-b9a1a70a20d4" --unshare_namespace_mnt="false"' 3: I1122 05:32:33.569659 20040 launcher.cpp:140] Forked child with pid '28892' for container '9162df07-8d99-4218-b463-b9a1a70a20d4' 3: I1122 05:32:33.570502 20040 containerizer.cpp:2919] Transitioning the state of container 9162df07-8d99-4218-b463-b9a1a70a20d4 from PREPARING to ISOLATING 3: I1122 05:32:33.577565 20051 containerizer.cpp:2919] Transitioning the state of container 9162df07-8d99-4218-b463-b9a1a70a20d4 from ISOLATING to FETCHING 3: I1122 05:32:33.577827 20052 fetcher.cpp:379] Starting to fetch URIs for container: 9162df07-8d99-4218-b463-b9a1a70a20d4, directory: /tmp/ContentType_SchedulerTest_TaskGroupRunning_0_vrNOKG/slaves/e701f277-4922-473f-a4c6-d43df15ba680-S0/frameworks/e701f277-4922-473f-a4c6-d43df15ba680-0000/executors/E/runs/9162df07-8d99-4218-b463-b9a1a70a20d4 3: I1122 05:32:33.578986 20047 containerizer.cpp:2919] Transitioning the state of container 9162df07-8d99-4218-b463-b9a1a70a20d4 from FETCHING to RUNNING 3: I1122 05:32:33.810022 28906 executor.cpp:192] Version: 1.5.0 3: I1122 05:32:33.818596 20056 process.cpp:3503] Handling HTTP event for process 'slave(809)' with path: '/slave(809)/api/v1/executor' 3: I1122 05:32:33.822055 20048 http.cpp:1185] HTTP POST for /slave(809)/api/v1/executor from 172.17.0.2:48978 3: I1122 05:32:33.822324 20048 slave.cpp:3868] Received Subscribe request for HTTP executor 'E' of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 3: I1122 05:32:33.824298 20050 slave.cpp:2713] Sending queued task group task group containing tasks [ 1, 2 ] to executor 'E' of framework e701f277-4922-473f-a4c6-d43df15ba680-0000 (via HTTP) 3: I1122 05:32:33.830530 28903 default_executor.cpp:191] Received SUBSCRIBED event 3: I1122 05:32:33.833123 28903 default_executor.cpp:195] Subscribed executor on 4ab10b862c73 + docker rmi mesos-1511326379-27657 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
