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)/3299/display/redirect?page=changes>
Changes: [yujie.jay] Added test for nested container agent reboot case. ------------------------------------------ [...truncated 23.27 MB...] I0223 08:23:10.036123 27100 master.cpp:5306] Processing MESSAGE call from framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) to agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.036586 27094 executor.cpp:716] Enqueuing event MESSAGE received from http://172.17.0.2:39484/slave(657)/api/v1/executor I0223 08:23:10.037519 27096 master.cpp:1381] Framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) disconnected I0223 08:23:10.037588 27096 master.cpp:3134] Deactivating framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) I0223 08:23:10.037654 27096 master.cpp:3111] Disconnecting framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) I0223 08:23:10.037672 27096 master.cpp:1396] Giving framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) 0ns to failover I0223 08:23:10.037708 27093 hierarchical.cpp:415] Deactivated framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.037770 27104 master.cpp:6877] Framework failover timeout, removing framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) I0223 08:23:10.037783 27104 master.cpp:7762] Removing framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (default) I0223 08:23:10.037849 27104 master.cpp:8347] Updating the state of task e9767b22-7867-4937-9291-894f687342b6 of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0223 08:23:10.037864 27100 slave.cpp:2664] Asked to shut down framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 by [email protected]:39484 I0223 08:23:10.037889 27100 slave.cpp:2689] Shutting down framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.037904 27100 slave.cpp:5089] Shutting down executor 'default' of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (via HTTP) I0223 08:23:10.038106 27104 master.cpp:8441] Removing task e9767b22-7867-4937-9291-894f687342b6 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 on agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.038183 27105 hierarchical.cpp:1064] 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 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 from framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.038233 27093 executor.cpp:716] Enqueuing event SHUTDOWN received from http://172.17.0.2:39484/slave(657)/api/v1/executor I0223 08:23:10.038262 27104 master.cpp:8470] Removing executor 'default' with resources {} of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 on agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.038384 27106 hierarchical.cpp:362] Removed framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.039321 27099 slave.cpp:4775] Executor 'default' of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 exited with status 0 I0223 08:23:10.039356 27099 slave.cpp:4875] Cleaning up executor 'default' of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 (via HTTP) W0223 08:23:10.039458 27092 master.cpp:6323] Ignoring unknown exited executor 'default' of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 on agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.047336 27092 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_HcSCg3/slaves/413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0/frameworks/413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000/executors/default/runs/d33c76bf-32f4-4bda-a32a-cdf1f56a28db' for gc 6.99999945373926days in the future I0223 08:23:10.047381 27099 slave.cpp:4963] Cleaning up framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.047441 27092 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_HcSCg3/slaves/413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0/frameworks/413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000/executors/default' for gc 6.99999945187556days in the future I0223 08:23:10.047487 27101 status_update_manager.cpp:285] Closing status update streams for framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.047494 27099 slave.cpp:803] Agent terminating I0223 08:23:10.047488 27092 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_HcSCg3/slaves/413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0/frameworks/413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000' for gc 6.99999945064889days in the future I0223 08:23:10.047519 27101 status_update_manager.cpp:531] Cleaning up status update stream for task e9767b22-7867-4937-9291-894f687342b6 of framework 413d3bc7-821b-4952-9836-f5e6ec8d3d94-0000 I0223 08:23:10.047642 27095 master.cpp:1264] Agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) disconnected I0223 08:23:10.047667 27095 master.cpp:3171] Disconnecting agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.047693 27095 master.cpp:3190] Deactivating agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 at slave(657)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.047775 27102 hierarchical.cpp:618] Agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 deactivated I0223 08:23:10.049293 27090 master.cpp:1103] Master terminating I0223 08:23:10.049506 27091 hierarchical.cpp:549] Removed agent 413d3bc7-821b-4952-9836-f5e6ec8d3d94-S0 [ OK ] ContentType/SchedulerTest.Message/0 (145 ms) [ RUN ] ContentType/SchedulerTest.Message/1 I0223 08:23:10.079277 27090 cluster.cpp:160] Creating default 'local' authorizer I0223 08:23:10.080219 27098 master.cpp:383] Master 335f3e82-daab-44ef-bebb-991f94f10b3b (e2bc3c03b771) started on 172.17.0.2:39484 I0223 08:23:10.080301 27098 master.cpp:385] 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/XTuBuT/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/XTuBuT/master" --zk_session_timeout="10secs" I0223 08:23:10.080651 27098 master.cpp:435] Master only allowing authenticated frameworks to register I0223 08:23:10.080708 27098 master.cpp:449] Master only allowing authenticated agents to register I0223 08:23:10.080757 27098 master.cpp:462] Master only allowing authenticated HTTP frameworks to register I0223 08:23:10.080797 27098 credentials.hpp:37] Loading credentials for authentication from '/tmp/XTuBuT/credentials' I0223 08:23:10.080991 27098 master.cpp:507] Using default 'crammd5' authenticator I0223 08:23:10.081104 27098 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0223 08:23:10.081197 27098 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0223 08:23:10.081270 27098 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0223 08:23:10.081339 27098 master.cpp:587] Authorization enabled I0223 08:23:10.081621 27097 hierarchical.cpp:161] Initialized hierarchical allocator process I0223 08:23:10.081646 27097 whitelist_watcher.cpp:77] No whitelist given I0223 08:23:10.082362 27098 master.cpp:2122] Elected as the leading master! I0223 08:23:10.082435 27098 master.cpp:1646] Recovering from registrar I0223 08:23:10.082546 27098 registrar.cpp:329] Recovering registrar I0223 08:23:10.082753 27106 registrar.cpp:362] Successfully fetched the registry (0B) in 124928ns I0223 08:23:10.082794 27106 registrar.cpp:461] Applied 1 operations in 5167ns; attempting to update the registry I0223 08:23:10.083178 27099 registrar.cpp:506] Successfully updated the registry in 340992ns I0223 08:23:10.083232 27099 registrar.cpp:392] Successfully recovered registrar I0223 08:23:10.083359 27096 master.cpp:1760] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0223 08:23:10.083483 27105 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover I0223 08:23:10.085644 27090 cluster.cpp:446] Creating default 'local' authorizer I0223 08:23:10.086297 27091 slave.cpp:211] Mesos agent started on (658)@172.17.0.2:39484 I0223 08:23:10.086516 27090 scheduler.cpp:184] Version: 1.3.0 I0223 08:23:10.086700 27091 slave.cpp:212] 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/ContentType_SchedulerTest_Message_1_Z4FpbP/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/ContentType_SchedulerTest_Message_1_Z4FpbP/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/ContentType_SchedulerTest_Message_1_Z4FpbP/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/ContentType_SchedulerTest_Message_1_Z4FpbP" --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_Message_1_lf4ZkR" I0223 08:23:10.087333 27091 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_Message_1_Z4FpbP/credential' I0223 08:23:10.087059 27102 scheduler.cpp:470] New master detected at [email protected]:39484 I0223 08:23:10.087401 27102 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0223 08:23:10.087491 27091 slave.cpp:354] Agent using credential for: test-principal I0223 08:23:10.087563 27091 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Message_1_Z4FpbP/http_credentials' I0223 08:23:10.087718 27091 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0223 08:23:10.087797 27091 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0223 08:23:10.088315 27091 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0223 08:23:10.088469 27091 slave.cpp:549] Agent attributes: [ ] I0223 08:23:10.088511 27091 slave.cpp:554] Agent hostname: e2bc3c03b771 I0223 08:23:10.088353 27094 scheduler.cpp:361] Connected with the master at http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.088686 27094 status_update_manager.cpp:177] Pausing sending status updates I0223 08:23:10.088977 27098 state.cpp:62] Recovering state from '/tmp/ContentType_SchedulerTest_Message_1_lf4ZkR/meta' I0223 08:23:10.089074 27098 status_update_manager.cpp:203] Recovering status update manager I0223 08:23:10.089200 27096 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.089493 27098 slave.cpp:5564] Finished recovery I0223 08:23:10.089817 27093 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0223 08:23:10.089820 27098 slave.cpp:5738] Querying resource estimator for oversubscribable resources I0223 08:23:10.089946 27098 slave.cpp:931] New master detected at [email protected]:39484 I0223 08:23:10.089960 27095 status_update_manager.cpp:177] Pausing sending status updates I0223 08:23:10.089984 27098 slave.cpp:966] Detecting new master I0223 08:23:10.090112 27098 slave.cpp:5752] Received oversubscribable resources {} from the resource estimator I0223 08:23:10.090385 27096 http.cpp:430] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44785 I0223 08:23:10.090494 27096 master.cpp:2474] Received subscription request for HTTP framework 'default' I0223 08:23:10.090533 27096 master.cpp:2158] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0223 08:23:10.090680 27095 master.cpp:2589] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0223 08:23:10.090867 27096 hierarchical.cpp:286] Added framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.090966 27096 hierarchical.cpp:1810] No allocations performed I0223 08:23:10.090989 27096 hierarchical.cpp:1900] No inverse offers to send out! I0223 08:23:10.091003 27096 hierarchical.cpp:1394] Performed allocation for 0 agents in 50969ns I0223 08:23:10.090965 27106 master.hpp:2134] Sending heartbeat to 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.091529 27093 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.091758 27097 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.095515 27105 slave.cpp:993] Authenticating with master [email protected]:39484 I0223 08:23:10.095537 27105 slave.cpp:1004] Using default CRAM-MD5 authenticatee I0223 08:23:10.095665 27103 authenticatee.cpp:121] Creating new client SASL connection I0223 08:23:10.095834 27105 master.cpp:7208] Authenticating slave(658)@172.17.0.2:39484 I0223 08:23:10.095968 27095 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1235)@172.17.0.2:39484 I0223 08:23:10.096050 27106 authenticator.cpp:98] Creating new server SASL connection I0223 08:23:10.096245 27092 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0223 08:23:10.096276 27092 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0223 08:23:10.096328 27092 authenticator.cpp:204] Received SASL authentication start I0223 08:23:10.096387 27092 authenticator.cpp:326] Authentication requires more steps I0223 08:23:10.096426 27092 authenticatee.cpp:259] Received SASL authentication step I0223 08:23:10.096467 27092 authenticator.cpp:232] Received SASL authentication step I0223 08:23:10.096491 27092 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e2bc3c03b771' server FQDN: 'e2bc3c03b771' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0223 08:23:10.096504 27092 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0223 08:23:10.096520 27092 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0223 08:23:10.096530 27092 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e2bc3c03b771' server FQDN: 'e2bc3c03b771' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0223 08:23:10.096540 27092 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0223 08:23:10.096546 27092 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0223 08:23:10.096560 27092 authenticator.cpp:318] Authentication success I0223 08:23:10.096616 27105 authenticatee.cpp:299] Authentication success I0223 08:23:10.096635 27101 master.cpp:7238] Successfully authenticated principal 'test-principal' at slave(658)@172.17.0.2:39484 I0223 08:23:10.096663 27092 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1235)@172.17.0.2:39484 I0223 08:23:10.096839 27105 slave.cpp:1088] Successfully authenticated with master [email protected]:39484 I0223 08:23:10.096909 27105 slave.cpp:1521] Will retry registration in 17.025952ms if necessary I0223 08:23:10.096982 27096 master.cpp:5423] Registering agent at slave(658)@172.17.0.2:39484 (e2bc3c03b771) with id 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 I0223 08:23:10.097129 27095 registrar.cpp:461] Applied 1 operations in 13757ns; attempting to update the registry I0223 08:23:10.097527 27095 registrar.cpp:506] Successfully updated the registry in 369920ns I0223 08:23:10.097738 27095 master.cpp:5497] Registered agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0223 08:23:10.097792 27091 slave.cpp:4352] Received ping from slave-observer(592)@172.17.0.2:39484 I0223 08:23:10.097849 27105 hierarchical.cpp:516] Added agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 (e2bc3c03b771) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0223 08:23:10.097872 27091 slave.cpp:1134] Registered with master [email protected]:39484; given agent ID 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 I0223 08:23:10.097889 27091 fetcher.cpp:94] Clearing fetcher cache I0223 08:23:10.098000 27092 status_update_manager.cpp:184] Resuming sending status updates I0223 08:23:10.098161 27105 hierarchical.cpp:1900] No inverse offers to send out! I0223 08:23:10.098177 27105 hierarchical.cpp:1394] Performed allocation for 1 agents in 261403ns I0223 08:23:10.098235 27091 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_Message_1_lf4ZkR/meta/slaves/335f3e82-daab-44ef-bebb-991f94f10b3b-S0/slave.info' I0223 08:23:10.098332 27096 master.cpp:7037] Sending 1 offers to framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.098515 27091 slave.cpp:1200] Forwarding total oversubscribed resources {} I0223 08:23:10.098814 27096 master.cpp:6054] Received update of agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) with total oversubscribed resources {} I0223 08:23:10.099009 27096 hierarchical.cpp:589] Agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 (e2bc3c03b771) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]) I0223 08:23:10.099097 27096 hierarchical.cpp:1810] No allocations performed I0223 08:23:10.099138 27096 hierarchical.cpp:1900] No inverse offers to send out! I0223 08:23:10.099172 27096 hierarchical.cpp:1394] Performed allocation for 1 agents in 92658ns I0223 08:23:10.099684 27099 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.100571 27097 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.101300 27092 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0223 08:23:10.101763 27099 http.cpp:430] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44784 I0223 08:23:10.102427 27099 master.cpp:3825] Processing ACCEPT call for offers: [ 335f3e82-daab-44ef-bebb-991f94f10b3b-O0 ] on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) for framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.102470 27099 master.cpp:3401] Authorizing framework principal 'test-principal' to launch task f29b200c-785a-468f-bdc9-0439913598ed W0223 08:23:10.103263 27091 validation.cpp:1064] Executor 'default' for task 'f29b200c-785a-468f-bdc9-0439913598ed' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0223 08:23:10.103288 27091 validation.cpp:1076] Executor 'default' for task 'f29b200c-785a-468f-bdc9-0439913598ed' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0223 08:23:10.103405 27091 master.cpp:9098] Adding task f29b200c-785a-468f-bdc9-0439913598ed with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.103495 27091 master.cpp:4475] Launching task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.103701 27103 slave.cpp:1631] Got assigned task 'f29b200c-785a-468f-bdc9-0439913598ed' for framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.103950 27103 slave.cpp:1791] Launching task 'f29b200c-785a-468f-bdc9-0439913598ed' for framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.104115 27093 hierarchical.cpp:807] Updated allocation of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] I0223 08:23:10.153504 27103 paths.cpp:547] Trying to chown '/tmp/ContentType_SchedulerTest_Message_1_lf4ZkR/slaves/335f3e82-daab-44ef-bebb-991f94f10b3b-S0/frameworks/335f3e82-daab-44ef-bebb-991f94f10b3b-0000/executors/default/runs/d6bab835-4f3c-4a5c-bd46-102445cc9162' to user 'mesos' I0223 08:23:10.153733 27103 slave.cpp:6486] Launching executor 'default' of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 with resources {} in work directory '/tmp/ContentType_SchedulerTest_Message_1_lf4ZkR/slaves/335f3e82-daab-44ef-bebb-991f94f10b3b-S0/frameworks/335f3e82-daab-44ef-bebb-991f94f10b3b-0000/executors/default/runs/d6bab835-4f3c-4a5c-bd46-102445cc9162' I0223 08:23:10.154094 27103 slave.cpp:2124] Queued task 'f29b200c-785a-468f-bdc9-0439913598ed' for executor 'default' of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.154147 27103 slave.cpp:884] Successfully attached file '/tmp/ContentType_SchedulerTest_Message_1_lf4ZkR/slaves/335f3e82-daab-44ef-bebb-991f94f10b3b-S0/frameworks/335f3e82-daab-44ef-bebb-991f94f10b3b-0000/executors/default/runs/d6bab835-4f3c-4a5c-bd46-102445cc9162' I0223 08:23:10.154834 27101 executor.cpp:192] Version: 1.3.0 I0223 08:23:10.155573 27101 executor.cpp:401] Connected with the agent I0223 08:23:10.155900 27106 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.156347 27105 process.cpp:3704] Handling HTTP event for process 'slave(658)' with path: '/slave(658)/api/v1/executor' I0223 08:23:10.156692 27095 http.cpp:314] HTTP POST for /slave(658)/api/v1/executor from 172.17.0.2:44791 I0223 08:23:10.156785 27095 slave.cpp:3175] Received Subscribe request for HTTP executor 'default' of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.157196 27095 slave.cpp:2337] Sending queued task 'f29b200c-785a-468f-bdc9-0439913598ed' to executor 'default' of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (via HTTP) I0223 08:23:10.157732 27092 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.157969 27092 executor.cpp:716] Enqueuing event LAUNCH received from http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.158377 27091 executor.cpp:302] Sending UPDATE call to http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.158743 27091 process.cpp:3704] Handling HTTP event for process 'slave(658)' with path: '/slave(658)/api/v1/executor' I0223 08:23:10.159035 27097 http.cpp:314] HTTP POST for /slave(658)/api/v1/executor from 172.17.0.2:44792 I0223 08:23:10.159085 27097 slave.cpp:3822] Handling status update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.159332 27097 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.159359 27097 status_update_manager.cpp:500] Creating StatusUpdate stream for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.159512 27097 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 to the agent I0223 08:23:10.159791 27092 slave.cpp:4262] Forwarding the update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 to [email protected]:39484 I0223 08:23:10.159886 27092 slave.cpp:4156] Status update manager successfully handled status update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.159926 27097 master.cpp:6199] Status update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 from agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.159960 27097 master.cpp:6267] Forwarding status update TASK_RUNNING (UUID: 53dcd3ea-07b8-442f-8b07-2fec3e49b1c9) for task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.160229 27097 master.cpp:8347] Updating the state of task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0223 08:23:10.160282 27097 executor.cpp:716] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.160739 27101 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.161212 27095 scheduler.cpp:243] Sending MESSAGE call to http://172.17.0.2:39484/master/api/v1/scheduler I0223 08:23:10.161600 27106 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0223 08:23:10.200093 27101 http.cpp:430] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44784 I0223 08:23:10.200194 27101 master.cpp:5306] Processing MESSAGE call from framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) to agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.200595 27097 executor.cpp:716] Enqueuing event MESSAGE received from http://172.17.0.2:39484/slave(658)/api/v1/executor I0223 08:23:10.201465 27102 master.cpp:1381] Framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) disconnected I0223 08:23:10.201484 27102 master.cpp:3134] Deactivating framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.201511 27102 master.cpp:3111] Disconnecting framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.201526 27102 master.cpp:1396] Giving framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) 0ns to failover I0223 08:23:10.201567 27103 hierarchical.cpp:415] Deactivated framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.201648 27096 master.cpp:6877] Framework failover timeout, removing framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.201663 27096 master.cpp:7762] Removing framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (default) I0223 08:23:10.201712 27096 master.cpp:8347] Updating the state of task f29b200c-785a-468f-bdc9-0439913598ed of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0223 08:23:10.201932 27096 master.cpp:8441] Removing task f29b200c-785a-468f-bdc9-0439913598ed with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.202062 27096 master.cpp:8470] Removing executor 'default' with resources {} of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 on agent 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 at slave(658)@172.17.0.2:39484 (e2bc3c03b771) I0223 08:23:10.202170 27096 slave.cpp:2664] Asked to shut down framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 by [email protected]:39484 I0223 08:23:10.202191 27096 slave.cpp:2689] Shutting down framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.202203 27096 slave.cpp:5089] Shutting down executor 'default' of framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 (via HTTP) I0223 08:23:10.202457 27096 hierarchical.cpp:1064] 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 335f3e82-daab-44ef-bebb-991f94f10b3b-S0 from framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.202548 27096 hierarchical.cpp:362] Removed framework 335f3e82-daab-44ef-bebb-991f94f10b3b-0000 I0223 08:23:10.202956 27104 executor.cpp:716] Enqueuing event SHUTDOWN received from http://172.17.0.2:39484/slave(658)/api/v1/executor *** Aborted at 1487838190 (unix time) try "date -d @1487838190" if you are using GNU date *** PC: @ 0x1bc49c3 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() *** SIGSEGV (@0x2adde8eb3378) received by PID 27090 (TID 0x2addf47b5700) from PID 18446744073322312568; stack trace: *** @ 0x2addeb610330 (unknown) @ 0x1bc49c3 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName() @ 0xd8dcb1 mesos::internal::tests::executor::MockHTTPExecutor<>::event() @ 0xdb1667 mesos::internal::tests::executor::TestMesos<>::events() @ 0xd9d7ed std::_Function_handler<>::_M_invoke() @ 0x2adde8eb33a5 process::AsyncExecutorProcess::execute<>() @ 0x2adde8ebcdc1 _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_ @ 0x2adde9e234f1 process::ProcessManager::resume() @ 0x2adde9e2c327 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2addeb8cfa60 (unknown) @ 0x2addeb608184 start_thread @ 0x2addec13837d (unknown) make[3]: Leaving directory `/mesos/build' make[3]: *** [CMakeFiles/check] Segmentation fault 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-1487832582-24278 Untagged: mesos-1487832582-24278:latest Deleted: sha256:565d4e82b063e15b73ccf66defa25261a9e9d772723cb64e9ff0e06ff8fdfeab Deleted: sha256:129f1bf1898ddeea2c56146c4296b5656d557d09062dceca6809f49c74a25760 Deleted: sha256:69d7e10ba1aa39bfcced7713829efe29ca5d224f4a615a8e4aeb3e8efc17d104 Deleted: sha256:20f37adbbf64dba0cd393c26b04890f8ea06fb3c21805792c68621b8865dc87b Deleted: sha256:b278dc545735c79e31718604302ba4fd9ee3918ded3d47c0c7725fbdfc60b892 Deleted: sha256:02fb96c716fcebdefc3c0f03aebf12801eea7465ad3b98cdd9f8ea360338e296 Deleted: sha256:d73a03abfee4c85db3afb1c500e423b9cf7298fb08ada2881c8e1509118eaa55 Deleted: sha256:149b83521303c801bd3dd186d59d43f72b5b27f815a0b51f91cb46e0b806f7c7 Deleted: sha256:a016f9d3c06c4233bd4ced7f89741e791f50ea3af444da706a5ca434682444a3 Deleted: sha256:a22fd8aebe26d436a44c7d11f81dbbc335f009a477ea95a7c9834183d443bdde Deleted: sha256:28b846394f3a92a6fd5c54be53822e43d0915feb6f3cffa53e6a55dcc7b1322e Deleted: sha256:37346d6eeca427ff5cbf88e5806b2d795da32f8e07d2f4a33b0904eb6ca97491 Deleted: sha256:e34371d8f01ed7ce64e26edd3f671936604436c8f5fd0fd2ae6fc6f953aacbf1 Deleted: sha256:a73f43efa3cf34a58433f1e5cdc35a8d54962f65cf14558e9ec516669237eb1a Deleted: sha256:c51e7b0745a47aa0ca1011f6046740125e0bbcee1c4063dc98d420da4ef32585 Deleted: sha256:7f1e727aa7a351f06e1bb897acc5942390df55a7e0e4eaa23fd9658f58e1d6e1 Deleted: sha256:1957c2768eb44c22e06b3a2f2cf4b4440902e600fb2f55f9fca0ccf90f343d38 Deleted: sha256:052bb2ee7ad06fa3fbb2bf6c4c48b79aa1a7cc444b3a1234a6d8fdd64edebb35 Deleted: sha256:13cae7ceb7eb2584e374c1455716e5151f8fad734584ac680d1d8ddcdc5ad6d3 Deleted: sha256:dee0dceffd354b41e8bed92c7eb374872f31bd4f53d3bdb7b9c7ee8a2c9f3c23 Deleted: sha256:7591ec614dcfa7d3d70baf8c0ec0d89d9f419d0345c9784df0bcfddb0b86d860 Deleted: sha256:ec9d741310a8c453492b7f39d3749acc7fe6b9095ade6e4ec350a176674e90c8 Deleted: sha256:53150ef652296b4950c1a2f395e8977378266c489cce534c3a1037aeb7dbedde Deleted: sha256:ad5c49b1dc146147562321b8815d6bf2f082989c7c605934dc23b7556fb9fbc1 Build step 'Execute shell' marked build as failure
