See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4487/display/redirect?page=changes>
Changes: [alexr] Fixed flaky test UnreachableAgentReregisterAfterFailover test. [bmahler] Preallocated buffer for resources conversion. ------------------------------------------ [...truncated 25.56 MB...] 3: I1121 19:15:27.241359 16011 slave.cpp:4909] Sending acknowledgement for status update TASK_FINISHED (UUID: 044e3f6a-b384-4f59-99dc-df757c59c002) for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 to executor(180)@172.17.0.5:37054 3: I1121 19:15:27.241518 16016 exec.cpp:388] Executor received status update acknowledgement 044e3f6a-b384-4f59-99dc-df757c59c002 for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.241526 16009 master.cpp:7268] Status update TASK_FINISHED (UUID: 044e3f6a-b384-4f59-99dc-df757c59c002) for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 from agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.241585 16009 master.cpp:7330] Forwarding status update TASK_FINISHED (UUID: 044e3f6a-b384-4f59-99dc-df757c59c002) for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.241755 16009 master.cpp:9523] Updating the state of task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED) 3: I1121 19:15:27.241981 16002 sched.cpp:1029] Scheduler::statusUpdate took 88833ns 3: I1121 19:15:27.242362 16009 master.cpp:5793] Processing ACKNOWLEDGE call 044e3f6a-b384-4f59-99dc-df757c59c002 for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 (default) at [email protected]:37054 on agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 3: I1121 19:15:27.242422 16009 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 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 on agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.242838 16008 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 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 from framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.243134 16021 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 044e3f6a-b384-4f59-99dc-df757c59c002) for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.243252 16021 task_status_update_manager.cpp:538] Cleaning up status update stream for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.243551 16003 slave.cpp:3801] Task status update manager successfully handled status update acknowledgement (UUID: 044e3f6a-b384-4f59-99dc-df757c59c002) for task 1 of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.243592 16003 slave.cpp:8343] Completing task 1 3: I1121 19:15:27.244648 16000 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1121 19:15:27.245579 16004 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.5:58588 3: I1121 19:15:27.245688 16004 http.cpp:673] Processing call GET_TASKS 3: I1121 19:15:27.250118 15999 sched.cpp:2009] Asked to stop the driver 3: I1121 19:15:27.250221 16003 sched.cpp:1191] Stopping framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.250449 16006 master.cpp:8813] Processing TEARDOWN call for framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 (default) at [email protected]:37054 3: I1121 19:15:27.250478 16006 master.cpp:8825] Removing framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 (default) at [email protected]:37054 3: I1121 19:15:27.250497 16006 master.cpp:3333] Deactivating framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 (default) at [email protected]:37054 3: I1121 19:15:27.250643 16012 hierarchical.cpp:419] Deactivated framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.250675 16000 slave.cpp:3267] Asked to shut down framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 by [email protected]:37054 3: I1121 19:15:27.250706 16000 slave.cpp:3292] Shutting down framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.250730 16006 master.cpp:9646] Removing executor 'default' with resources [] of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 on agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.250751 16000 slave.cpp:5876] Shutting down executor 'default' of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 at executor(180)@172.17.0.5:37054 3: I1121 19:15:27.250890 16020 exec.cpp:435] Executor asked to shutdown 3: I1121 19:15:27.250933 16020 exec.cpp:450] Executor::shutdown took 31807ns 3: I1121 19:15:27.251092 16017 slave.cpp:5132] Got exited event for executor(180)@172.17.0.5:37054 3: I1121 19:15:27.251547 16019 hierarchical.cpp:358] Removed framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.251894 16011 slave.cpp:5541] Executor 'default' of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 exited with status 0 3: I1121 19:15:27.251984 16011 slave.cpp:5645] Cleaning up executor 'default' of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 at executor(180)@172.17.0.5:37054 3: W1121 19:15:27.252100 16017 master.cpp:7474] Ignoring unknown exited executor 'default' of framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 on agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.252185 16006 gc.cpp:90] Scheduling '/tmp/ContentType_MasterAPITest_GetTasks_1_0GzZJL/slaves/54478d81-ed5a-42c8-aa79-b56d41643b08-S0/frameworks/54478d81-ed5a-42c8-aa79-b56d41643b08-0000/executors/default/runs/4bf0c32c-ace1-4114-acdb-b775fb4fcea7' for gc 6.99999708201185days in the future 3: I1121 19:15:27.252343 16001 gc.cpp:90] Scheduling '/tmp/ContentType_MasterAPITest_GetTasks_1_0GzZJL/slaves/54478d81-ed5a-42c8-aa79-b56d41643b08-S0/frameworks/54478d81-ed5a-42c8-aa79-b56d41643b08-0000/executors/default' for gc 6.9999970800563days in the future 3: I1121 19:15:27.252351 16011 slave.cpp:5752] Cleaning up framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.252456 16022 task_status_update_manager.cpp:289] Closing task status update streams for framework 54478d81-ed5a-42c8-aa79-b56d41643b08-0000 3: I1121 19:15:27.252512 16013 gc.cpp:90] Scheduling '/tmp/ContentType_MasterAPITest_GetTasks_1_0GzZJL/slaves/54478d81-ed5a-42c8-aa79-b56d41643b08-S0/frameworks/54478d81-ed5a-42c8-aa79-b56d41643b08-0000' for gc 6.99999707812444days in the future 3: I1121 19:15:27.252533 16011 slave.cpp:883] Agent terminating 3: I1121 19:15:27.252703 16014 master.cpp:1311] Agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) disconnected 3: I1121 19:15:27.252724 16014 master.cpp:3370] Disconnecting agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.252773 16014 master.cpp:3389] Deactivating agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 at slave(683)@172.17.0.5:37054 (ee990a7fea31) 3: I1121 19:15:27.252876 16015 hierarchical.cpp:697] Agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 deactivated 3: I1121 19:15:27.255743 15999 master.cpp:1153] Master terminating 3: I1121 19:15:27.256274 16016 hierarchical.cpp:633] Removed agent 54478d81-ed5a-42c8-aa79-b56d41643b08-S0 3: [ OK ] ContentType/MasterAPITest.GetTasks/1 (78 ms) 3: [ RUN ] ContentType/MasterAPITest.GetLoggingLevel/0 3: I1121 19:15:27.262040 15999 cluster.cpp:162] Creating default 'local' authorizer 3: I1121 19:15:27.264657 16011 master.cpp:448] Master e81a1b58-7036-463a-9a06-7dec7878f3fe (ee990a7fea31) started on 172.17.0.5:37054 3: I1121 19:15:27.264689 16011 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --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/iZ87CL/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/iZ87CL/master" --zk_session_timeout="10secs" 3: I1121 19:15:27.264950 16011 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1121 19:15:27.264956 16011 master.cpp:505] Master only allowing authenticated agents to register 3: I1121 19:15:27.264960 16011 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1121 19:15:27.264964 16011 credentials.hpp:37] Loading credentials for authentication from '/tmp/iZ87CL/credentials' 3: I1121 19:15:27.265205 16011 master.cpp:555] Using default 'crammd5' authenticator 3: I1121 19:15:27.265344 16011 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1121 19:15:27.265478 16011 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1121 19:15:27.265588 16011 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1121 19:15:27.265689 16011 master.cpp:634] Authorization enabled 3: I1121 19:15:27.265830 16018 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1121 19:15:27.265872 16007 whitelist_watcher.cpp:77] No whitelist given 3: I1121 19:15:27.269201 16023 master.cpp:2215] Elected as the leading master! 3: I1121 19:15:27.269218 16023 master.cpp:1695] Recovering from registrar 3: I1121 19:15:27.269326 16021 registrar.cpp:347] Recovering registrar 3: I1121 19:15:27.269882 16021 registrar.cpp:391] Successfully fetched the registry (0B) in 521984ns 3: I1121 19:15:27.269981 16021 registrar.cpp:495] Applied 1 operations in 34066ns; attempting to update the registry 3: I1121 19:15:27.270484 16021 registrar.cpp:552] Successfully updated the registry in 451072ns 3: I1121 19:15:27.270589 16021 registrar.cpp:424] Successfully recovered registrar 3: I1121 19:15:27.270915 16014 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1121 19:15:27.270967 16020 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: I1121 19:15:27.272814 16017 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1121 19:15:27.273947 16004 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.5:58590 3: I1121 19:15:27.274016 16004 http.cpp:673] Processing call GET_LOGGING_LEVEL 3: I1121 19:15:27.275351 15999 master.cpp:1153] Master terminating 3: [ OK ] ContentType/MasterAPITest.GetLoggingLevel/0 (18 ms) 3: [ RUN ] ContentType/MasterAPITest.GetLoggingLevel/1 3: I1121 19:15:27.280014 15999 cluster.cpp:162] Creating default 'local' authorizer 3: I1121 19:15:27.282459 16013 master.cpp:448] Master f19848db-f3aa-415d-a6d1-1cdaa5dafca6 (ee990a7fea31) started on 172.17.0.5:37054 3: I1121 19:15:27.282490 16013 master.cpp:450] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --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/3JytKg/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/3JytKg/master" --zk_session_timeout="10secs" 3: I1121 19:15:27.282730 16013 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1121 19:15:27.282737 16013 master.cpp:505] Master only allowing authenticated agents to register 3: I1121 19:15:27.282740 16013 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1121 19:15:27.282745 16013 credentials.hpp:37] Loading credentials for authentication from '/tmp/3JytKg/credentials' 3: I1121 19:15:27.282977 16013 master.cpp:555] Using default 'crammd5' authenticator 3: I1121 19:15:27.283100 16013 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1121 19:15:27.283229 16013 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1121 19:15:27.283357 16013 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1121 19:15:27.283468 16013 master.cpp:634] Authorization enabled 3: I1121 19:15:27.283579 16014 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1121 19:15:27.283598 16020 whitelist_watcher.cpp:77] No whitelist given 3: I1121 19:15:27.285799 16023 master.cpp:2215] Elected as the leading master! 3: I1121 19:15:27.285814 16023 master.cpp:1695] Recovering from registrar 3: I1121 19:15:27.285912 16002 registrar.cpp:347] Recovering registrar 3: I1121 19:15:27.286352 16002 registrar.cpp:391] Successfully fetched the registry (0B) in 411904ns 3: I1121 19:15:27.286437 16002 registrar.cpp:495] Applied 1 operations in 33332ns; attempting to update the registry 3: I1121 19:15:27.286877 16002 registrar.cpp:552] Successfully updated the registry in 392704ns 3: I1121 19:15:27.286989 16002 registrar.cpp:424] Successfully recovered registrar 3: I1121 19:15:27.287276 16021 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1121 19:15:27.287297 16005 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: I1121 19:15:27.288967 16008 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1121 19:15:27.290159 16001 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.5:58592 3: I1121 19:15:27.290282 16001 http.cpp:673] Processing call GET_LOGGING_LEVEL 3: I1121 19:15:27.291529 15999 master.cpp:1153] Master terminating 3: [ OK ] ContentType/MasterAPITest.GetLoggingLevel/1 (17 ms) 3: [ RUN ] ContentType/MasterAPITest.SetLoggingLevel/0 3: I1121 19:15:27.297960 15999 cluster.cpp:162] Creating default 'local' authorizer 3: I1121 19:15:27.300377 16023 master.cpp:448] Master dc41bec1-97fd-4a5b-9972-152cab29874a (ee990a7fea31) started on 172.17.0.5:37054 3: I1121 19:15:27.300405 16023 master.cpp:450] Flags at startup: --acls="set_log_level { 3: principals { 3: values: "test-principal-2" 3: } 3: level { 3: type: NONE 3: } 3: } 3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1000secs" --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/MKxmUL/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/MKxmUL/master" --zk_session_timeout="10secs" 3: I1121 19:15:27.300720 16023 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1121 19:15:27.300727 16023 master.cpp:505] Master only allowing authenticated agents to register 3: I1121 19:15:27.300731 16023 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1121 19:15:27.300735 16023 credentials.hpp:37] Loading credentials for authentication from '/tmp/MKxmUL/credentials' 3: I1121 19:15:27.300947 16023 master.cpp:555] Using default 'crammd5' authenticator 3: I1121 19:15:27.301069 16023 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1121 19:15:27.301189 16023 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1121 19:15:27.301303 16023 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1121 19:15:27.301419 16023 master.cpp:634] Authorization enabled 3: I1121 19:15:27.301542 16011 hierarchical.cpp:173] Initialized hierarchical allocator process 3: I1121 19:15:27.301582 16005 whitelist_watcher.cpp:77] No whitelist given 3: I1121 19:15:27.303777 16013 master.cpp:2215] Elected as the leading master! 3: I1121 19:15:27.303793 16013 master.cpp:1695] Recovering from registrar 3: I1121 19:15:27.303901 16014 registrar.cpp:347] Recovering registrar 3: I1121 19:15:27.304348 16014 registrar.cpp:391] Successfully fetched the registry (0B) in 418816ns 3: I1121 19:15:27.304431 16014 registrar.cpp:495] Applied 1 operations in 29327ns; attempting to update the registry 3: I1121 19:15:27.304967 16014 registrar.cpp:552] Successfully updated the registry in 472064ns 3: I1121 19:15:27.305136 16014 registrar.cpp:424] Successfully recovered registrar 3: I1121 19:15:27.305553 16006 master.cpp:1808] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1121 19:15:27.305596 16019 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover 3: I1121 19:15:27.307301 16021 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1121 19:15:27.308312 16016 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.5:58594 3: I1121 19:15:27.308426 16016 http.cpp:673] Processing call SET_LOGGING_LEVEL 3: I1121 19:15:27.311069 16017 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/api/v1' 3: I1121 19:15:27.311985 16008 http.cpp:1185] HTTP POST for /master/api/v1 from 172.17.0.5:58596 3: I1121 19:15:27.312074 16008 http.cpp:673] Processing call SET_LOGGING_LEVEL 3: I1121 19:15:27.312485 16012 logging.hpp:66] Setting verbose logging level to 2 3: I1121 19:15:27.313541 15999 clock.cpp:331] Clock paused at 2018-01-02 23:13:43.501502986+00:00 3: I1121 19:15:27.313590 15999 clock.cpp:381] Clock advanced (1mins) to 2018-01-02 23:14:43.501502986+00:00 3: I1121 19:15:27.315701 16024 clock.cpp:435] Clock of __reaper__(1)@172.17.0.5:37054 updated to 2018-01-02 23:13:43.557965066+00:00 3: I1121 19:15:27.315745 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:43.575944970+00:00 3: I1121 19:15:27.315809 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:43.967556874+00:00 3: I1121 19:15:27.315831 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:43.980346122+00:00 3: I1121 19:15:27.315917 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:44.577035018+00:00 3: I1121 19:15:27.315948 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:44.585958154+00:00 3: I1121 19:15:27.315990 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:45.178444042+00:00 3: I1121 19:15:27.316013 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:45.192838922+00:00 3: I1121 19:15:27.316061 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:45.789722122+00:00 3: I1121 19:15:27.316092 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:45.798269962+00:00 3: I1121 19:15:27.316130 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:46.407006986+00:00 3: I1121 19:15:27.316167 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:46.699982090+00:00 3: I1121 19:15:27.316231 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.003684106+00:00 3: I1121 19:15:27.316277 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.305536010+00:00 3: I1121 19:15:27.316316 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.619601930+00:00 3: I1121 19:15:27.316340 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.677296906+00:00 3: I1121 19:15:27.316370 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.756834058+00:00 3: I1121 19:15:27.316395 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.780363018+00:00 3: I1121 19:15:27.316431 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.941985802+00:00 3: I1121 19:15:27.316453 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:47.949015818+00:00 3: I1121 19:15:27.316476 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.016121866+00:00 3: I1121 19:15:27.316506 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.022646026+00:00 3: I1121 19:15:27.316537 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.078119178+00:00 3: I1121 19:15:27.316560 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.085901066+00:00 3: I1121 19:15:27.316586 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.168333066+00:00 3: I1121 19:15:27.316623 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.178704138+00:00 3: I1121 19:15:27.316673 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.313319946+00:00 3: I1121 19:15:27.316699 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.319309066+00:00 3: I1121 19:15:27.316727 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.387765002+00:00 3: I1121 19:15:27.316756 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:13:48.397847818+00:00 3: I1121 19:15:27.317507 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:14:43.500520970+00:00 3: I1121 19:15:27.317543 16024 process.cpp:2730] Dropping event for process slave(635)@172.17.0.5:37054 3: I1121 19:15:27.317564 16024 process.cpp:2730] Dropping event for process __waiter__(4998)@172.17.0.5:37054 3: I1121 19:15:27.317584 16024 process.cpp:2730] Dropping event for process __waiter__(4999)@172.17.0.5:37054 3: I1121 19:15:27.317602 16024 process.cpp:2730] Dropping event for process __waiter__(5000)@172.17.0.5:37054 3: I1121 19:15:27.317621 16024 clock.cpp:435] Clock of __reaper__(1)@172.17.0.5:37054 updated to 2018-01-02 23:14:43.501502986+00:00 3: I1121 19:15:27.317668 16024 process.cpp:2730] Dropping event for process slave(667)@172.17.0.5:37054 3: I1121 19:15:27.317731 16024 clock.cpp:435] Clock of [email protected]:37054 updated to 2018-01-02 23:14:43.501502986+00:00 3: I1121 19:15:27.317776 16024 process.cpp:2730] Dropping event for process __waiter__(5001)@172.17.0.5:37054 3: I1121 19:15:27.317795 16024 process.cpp:2730] Dropping event for process __waiter__(5002)@172.17.0.5:37054 3: I1121 19:15:27.317821 16024 process.cpp:2730] Dropping event for process task-status-update-manager(661)@172.17.0.5:37054 3: I1121 19:15:27.317837 16024 process.cpp:2730] Dropping event for process __waiter__(5004)@172.17.0.5:37054 3: I1121 19:15:27.317852 16024 process.cpp:2730] Dropping event for process slave(636)@172.17.0.5:37054 3: I1121 19:15:27.317869 16024 process.cpp:2730] Dropping event for process task-status-update-manager(661)@172.17.0.5:37054 3: I1121 19:15:27.317888 16024 process.cpp:2730] Dropping event for process slave-observer(600)@172.17.0.5:37054 3: I1121 19:15:27.317904 16024 process.cpp:2730] Dropping event for process __waiter__(5005)@172.17.0.5:37054 3: I1121 19:15:27.317920 16024 process.cpp:2730] Dropping event for process __waiter__(5006)@172.17.0.5:37054 3: I1121 19:15:27.317934 16024 process.cpp:2730] Dropping event for process __waiter__(5007)@172.17.0.5:37054 3: I1121 19:15:27.317948 16024 process.cpp:2730] Dropping event for process __waiter__(5009)@172.17.0.5:37054 3: I1121 19:15:27.317961 16024 process.cpp:2730] Dropping event for process slave(637)@172.17.0.5:37054 3: I1121 19:15:27.317978 16024 process.cpp:2730] Dropping event for process slave-observer(601)@172.17.0.5:37054 3: I1121 19:15:27.317994 16024 process.cpp:2730] Dropping event for process __waiter__(5010)@172.17.0.5:37054 3: I1121 19:15:27.318006 16024 process.cpp:2730] Dropping event for process __waiter__(5011)@172.17.0.5:37054 3: I1121 19:15:27.318022 16024 process.cpp:2730] Dropping event for process __waiter__(5012)@172.17.0.5:37054 3: I1121 19:15:27.318033 16024 process.cpp:2730] Dropping event for process __waiter__(5014)@172.17.0.5:37054 3: I1121 19:15:27.318049 16024 process.cpp:2730] Dropping event for process slave(638)@172.17.0.5:37054 3: I1121 19:15:27.318065 16024 process.cpp:2730] Dropping event for process slave-observer(602)@172.17.0.5:37054 3: I1121 19:15:27.318083 16024 process.cpp:2730] Dropping event for process __waiter__(5015)@172.17.0.5:37054 3: I1121 19:15:27.318099 16024 process.cpp:2730] Dropping event for process __waiter__(5016)@172.17.0.5:37054 3: I1121 19:15:27.318114 16024 process.cpp:2730] Dropping event for process __waiter__(5017)@172.17.0.5:37054 3: I1121 19:15:27.318126 16024 process.cpp:2730] Dropping event for process __waiter__(5019)@172.17.0.5:37054 3: I1121 19:15:27.318141 16024 process.cpp:2730] Dropping event for process slave(639)@172.17.0.5:37054 3: I1121 19:15:27.318155 16024 process.cpp:2730] Dropping event for process slave-observer(603)@172.17.0.5:37054 3: I1121 19:15:27.318171 16024 process.cpp:2730] Dropping event for process __waiter__(5020)@172.17.0.5:37054 3: I1121 19:15:27.318182 16024 process.cpp:2730] Dropping event for process __waiter__(5021)@172.17.0.5:37054 3: I1121 19:15:27.318197 16024 process.cpp:2730] Dropping event for process __waiter__(5022)@172.17.0.5:37054 3: I1121 19:15:27.318217 16024 process.cpp:2730] Dropping event for process [email protected]:37054 3: I1121 19:15:27.318239 16024 process.cpp:2730] Dropping event for process [email protected]:37054 3: I1121 19:15:27.318287 16024 process.cpp:2730] Dropping event for process slave(668)@172.17.0.5:37054 3: I1121 19:15:27.318322 16024 process.cpp:2730] Dropping event for process __waiter__(5024)@172.17.0.5:37054 3: I1121 19:15:27.318342 16024 process.cpp:2730] Dropping event for process slave(640)@172.17.0.5:37054 3: I1121 19:15:27.318359 16024 process.cpp:2730] Dropping event for process slave-observer(604)@172.17.0.5:37054 3: I1121 19:15:27.318375 16024 process.cpp:2730] Dropping event for process __waiter__(5025)@172.17.0.5:37054 3: I1121 19:15:27.318392 16024 process.cpp:2730] Dropping event for process __waiter__(5026)@172.17.0.5:37054 3: I1121 19:15:27.318404 16024 process.cpp:2730] Dropping event for process __waiter__(5027)@172.17.0.5:37054 3: I1121 19:15:27.318419 16024 process.cpp:2730] Dropping event for process __waiter__(5028)@172.17.0.5:37054 3: I1121 19:15:27.318431 16024 process.cpp:2730] Dropping event for process __waiter__(5030)@172.17.0.5:37054 3: I1121 19:15:27.318446 16024 process.cpp:2730] Dropping event for process slave(641)@172.17.0.5:37054 3: I1121 19:15:27.318459 16024 process.cpp:2730] Dropping event for process slave-observer(605)@172.17.0.5:37054 3: I1121 19:15:27.318475 16024 process.cpp:2730] Dropping event for process __waiter__(5031)@172.17.0.5:37054 3: I1121 19:15:27.318486 16024 process.cpp:2730] Dropping event for process __waiter__(5032)@172.17.0.5:37054 3: I1121 19:15:27.318501 16024 process.cpp:2730] Dropping event for process __waiter__(5033)@172.17.0.5:37054 3: I1121 19:15:27.318513 16024 process.cpp:2730] Dropping event for process __waiter__(5035)@172.17.0.5:37054 3: I1121 19:15:27.318528 16024 process.cpp:2730] Dropping event for process slave(642)@172.17.0.5:37054 3: I1121 19:15:27.318542 16024 process.cpp:2730] Dropping event for process slave-observer(606)@172.17.0.5:37054 3: I1121 19:15:27.318557 16024 process.cpp:2730] Dropping event for process __waiter__(5036)@172.17.0.5:37054 3: I1121 19:15:27.318568 16024 process.cpp:2730] Dropping event for process __waiter__(5037)@172.17.0.5:37054 3: I1121 19:15:27.318583 16024 process.cpp:2730] Dropping event for process __waiter__(5038)@172.17.0.5:37054 3: I1121 19:15:27.318595 16024 process.cpp:2730] Dropping event for process __waiter__(5040)@172.17.0.5:37054 3: I1121 19:15:27.318614 16024 process.cpp:2730] Dropping event for process slave(643)@172.17.0.5:37054 3: I1121 19:15:27.318629 16024 process.cpp:2730] Dropping event for process slave-observer(607)@172.17.0.5:37054 3: I1121 19:15:27.318646 16024 process.cpp:2730] Dropping event for process __waiter__(5041)@172.17.0.5:37054 3: I1121 19:15:27.318660 16024 process.cpp:2730] Dropping event for process __waiter__(5042)@172.17.0.5:37054 3: I1121 19:15:27.318673 16024 process.cpp:2730] Dropping event for process __waiter__(5043)@172.17.0.5:37054 3: I1121 19:15:27.318686 16024 process.cpp:2730] Dropping event for process __waiter__(5045)@172.17.0.5:37054 3: I1121 19:15:27.318701 16024 process.cpp:2730] Dropping event for process slave(644)@172.17.0.5:37054 3: I1121 19:15:27.318720 16024 process.cpp:2730] Dropping event for process task-status-update-manager(662)@172.17.0.5:37054 3: I1121 19:15:27.318737 16024 process.cpp:2730] Dropping event for process slave-observer(608)@172.17.0.5:37054 3: I1121 19:15:27.318753 16024 process.cpp:2730] Dropping event for process __waiter__(5046)@172.17.0.5:37054 3: I1121 19:15:27.318771 16024 process.cpp:2730] Dropping event for process task-status-update-manager(662)@172.17.0.5:37054 write /dev/stdout: resource temporarily unavailable + docker rmi mesos-1511289925-32589 Error response from daemon: conflict: unable to remove repository reference "mesos-1511289925-32589" (must force) - container ee990a7fea31 is using its referenced image e7bcba3d356a Build step 'Execute shell' marked build as failure
