See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3373/display/redirect?page=changes>
Changes: [anand] Modified an erroneous comment in the Master API proto. ------------------------------------------ [...truncated 7.04 MB...] I0315 17:57:08.670403 27765 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.670527 27765 slave.cpp:4006] Handling status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 from executor(63)@172.17.0.3:40917 I0315 17:57:08.670811 27765 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.670833 27765 status_update_manager.cpp:500] Creating StatusUpdate stream for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.671063 27765 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 to the agent I0315 17:57:08.671236 27766 slave.cpp:4446] Forwarding the update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 to [email protected]:40917 I0315 17:57:08.671406 27766 slave.cpp:4340] Status update manager successfully handled status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.671406 27780 master.cpp:6243] Status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 from agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.671440 27766 slave.cpp:4356] Sending acknowledgement for status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 to executor(63)@172.17.0.3:40917 I0315 17:57:08.671573 27780 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.671682 27765 exec.cpp:374] Executor received status update acknowledgement 20482675-db01-465d-a17f-2c562ad218de for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.671900 27780 master.cpp:8345] Updating the state of task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0315 17:57:08.671973 27766 sched.cpp:1041] Scheduler::statusUpdate took 38493ns I0315 17:57:08.672312 27766 master.cpp:5151] Processing ACKNOWLEDGE call 20482675-db01-465d-a17f-2c562ad218de for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (default) at [email protected]:40917 on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 I0315 17:57:08.672603 27767 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.672634 27765 slave.cpp:5197] Asked to shut down executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 by [email protected]:40917 I0315 17:57:08.672812 27765 slave.cpp:5265] Shutting down executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 at executor(63)@172.17.0.3:40917 I0315 17:57:08.672880 27765 exec.cpp:415] Executor asked to shutdown I0315 17:57:08.673051 27767 slave.cpp:3295] Status update manager successfully handled status update acknowledgement (UUID: 20482675-db01-465d-a17f-2c562ad218de) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.674382 27765 exec.cpp:430] Executor::shutdown took 18970ns I0315 17:57:08.674513 27767 slave.cpp:4578] Got exited event for executor(63)@172.17.0.3:40917 I0315 17:57:08.675189 27774 hierarchical.cpp:1854] No allocations performed I0315 17:57:08.675256 27774 hierarchical.cpp:1944] No inverse offers to send out! I0315 17:57:08.675329 27774 hierarchical.cpp:1438] Performed allocation for 1 agents in 209935ns I0315 17:57:08.675684 27769 slave.cpp:5338] Killing executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 at executor(63)@172.17.0.3:40917 I0315 17:57:08.675796 27778 hierarchical.cpp:1854] No allocations performed I0315 17:57:08.675817 27778 hierarchical.cpp:1944] No inverse offers to send out! I0315 17:57:08.675828 27778 hierarchical.cpp:1438] Performed allocation for 1 agents in 78402ns I0315 17:57:08.676012 27765 slave.cpp:4951] Executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 exited with status 0 I0315 17:57:08.676069 27765 slave.cpp:4006] Handling status update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 from @0.0.0.0:0 I0315 17:57:08.676244 27765 master.cpp:6373] Executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16): exited with status 0 I0315 17:57:08.676275 27765 master.cpp:8468] Removing executor 'default' with resources {} of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.676398 27765 sched.cpp:1143] Executor default on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 exited with status 0 I0315 17:57:08.676435 27765 sched.cpp:1154] Scheduler::executorLost took 19963ns I0315 17:57:08.676745 27770 status_update_manager.cpp:323] Received status update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.676810 27770 status_update_manager.cpp:377] Forwarding update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 to the agent I0315 17:57:08.676930 27770 slave.cpp:4446] Forwarding the update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 to [email protected]:40917 I0315 17:57:08.677003 27770 slave.cpp:4340] Status update manager successfully handled status update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.677079 27770 master.cpp:6243] Status update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 from agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.677105 27770 master.cpp:6311] Forwarding status update TASK_FAILED (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.677160 27770 master.cpp:8345] Updating the state of task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (latest state: TASK_FAILED, status update state: TASK_FAILED) I0315 17:57:08.677395 27770 sched.cpp:1041] Scheduler::statusUpdate took 27518ns I0315 17:57:08.677629 27770 hierarchical.cpp:1108] 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 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 from framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.677732 27770 master.cpp:5151] Processing ACKNOWLEDGE call ee3e4678-53ef-4992-bf70-a6b89bc90fc2 for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (default) at [email protected]:40917 on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 I0315 17:57:08.677762 27770 master.cpp:8439] Removing task 2 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 on agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.677918 27770 status_update_manager.cpp:395] Received status update acknowledgement (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.677971 27770 status_update_manager.cpp:531] Cleaning up status update stream for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.678092 27770 slave.cpp:3295] Status update manager successfully handled status update acknowledgement (UUID: ee3e4678-53ef-4992-bf70-a6b89bc90fc2) for task 2 of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.678113 27770 slave.cpp:7149] Completing task 2 I0315 17:57:08.678124 27770 slave.cpp:5051] Cleaning up executor 'default' of framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 at executor(63)@172.17.0.3:40917 I0315 17:57:08.694705 27771 gc.cpp:55] Scheduling '/tmp/SlaveTest_ExecutorShutdownGracePeriod_KvN3X2/slaves/6209dc05-268e-470a-aeb4-a9951b4a0076-S0/frameworks/6209dc05-268e-470a-aeb4-a9951b4a0076-0000/executors/default/runs/aae17204-f1a1-4b3c-81a9-4f3246f59f4a' for gc 6.99999221688593days in the future I0315 17:57:08.694772 27770 slave.cpp:5139] Cleaning up framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.694792 27771 gc.cpp:55] Scheduling '/tmp/SlaveTest_ExecutorShutdownGracePeriod_KvN3X2/slaves/6209dc05-268e-470a-aeb4-a9951b4a0076-S0/frameworks/6209dc05-268e-470a-aeb4-a9951b4a0076-0000/executors/default' for gc 6.99999221688593days in the future I0315 17:57:08.694825 27771 status_update_manager.cpp:285] Closing status update streams for framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.694881 27773 gc.cpp:55] Scheduling '/tmp/SlaveTest_ExecutorShutdownGracePeriod_KvN3X2/slaves/6209dc05-268e-470a-aeb4-a9951b4a0076-S0/frameworks/6209dc05-268e-470a-aeb4-a9951b4a0076-0000' for gc 6.99999221688593days in the future I0315 17:57:08.695011 27764 sched.cpp:2021] Asked to stop the driver I0315 17:57:08.695116 27770 sched.cpp:1203] Stopping framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.695372 27770 master.cpp:7765] Processing TEARDOWN call for framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (default) at [email protected]:40917 I0315 17:57:08.695394 27770 master.cpp:7777] Removing framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (default) at [email protected]:40917 I0315 17:57:08.695410 27770 master.cpp:3148] Deactivating framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 (default) at [email protected]:40917 I0315 17:57:08.696290 27780 hierarchical.cpp:376] Deactivated framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.696287 27771 slave.cpp:2853] Asked to shut down framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 by [email protected]:40917 I0315 17:57:08.696367 27771 slave.cpp:2868] Cannot shut down unknown framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.696481 27780 hierarchical.cpp:323] Removed framework 6209dc05-268e-470a-aeb4-a9951b4a0076-0000 I0315 17:57:08.696661 27764 slave.cpp:808] Agent terminating I0315 17:57:08.696838 27780 master.cpp:1317] Agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) disconnected I0315 17:57:08.696856 27780 master.cpp:3185] Disconnecting agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.696893 27780 master.cpp:3204] Deactivating agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 at slave(176)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.696954 27766 hierarchical.cpp:655] Agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 deactivated I0315 17:57:08.720942 27764 master.cpp:1156] Master terminating I0315 17:57:08.721150 27772 hierarchical.cpp:560] Removed agent 6209dc05-268e-470a-aeb4-a9951b4a0076-S0 [ OK ] SlaveTest.ExecutorShutdownGracePeriod (173 ms) [ RUN ] SlaveTest.RunTaskGroup I0315 17:57:08.750186 27764 cluster.cpp:160] Creating default 'local' authorizer I0315 17:57:08.754467 27778 master.cpp:437] Master af952b17-67dd-439b-9e34-a154517d2a4d (c8f060cfad16) started on 172.17.0.3:40917 I0315 17:57:08.754577 27778 master.cpp:439] 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/5t1SIB/credentials" --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" --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/5t1SIB/master" --zk_session_timeout="10secs" I0315 17:57:08.755059 27778 master.cpp:489] Master only allowing authenticated frameworks to register I0315 17:57:08.755141 27778 master.cpp:503] Master only allowing authenticated agents to register I0315 17:57:08.755167 27778 master.cpp:516] Master only allowing authenticated HTTP frameworks to register I0315 17:57:08.755218 27778 credentials.hpp:37] Loading credentials for authentication from '/tmp/5t1SIB/credentials' I0315 17:57:08.755616 27778 master.cpp:561] Using default 'crammd5' authenticator I0315 17:57:08.755964 27778 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0315 17:57:08.756424 27778 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0315 17:57:08.756763 27778 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0315 17:57:08.757122 27778 master.cpp:641] Authorization enabled I0315 17:57:08.757726 27767 hierarchical.cpp:159] Initialized hierarchical allocator process I0315 17:57:08.757787 27768 whitelist_watcher.cpp:77] No whitelist given I0315 17:57:08.759433 27778 master.cpp:2165] Elected as the leading master! I0315 17:57:08.759660 27778 master.cpp:1704] Recovering from registrar I0315 17:57:08.759826 27770 registrar.cpp:331] Recovering registrar I0315 17:57:08.760241 27769 registrar.cpp:364] Successfully fetched the registry (0B) in 276992ns I0315 17:57:08.760282 27769 registrar.cpp:463] Applied 1 operations in 4755ns; attempting to update the registry I0315 17:57:08.760619 27767 registrar.cpp:508] Successfully updated the registry in 317184ns I0315 17:57:08.760663 27767 registrar.cpp:394] Successfully recovered registrar I0315 17:57:08.760769 27767 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0315 17:57:08.760819 27767 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0315 17:57:08.763700 27764 cluster.cpp:446] Creating default 'local' authorizer I0315 17:57:08.764443 27780 slave.cpp:215] Mesos agent started on (177)@172.17.0.3:40917 I0315 17:57:08.764753 27764 scheduler.cpp:184] Version: 1.3.0 I0315 17:57:08.764459 27780 slave.cpp:216] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --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/SlaveTest_RunTaskGroup_6MeKBo/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_RunTaskGroup_6MeKBo/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/SlaveTest_RunTaskGroup_6MeKBo/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" --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/SlaveTest_RunTaskGroup_6MeKBo" --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/SlaveTest_RunTaskGroup_3ENia0" I0315 17:57:08.764823 27780 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_RunTaskGroup_6MeKBo/credential' I0315 17:57:08.765205 27769 scheduler.cpp:470] New master detected at [email protected]:40917 I0315 17:57:08.765224 27769 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0315 17:57:08.765373 27780 slave.cpp:358] Agent using credential for: test-principal I0315 17:57:08.765388 27780 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_RunTaskGroup_6MeKBo/http_credentials' I0315 17:57:08.765498 27780 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0315 17:57:08.765540 27780 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0315 17:57:08.765961 27780 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0315 17:57:08.766003 27780 slave.cpp:553] Agent attributes: [ ] I0315 17:57:08.766011 27780 slave.cpp:558] Agent hostname: c8f060cfad16 I0315 17:57:08.766674 27780 status_update_manager.cpp:177] Pausing sending status updates I0315 17:57:08.766937 27780 state.cpp:62] Recovering state from '/tmp/SlaveTest_RunTaskGroup_3ENia0/meta' I0315 17:57:08.767071 27780 status_update_manager.cpp:203] Recovering status update manager I0315 17:57:08.767168 27780 slave.cpp:5753] Finished recovery I0315 17:57:08.767761 27769 scheduler.cpp:361] Connected with the master at http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.768617 27766 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.769306 27768 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0315 17:57:08.769755 27768 http.cpp:440] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48558 I0315 17:57:08.769847 27768 master.cpp:2517] Received subscription request for HTTP framework 'default' I0315 17:57:08.769886 27768 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0315 17:57:08.770122 27768 master.cpp:2632] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0315 17:57:08.770659 27768 hierarchical.cpp:271] Added framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.770777 27777 master.hpp:2179] Sending heartbeat to af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.770839 27768 hierarchical.cpp:1854] No allocations performed I0315 17:57:08.771167 27768 hierarchical.cpp:1944] No inverse offers to send out! I0315 17:57:08.771252 27768 hierarchical.cpp:1438] Performed allocation for 0 agents in 421547ns I0315 17:57:08.771333 27777 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.771754 27777 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.789500 27780 slave.cpp:5935] Querying resource estimator for oversubscribable resources I0315 17:57:08.789835 27780 slave.cpp:936] New master detected at [email protected]:40917 I0315 17:57:08.789970 27780 slave.cpp:971] Detecting new master I0315 17:57:08.790065 27780 slave.cpp:5949] Received oversubscribable resources {} from the resource estimator I0315 17:57:08.790179 27780 status_update_manager.cpp:177] Pausing sending status updates I0315 17:57:08.793530 27780 slave.cpp:998] Authenticating with master [email protected]:40917 I0315 17:57:08.793630 27780 slave.cpp:1009] Using default CRAM-MD5 authenticatee I0315 17:57:08.793977 27779 authenticatee.cpp:121] Creating new client SASL connection I0315 17:57:08.794330 27772 master.cpp:7252] Authenticating slave(177)@172.17.0.3:40917 I0315 17:57:08.794490 27772 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(379)@172.17.0.3:40917 I0315 17:57:08.794713 27772 authenticator.cpp:98] Creating new server SASL connection I0315 17:57:08.795090 27779 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0315 17:57:08.795174 27779 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0315 17:57:08.795426 27767 authenticator.cpp:204] Received SASL authentication start I0315 17:57:08.795583 27767 authenticator.cpp:326] Authentication requires more steps I0315 17:57:08.796517 27779 authenticatee.cpp:259] Received SASL authentication step I0315 17:57:08.797094 27766 authenticator.cpp:232] Received SASL authentication step I0315 17:57:08.797560 27766 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8f060cfad16' server FQDN: 'c8f060cfad16' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0315 17:57:08.797642 27766 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0315 17:57:08.797801 27766 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0315 17:57:08.798140 27766 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'c8f060cfad16' server FQDN: 'c8f060cfad16' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0315 17:57:08.798226 27766 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0315 17:57:08.798382 27766 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0315 17:57:08.798544 27766 authenticator.cpp:318] Authentication success I0315 17:57:08.798888 27770 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(379)@172.17.0.3:40917 I0315 17:57:08.798755 27766 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(177)@172.17.0.3:40917 I0315 17:57:08.806196 27779 authenticatee.cpp:299] Authentication success I0315 17:57:08.806383 27766 slave.cpp:1093] Successfully authenticated with master [email protected]:40917 I0315 17:57:08.806541 27766 slave.cpp:1521] Will retry registration in 13.983777ms if necessary I0315 17:57:08.806737 27775 master.cpp:5437] Registering agent at slave(177)@172.17.0.3:40917 (c8f060cfad16) with id af952b17-67dd-439b-9e34-a154517d2a4d-S0 I0315 17:57:08.806942 27778 registrar.cpp:463] Applied 1 operations in 26050ns; attempting to update the registry I0315 17:57:08.807361 27778 registrar.cpp:508] Successfully updated the registry in 380160ns I0315 17:57:08.807806 27778 master.cpp:5511] Registered agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0315 17:57:08.808125 27765 hierarchical.cpp:527] Added agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 (c8f060cfad16) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0315 17:57:08.808929 27765 hierarchical.cpp:1944] No inverse offers to send out! I0315 17:57:08.808178 27766 slave.cpp:1139] Registered with master [email protected]:40917; given agent ID af952b17-67dd-439b-9e34-a154517d2a4d-S0 I0315 17:57:08.809116 27766 fetcher.cpp:94] Clearing fetcher cache I0315 17:57:08.809609 27766 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/SlaveTest_RunTaskGroup_3ENia0/meta/slaves/af952b17-67dd-439b-9e34-a154517d2a4d-S0/slave.info' I0315 17:57:08.809032 27765 hierarchical.cpp:1438] Performed allocation for 1 agents in 404533ns I0315 17:57:08.810035 27765 master.cpp:7081] Sending 1 offers to framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 (default) I0315 17:57:08.810394 27765 status_update_manager.cpp:184] Resuming sending status updates I0315 17:57:08.811470 27765 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.810772 27766 slave.cpp:1205] Forwarding total oversubscribed resources {} I0315 17:57:08.811898 27766 slave.cpp:4536] Received ping from slave-observer(164)@172.17.0.3:40917 I0315 17:57:08.812003 27769 master.cpp:6098] Received update of agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) with total oversubscribed resources {} I0315 17:57:08.813412 27777 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.3:40917/master/api/v1/scheduler I0315 17:57:08.814371 27777 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0315 17:57:08.815153 27777 http.cpp:440] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48557 I0315 17:57:08.815814 27777 master.cpp:3839] Processing ACCEPT call for offers: [ af952b17-67dd-439b-9e34-a154517d2a4d-O0 ] on agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) for framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 (default) I0315 17:57:08.816221 27777 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 8d05979d-5efb-4e4e-a841-c5fc7b931008 I0315 17:57:08.816678 27777 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task badf8679-4125-4ee7-bf18-46c8d3dbe8e2 I0315 17:57:08.819061 27777 master.cpp:9097] Adding task 8d05979d-5efb-4e4e-a841-c5fc7b931008 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.820292 27777 master.cpp:9097] Adding task badf8679-4125-4ee7-bf18-46c8d3dbe8e2 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.820381 27777 master.cpp:4694] Launching task group { 8d05979d-5efb-4e4e-a841-c5fc7b931008, badf8679-4125-4ee7-bf18-46c8d3dbe8e2 } of framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 (default) with resources cpus(*)(allocated: *):0.2; mem(*)(allocated: *):64; disk(*)(allocated: *):64 on agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 at slave(177)@172.17.0.3:40917 (c8f060cfad16) I0315 17:57:08.820657 27777 slave.cpp:1631] Got assigned task group containing tasks [ 8d05979d-5efb-4e4e-a841-c5fc7b931008, badf8679-4125-4ee7-bf18-46c8d3dbe8e2 ] for framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.821152 27777 slave.cpp:1912] Authorizing task group containing tasks [ 8d05979d-5efb-4e4e-a841-c5fc7b931008, badf8679-4125-4ee7-bf18-46c8d3dbe8e2 ] for framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.821180 27777 slave.cpp:6372] Authorizing framework principal 'test-principal' to launch task 8d05979d-5efb-4e4e-a841-c5fc7b931008 I0315 17:57:08.821264 27777 slave.cpp:6372] Authorizing framework principal 'test-principal' to launch task badf8679-4125-4ee7-bf18-46c8d3dbe8e2 I0315 17:57:08.821686 27777 hierarchical.cpp:1108] 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 af952b17-67dd-439b-9e34-a154517d2a4d-S0 from framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.821730 27777 hierarchical.cpp:1145] Framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 filtered agent af952b17-67dd-439b-9e34-a154517d2a4d-S0 for 5secs I0315 17:57:08.822160 27777 slave.cpp:2099] Launching task group containing tasks [ 8d05979d-5efb-4e4e-a841-c5fc7b931008, badf8679-4125-4ee7-bf18-46c8d3dbe8e2 ] for framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.822772 27777 paths.cpp:547] Trying to chown '/tmp/SlaveTest_RunTaskGroup_3ENia0/slaves/af952b17-67dd-439b-9e34-a154517d2a4d-S0/frameworks/af952b17-67dd-439b-9e34-a154517d2a4d-0000/executors/default/runs/3ec43625-a838-49f3-a0f1-dff3dccd603e' to user 'mesos' I0315 17:57:08.823021 27777 slave.cpp:6719] Launching executor 'default' of framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 with resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 in work directory '/tmp/SlaveTest_RunTaskGroup_3ENia0/slaves/af952b17-67dd-439b-9e34-a154517d2a4d-S0/frameworks/af952b17-67dd-439b-9e34-a154517d2a4d-0000/executors/default/runs/3ec43625-a838-49f3-a0f1-dff3dccd603e' I0315 17:57:08.823288 27777 slave.cpp:2313] Queued task group containing tasks [ 8d05979d-5efb-4e4e-a841-c5fc7b931008, badf8679-4125-4ee7-bf18-46c8d3dbe8e2 ] for executor 'default' of framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.823732 27767 slave.cpp:889] Successfully attached file '/tmp/SlaveTest_RunTaskGroup_3ENia0/slaves/af952b17-67dd-439b-9e34-a154517d2a4d-S0/frameworks/af952b17-67dd-439b-9e34-a154517d2a4d-0000/executors/default/runs/3ec43625-a838-49f3-a0f1-dff3dccd603e' I0315 17:57:08.824650 27777 executor.cpp:192] Version: 1.3.0 I0315 17:57:08.826930 27768 executor.cpp:401] Connected with the agent I0315 17:57:08.827152 27768 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.3:40917/slave(177)/api/v1/executor I0315 17:57:08.827802 27778 process.cpp:3705] Handling HTTP event for process 'slave(177)' with path: '/slave(177)/api/v1/executor' I0315 17:57:08.828048 27778 http.cpp:331] HTTP POST for /slave(177)/api/v1/executor from 172.17.0.3:48563 I0315 17:57:08.828130 27778 slave.cpp:3359] Received Subscribe request for HTTP executor 'default' of framework af952b17-67dd-439b-9e34-a154517d2a4d-0000 I0315 17:57:08.829391 27768 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.3:40917/slave(177)/api/v1/executor *** Aborted at 1489600628 (unix time) try "date -d @1489600628" if you are using GNU date *** PC: @ 0x1c0d503 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() *** SIGSEGV (@0x2ac63e5f0109) received by PID 27764 (TID 0x2ac64a84a700) from PID 1046413577; stack trace: *** @ 0x2ac641639330 (unknown) @ 0x1c0d503 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() @ 0xda5b5b mesos::internal::tests::executor::MockHTTPExecutor<>::event() @ 0xdc9cd7 mesos::internal::tests::executor::TestMesos<>::events() @ 0xdb5b4d std::_Function_handler<>::_M_invoke() @ 0x2ac63ea62075 process::AsyncExecutorProcess::execute<>() @ 0x2ac63ea6bcd1 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISC_SaISC_EEEEESG_PvSK_SG_SN_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSS_FSP_T1_T2_T3_ET4_T5_T6_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2ac63fa09961 process::ProcessManager::resume() @ 0x2ac63fa12b47 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2ac6418f8a60 (unknown) @ 0x2ac641631184 start_thread @ 0x2ac64216137d (unknown) make[3]: *** [CMakeFiles/check] Segmentation fault 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-1489595942-15892 Untagged: mesos-1489595942-15892:latest Deleted: sha256:f768233ca48fa3a9486df2ca228a51ce13ec40a9edf4b904cdd9efad78065b11 Deleted: sha256:1e5ab07c96ee4214ae35e55c660b4a52f88ad5bad92485dd71fa70b2015c42bc Deleted: sha256:44f7af8ee213daec73c54df79893f2bb4127cec1d080315c3cd84b5b7cb3d695 Deleted: sha256:73ddf3bc8e72e568548835d9bbc029d30072dca2d61af42cb9b5a6b3163b5bcc Deleted: sha256:5993730ebdb3909a5930741cd07b3074b3980c809e9372757e21437d7d2704c4 Deleted: sha256:8a1e2025603edcf4a4c6ebee13280b8a61bb1be355c78dcbac8c04a09c8a3635 Deleted: sha256:6d702a9cce6c6d5bbf0af497832384d6c6baaa2a7982d64444b5cfc2845a6cf8 Deleted: sha256:e394551b7884c03d4ff170ff907d5521f1a77335c0ad8ffbce24b54ce7710548 Deleted: sha256:f1c9760ed9b04683101b55a1afa00b0959158cd1f4a16c69e4318dfa086764cc Deleted: sha256:58ef281fa91bc4ad9e49b79bd7d3446377898bb5bdf696822134ab45d0b11f13 Deleted: sha256:c77b3e105646975c084b72c6574c0c41863dc2222aed4d7265f8a76a1213d389 Deleted: sha256:064678f346a9ec6b2b418c704b9052487455c51559b8d1251c6d2293f4849f64 Deleted: sha256:4dca4e672509e1db780980dfb3bd1459f77c699666191c593ecc4d063e29eca7 Deleted: sha256:49ba929305eb6e7e1a4a5bea098998355134a8f3acd0859e910e108105fc36f1 Deleted: sha256:ef7b2bc2263365a31cf8defba4c17d56b1c8e312464a15c3b2e18c815ba30c72 Deleted: sha256:31c5a2769db905e82692c4f1aa9256fc1dcbad1b28105f34382ecaadd1cff270 Deleted: sha256:3c3876eb17b432672b411ea715297d1179272c59f63f952420cb7e852d6898a4 Deleted: sha256:883ac10368498861ceaafaff1ba689de05f950894fb6546d55cba1626f557769 Deleted: sha256:4b220b7a16968068e51bc13ae5b8d8d3a2bdc43bcfb71c601a20811164262a0c Deleted: sha256:42569a5ba0fb473bfb1a293be0824918f26e468bfb16b2a4aabb5e4fc736e323 Deleted: sha256:39528f67dfeebc59d182707c732b25a216d34aa93efdfc3ed613f888116b183e Deleted: sha256:6ddb8156c02f818af132aa995dc1ae4cbfb6bbe88c030894a07d1e9537564282 Deleted: sha256:bbb2c9313059789978e0fdb351ca2abb493a32a47f7633d522fcb7a04d2e06dd Deleted: sha256:27d6ddcc2283bfabb0da72dc33a83ae28a38e373d56131fc54ecde4b413b19f5 Build step 'Execute shell' marked build as failure
