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)/3257/changes>
Changes: [gyliu513] Added `drop` for overload to avoid custom logging. [gyliu513] Made ignoring logging use WARNING in master. [gyliu513] Added a test to ensure multi-role framework receiving offers. ------------------------------------------ [...truncated 27419 lines...] I0211 06:15:01.173408 27156 executor.cpp:192] Version: 1.3.0 I0211 06:15:01.173682 27167 slave.cpp:884] Successfully attached file '/tmp/HttpFaultToleranceTest_SchedulerFailoverFrameworkToExecutorMessage_mf4QxR/slaves/e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0/frameworks/e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000/executors/default/runs/058dc165-6a07-4387-bf95-b25b90f3e1c9' I0211 06:15:01.177922 27161 hierarchical.cpp:807] Updated allocation of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 on agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-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] I0211 06:15:01.178545 27163 executor.cpp:401] Connected with the agent I0211 06:15:01.179095 27163 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.3:55722/slave(68)/api/v1/executor I0211 06:15:01.180030 27164 process.cpp:3704] Handling HTTP event for process 'slave(68)' with path: '/slave(68)/api/v1/executor' I0211 06:15:01.180713 27164 http.cpp:307] HTTP POST for /slave(68)/api/v1/executor from 172.17.0.3:48687 I0211 06:15:01.181095 27164 slave.cpp:3166] Received Subscribe request for HTTP executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.181998 27164 slave.cpp:2328] Sending queued task '96450b39-49f9-417e-816a-7bd0adc33770' to executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (via HTTP) I0211 06:15:01.183902 27163 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.3:55722/slave(68)/api/v1/executor I0211 06:15:01.184706 27164 executor.cpp:716] Enqueuing event LAUNCH received from http://172.17.0.3:55722/slave(68)/api/v1/executor I0211 06:15:01.189313 27153 scheduler.cpp:184] Version: 1.3.0 I0211 06:15:01.190078 27154 scheduler.cpp:470] New master detected at [email protected]:55722 I0211 06:15:01.191032 27154 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0211 06:15:01.194169 27168 scheduler.cpp:361] Connected with the master at http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.195216 27159 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.196311 27159 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0211 06:15:01.197057 27159 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48690 I0211 06:15:01.197419 27159 master.cpp:2463] Received subscription request for HTTP framework 'default' I0211 06:15:01.197700 27159 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*' I0211 06:15:01.198227 27159 master.cpp:2577] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0211 06:15:01.198493 27159 master.cpp:2623] Updating info for framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.199090 27159 master.hpp:2134] Sending heartbeat to e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.199441 27159 slave.cpp:2824] Updating framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 pid to @0.0.0.0:0 I0211 06:15:01.199733 27159 master.cpp:1237] Ignoring disconnection for framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) as it has already reconnected I0211 06:15:01.200060 27159 status_update_manager.cpp:184] Resuming sending status updates I0211 06:15:01.201341 27155 scheduler.cpp:676] Enqueuing event ERROR received from http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.201508 27162 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.202775 27162 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.3:55722/master/api/v1/scheduler E0211 06:15:01.203472 27155 scheduler.cpp:649] End-Of-File received from master. The master closed the event stream I0211 06:15:01.203980 27155 scheduler.cpp:444] Re-detecting master I0211 06:15:01.204639 27155 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I0211 06:15:01.205085 27155 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I0211 06:15:01.205332 27155 scheduler.cpp:470] New master detected at [email protected]:55722 I0211 06:15:01.205509 27155 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0211 06:15:01.234442 27163 scheduler.cpp:361] Connected with the master at http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.257262 27165 scheduler.cpp:243] Sending MESSAGE call to http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.258463 27157 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0211 06:15:01.259151 27157 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48689 I0211 06:15:01.259466 27157 master.cpp:5303] Processing MESSAGE call from framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) to agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.260576 27157 executor.cpp:716] Enqueuing event MESSAGE received from http://172.17.0.3:55722/slave(68)/api/v1/executor I0211 06:15:01.265709 27157 master.cpp:1381] Framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) disconnected I0211 06:15:01.266008 27157 master.cpp:3121] Deactivating framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) I0211 06:15:01.266284 27157 master.cpp:3098] Disconnecting framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) I0211 06:15:01.266551 27157 master.cpp:1396] Giving framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) 0ns to failover I0211 06:15:01.266877 27157 hierarchical.cpp:415] Deactivated framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.267127 27157 master.cpp:6874] Framework failover timeout, removing framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) I0211 06:15:01.267160 27157 master.cpp:7759] Removing framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (default) I0211 06:15:01.267233 27157 master.cpp:8344] Updating the state of task 96450b39-49f9-417e-816a-7bd0adc33770 of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0211 06:15:01.267462 27157 master.cpp:8438] Removing task 96450b39-49f9-417e-816a-7bd0adc33770 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 on agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.267596 27157 master.cpp:8467] Removing executor 'default' with resources {} of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 on agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.267699 27157 slave.cpp:2655] Asked to shut down framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 by [email protected]:55722 I0211 06:15:01.267724 27157 slave.cpp:2680] Shutting down framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.267738 27157 slave.cpp:5080] Shutting down executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (via HTTP) I0211 06:15:01.268033 27157 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 e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 from framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.268146 27157 hierarchical.cpp:362] Removed framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.268643 27156 executor.cpp:716] Enqueuing event SHUTDOWN received from http://172.17.0.3:55722/slave(68)/api/v1/executor I0211 06:15:01.270975 27155 slave.cpp:4766] Executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 exited with status 0 W0211 06:15:01.272353 27162 master.cpp:6320] Ignoring unknown exited executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 on agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.272234 27155 slave.cpp:4866] Cleaning up executor 'default' of framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 (via HTTP) I0211 06:15:01.273308 27157 gc.cpp:55] Scheduling '/tmp/HttpFaultToleranceTest_SchedulerFailoverFrameworkToExecutorMessage_mf4QxR/slaves/e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0/frameworks/e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000/executors/default/runs/058dc165-6a07-4387-bf95-b25b90f3e1c9' for gc 6.99999683749926days in the future I0211 06:15:01.273761 27155 slave.cpp:4954] Cleaning up framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.273918 27154 gc.cpp:55] Scheduling '/tmp/HttpFaultToleranceTest_SchedulerFailoverFrameworkToExecutorMessage_mf4QxR/slaves/e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0/frameworks/e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000/executors/default' for gc 6.99999683182815days in the future I0211 06:15:01.274185 27165 status_update_manager.cpp:285] Closing status update streams for framework e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000 I0211 06:15:01.274662 27155 slave.cpp:803] Agent terminating I0211 06:15:01.274683 27169 gc.cpp:55] Scheduling '/tmp/HttpFaultToleranceTest_SchedulerFailoverFrameworkToExecutorMessage_mf4QxR/slaves/e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0/frameworks/e05059bb-000f-4ff9-befd-d9accfe7c9e0-0000' for gc 6.99999682166519days in the future I0211 06:15:01.275921 27155 master.cpp:1264] Agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) disconnected I0211 06:15:01.276208 27155 master.cpp:3158] Disconnecting agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.276492 27155 master.cpp:3177] Deactivating agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 at slave(68)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.276832 27167 hierarchical.cpp:618] Agent e05059bb-000f-4ff9-befd-d9accfe7c9e0-S0 deactivated I0211 06:15:01.285562 27153 master.cpp:1103] Master terminating [ OK ] HttpFaultToleranceTest.SchedulerFailoverFrameworkToExecutorMessage (210 ms) [ RUN ] HttpFaultToleranceTest.SchedulerExit I0211 06:15:01.291275 27153 cluster.cpp:160] Creating default 'local' authorizer I0211 06:15:01.293138 27154 master.cpp:383] Master 5ce885bd-f63f-4a31-a804-20dadfa8066e (b1881548deae) started on 172.17.0.3:55722 I0211 06:15:01.293311 27154 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="false" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/9LBYcw/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/9LBYcw/master" --zk_session_timeout="10secs" I0211 06:15:01.293758 27154 master.cpp:437] Master allowing unauthenticated frameworks to register I0211 06:15:01.293915 27154 master.cpp:449] Master only allowing authenticated agents to register I0211 06:15:01.294076 27154 master.cpp:462] Master only allowing authenticated HTTP frameworks to register I0211 06:15:01.294234 27154 credentials.hpp:37] Loading credentials for authentication from '/tmp/9LBYcw/credentials' I0211 06:15:01.294522 27154 master.cpp:507] Using default 'crammd5' authenticator I0211 06:15:01.294721 27154 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0211 06:15:01.294930 27154 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0211 06:15:01.295125 27154 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0211 06:15:01.295318 27154 master.cpp:587] Authorization enabled I0211 06:15:01.295553 27165 hierarchical.cpp:161] Initialized hierarchical allocator process I0211 06:15:01.295835 27165 whitelist_watcher.cpp:77] No whitelist given I0211 06:15:01.296934 27167 master.cpp:2124] Elected as the leading master! I0211 06:15:01.297219 27167 master.cpp:1646] Recovering from registrar I0211 06:15:01.297564 27167 registrar.cpp:329] Recovering registrar I0211 06:15:01.298095 27167 registrar.cpp:362] Successfully fetched the registry (0B) in 272896ns I0211 06:15:01.298385 27167 registrar.cpp:461] Applied 1 operations in 5351ns; attempting to update the registry I0211 06:15:01.298977 27167 registrar.cpp:506] Successfully updated the registry in 310016ns I0211 06:15:01.299283 27167 registrar.cpp:392] Successfully recovered registrar I0211 06:15:01.299707 27167 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0211 06:15:01.300026 27167 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover I0211 06:15:01.304044 27153 cluster.cpp:446] Creating default 'local' authorizer I0211 06:15:01.309664 27166 slave.cpp:211] Mesos agent started on (69)@172.17.0.3:55722 I0211 06:15:01.309983 27166 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/HttpFaultToleranceTest_SchedulerExit_JINujR/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/HttpFaultToleranceTest_SchedulerExit_JINujR/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/HttpFaultToleranceTest_SchedulerExit_JINujR/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/HttpFaultToleranceTest_SchedulerExit_JINujR" --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/HttpFaultToleranceTest_SchedulerExit_ul2dLb" I0211 06:15:01.310724 27166 credentials.hpp:86] Loading credential for authentication from '/tmp/HttpFaultToleranceTest_SchedulerExit_JINujR/credential' I0211 06:15:01.311122 27166 slave.cpp:354] Agent using credential for: test-principal I0211 06:15:01.311389 27166 credentials.hpp:37] Loading credentials for authentication from '/tmp/HttpFaultToleranceTest_SchedulerExit_JINujR/http_credentials' I0211 06:15:01.311785 27166 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0211 06:15:01.312098 27166 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0211 06:15:01.312960 27166 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0211 06:15:01.313277 27166 slave.cpp:549] Agent attributes: [ ] I0211 06:15:01.313541 27166 slave.cpp:554] Agent hostname: b1881548deae I0211 06:15:01.313918 27157 status_update_manager.cpp:177] Pausing sending status updates I0211 06:15:01.314834 27166 state.cpp:62] Recovering state from '/tmp/HttpFaultToleranceTest_SchedulerExit_ul2dLb/meta' I0211 06:15:01.315274 27168 status_update_manager.cpp:203] Recovering status update manager I0211 06:15:01.315665 27168 slave.cpp:5555] Finished recovery I0211 06:15:01.316364 27168 slave.cpp:5729] Querying resource estimator for oversubscribable resources I0211 06:15:01.316864 27164 status_update_manager.cpp:177] Pausing sending status updates I0211 06:15:01.317179 27168 slave.cpp:931] New master detected at [email protected]:55722 I0211 06:15:01.317482 27168 slave.cpp:966] Detecting new master I0211 06:15:01.317796 27168 slave.cpp:5743] Received oversubscribable resources {} from the resource estimator I0211 06:15:01.318622 27153 scheduler.cpp:184] Version: 1.3.0 I0211 06:15:01.319183 27156 scheduler.cpp:470] New master detected at [email protected]:55722 I0211 06:15:01.319206 27156 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0211 06:15:01.321487 27156 slave.cpp:993] Authenticating with master [email protected]:55722 I0211 06:15:01.321538 27156 slave.cpp:1004] Using default CRAM-MD5 authenticatee I0211 06:15:01.321656 27156 authenticatee.cpp:121] Creating new client SASL connection I0211 06:15:01.321869 27156 master.cpp:7205] Authenticating slave(69)@172.17.0.3:55722 I0211 06:15:01.321929 27156 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(160)@172.17.0.3:55722 I0211 06:15:01.322008 27156 authenticator.cpp:98] Creating new server SASL connection I0211 06:15:01.322140 27156 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0211 06:15:01.322163 27156 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0211 06:15:01.322199 27156 authenticator.cpp:204] Received SASL authentication start I0211 06:15:01.322259 27156 authenticator.cpp:326] Authentication requires more steps I0211 06:15:01.322299 27156 authenticatee.cpp:259] Received SASL authentication step I0211 06:15:01.322353 27156 authenticator.cpp:232] Received SASL authentication step I0211 06:15:01.322376 27156 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b1881548deae' server FQDN: 'b1881548deae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0211 06:15:01.322388 27156 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0211 06:15:01.322403 27156 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0211 06:15:01.322417 27156 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b1881548deae' server FQDN: 'b1881548deae' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0211 06:15:01.322427 27156 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0211 06:15:01.322434 27156 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0211 06:15:01.322451 27156 authenticator.cpp:318] Authentication success I0211 06:15:01.322499 27156 authenticatee.cpp:299] Authentication success I0211 06:15:01.322533 27156 master.cpp:7235] Successfully authenticated principal 'test-principal' at slave(69)@172.17.0.3:55722 I0211 06:15:01.322567 27156 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(160)@172.17.0.3:55722 I0211 06:15:01.322646 27156 slave.cpp:1088] Successfully authenticated with master [email protected]:55722 I0211 06:15:01.322716 27156 slave.cpp:1515] Will retry registration in 18.020589ms if necessary I0211 06:15:01.322845 27156 master.cpp:5420] Registering agent at slave(69)@172.17.0.3:55722 (b1881548deae) with id 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 I0211 06:15:01.323000 27156 registrar.cpp:461] Applied 1 operations in 17424ns; attempting to update the registry I0211 06:15:01.323465 27156 registrar.cpp:506] Successfully updated the registry in 431872ns I0211 06:15:01.323737 27156 master.cpp:5494] Registered agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 at slave(69)@172.17.0.3:55722 (b1881548deae) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0211 06:15:01.323904 27156 hierarchical.cpp:516] Added agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 (b1881548deae) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0211 06:15:01.323994 27156 hierarchical.cpp:1810] No allocations performed I0211 06:15:01.324019 27156 hierarchical.cpp:1394] Performed allocation for 1 agents in 51512ns I0211 06:15:01.324056 27156 slave.cpp:1134] Registered with master [email protected]:55722; given agent ID 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 I0211 06:15:01.324074 27156 fetcher.cpp:90] Clearing fetcher cache I0211 06:15:01.324453 27156 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/HttpFaultToleranceTest_SchedulerExit_ul2dLb/meta/slaves/5ce885bd-f63f-4a31-a804-20dadfa8066e-S0/slave.info' I0211 06:15:01.324777 27156 slave.cpp:1200] Forwarding total oversubscribed resources {} I0211 06:15:01.324826 27156 slave.cpp:4343] Received ping from slave-observer(67)@172.17.0.3:55722 I0211 06:15:01.324870 27156 status_update_manager.cpp:184] Resuming sending status updates I0211 06:15:01.324905 27156 master.cpp:6051] Received update of agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 at slave(69)@172.17.0.3:55722 (b1881548deae) with total oversubscribed resources {} I0211 06:15:01.325008 27156 hierarchical.cpp:589] Agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 (b1881548deae) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) I0211 06:15:01.325083 27156 hierarchical.cpp:1810] No allocations performed I0211 06:15:01.325104 27156 hierarchical.cpp:1394] Performed allocation for 1 agents in 41933ns I0211 06:15:01.325693 27159 scheduler.cpp:361] Connected with the master at http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.326335 27165 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.327306 27169 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0211 06:15:01.327991 27169 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48694 I0211 06:15:01.328352 27169 master.cpp:2463] Received subscription request for HTTP framework 'default' I0211 06:15:01.328634 27169 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*' I0211 06:15:01.329072 27158 master.cpp:2577] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0211 06:15:01.329308 27164 hierarchical.cpp:286] Added framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.329658 27164 hierarchical.cpp:1900] No inverse offers to send out! I0211 06:15:01.329684 27164 hierarchical.cpp:1394] Performed allocation for 1 agents in 324949ns I0211 06:15:01.330297 27158 master.cpp:7034] Sending 1 offers to framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 (default) I0211 06:15:01.330569 27158 master.hpp:2134] Sending heartbeat to 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.337498 27168 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.337891 27168 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.338320 27168 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.339040 27166 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.3:55722/master/api/v1/scheduler I0211 06:15:01.340037 27155 process.cpp:3704] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0211 06:15:01.340734 27155 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:48693 I0211 06:15:01.341325 27155 master.cpp:3812] Processing ACCEPT call for offers: [ 5ce885bd-f63f-4a31-a804-20dadfa8066e-O0 ] on agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 at slave(69)@172.17.0.3:55722 (b1881548deae) for framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 (default) I0211 06:15:01.341635 27155 master.cpp:3388] Authorizing framework principal 'test-principal' to launch task d5462ed4-b66e-4cfb-b589-2d4d48f3264c W0211 06:15:01.342916 27155 validation.cpp:1064] Executor 'default' for task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0211 06:15:01.343665 27155 validation.cpp:1076] Executor 'default' for task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0211 06:15:01.344111 27155 master.cpp:9095] Adding task d5462ed4-b66e-4cfb-b589-2d4d48f3264c with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 at slave(69)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.344501 27155 master.cpp:4462] Launching task d5462ed4-b66e-4cfb-b589-2d4d48f3264c of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-S0 at slave(69)@172.17.0.3:55722 (b1881548deae) I0211 06:15:01.345093 27155 slave.cpp:1625] Got assigned task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' for framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.345830 27156 hierarchical.cpp:807] Updated allocation of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 on agent 5ce885bd-f63f-4a31-a804-20dadfa8066e-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] I0211 06:15:01.346696 27155 slave.cpp:1785] Launching task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' for framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.347663 27155 paths.cpp:547] Trying to chown '/tmp/HttpFaultToleranceTest_SchedulerExit_ul2dLb/slaves/5ce885bd-f63f-4a31-a804-20dadfa8066e-S0/frameworks/5ce885bd-f63f-4a31-a804-20dadfa8066e-0000/executors/default/runs/716e207a-4d0d-47b2-9c7f-3029b3882d65' to user 'mesos' I0211 06:15:01.348150 27155 slave.cpp:6477] Launching executor 'default' of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 with resources {} in work directory '/tmp/HttpFaultToleranceTest_SchedulerExit_ul2dLb/slaves/5ce885bd-f63f-4a31-a804-20dadfa8066e-S0/frameworks/5ce885bd-f63f-4a31-a804-20dadfa8066e-0000/executors/default/runs/716e207a-4d0d-47b2-9c7f-3029b3882d65' I0211 06:15:01.348871 27155 slave.cpp:2115] Queued task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' for executor 'default' of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.350337 27155 slave.cpp:884] Successfully attached file '/tmp/HttpFaultToleranceTest_SchedulerExit_ul2dLb/slaves/5ce885bd-f63f-4a31-a804-20dadfa8066e-S0/frameworks/5ce885bd-f63f-4a31-a804-20dadfa8066e-0000/executors/default/runs/716e207a-4d0d-47b2-9c7f-3029b3882d65' I0211 06:15:01.350118 27156 executor.cpp:192] Version: 1.3.0 I0211 06:15:01.351771 27159 executor.cpp:401] Connected with the agent I0211 06:15:01.352283 27159 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.3:55722/slave(69)/api/v1/executor I0211 06:15:01.353271 27159 process.cpp:3704] Handling HTTP event for process 'slave(69)' with path: '/slave(69)/api/v1/executor' I0211 06:15:01.353891 27159 http.cpp:307] HTTP POST for /slave(69)/api/v1/executor from 172.17.0.3:48695 I0211 06:15:01.354321 27159 slave.cpp:3166] Received Subscribe request for HTTP executor 'default' of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 I0211 06:15:01.355784 27159 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.3:55722/slave(69)/api/v1/executor *** Aborted at 1486793701 (unix time) try "date -d @1486793701" if you are using GNU date *** PC: @ 0x1ba3aca testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() *** SIGSEGV (@0x30) received by PID 27153 (TID 0x2ab410311700) from PID 48; stack trace: *** @ 0x2ab406f6b330 (unknown) @ 0x1ba3aca testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith() I0211 06:15:01.362855 27156 slave.cpp:2328] Sending queued task 'd5462ed4-b66e-4cfb-b589-2d4d48f3264c' to executor 'default' of framework 5ce885bd-f63f-4a31-a804-20dadfa8066e-0000 (via HTTP) I0211 06:15:01.364624 27156 executor.cpp:716] Enqueuing event LAUNCH received from http://172.17.0.3:55722/slave(69)/api/v1/executor @ 0xd8dd3f mesos::internal::tests::executor::MockHTTPExecutor<>::event() @ 0xdb1687 mesos::internal::tests::executor::TestMesos<>::events() @ 0xd9d80d std::_Function_handler<>::_M_invoke() @ 0x2ab404821fe5 process::AsyncExecutorProcess::execute<>() @ 0x2ab40482ba01 _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_ @ 0x2ab40578e511 process::ProcessManager::resume() @ 0x2ab405797347 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2ab40722aa60 (unknown) @ 0x2ab406f63184 start_thread @ 0x2ab407a9337d (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-1486790314-5657 Untagged: mesos-1486790314-5657:latest Deleted: sha256:32d03f05fd805e444126007603a9095c692959c5975d67390c8ed9b73e43fe8f Deleted: sha256:e299b0ec38db668b23b92f0a3416dad1076a1ac8b90d40d39ee170eff27162fc Deleted: sha256:eb81feaeb3ab5770fffa8d6a54dbecaa0b7bff51581157a71aef3d9967bed10c Deleted: sha256:9151c83f1c011a9f83cf965a3abbba2582c20c2121431ac0b9bd620997da7dc4 Deleted: sha256:a26bc827c84bbaefb808f8c3df35aea4509f7cd8b0088623030db385b9d3195c Deleted: sha256:566b46bcdaafc9319fcbb3cd1e9814bc48abd1865e4addc612446cb83df6d12f Deleted: sha256:989801656e2efbd54ea76ba4b061fa8d5e4f56e59956d79edad21bc95336eec1 Deleted: sha256:bbd723a49893a24d0a81a1d8d8001c884a0a6f6c89d836ca4c0b62c5e7e1cd14 Deleted: sha256:34fedfff1c7e7bbe512726f8c4f4e0a82e12d7f6c767e2a81cbfe00453ac9a4d Deleted: sha256:45086fce384502d06ca6924a58dfb79a5bfa626361eeeeae01dbce66f4323104 Deleted: sha256:2ac66c95aeed6a13c9be891fb5ef44843d131f3099329489db961f606f04c182 Deleted: sha256:fa7e24b15714a997079d935061f9edef0b377c0d8fb3558b69b6a6fc9ed1ff20 Deleted: sha256:aea81ab06b81502f272bb408b4a1a4970b830dae4b0c12c41bc890b0fd340257 Deleted: sha256:68c48e8f62d7908cdbb6c1b48b79474b6f92b49cc1e52afe8af85f5d0fd8fce5 Deleted: sha256:c705762dfaa2f22a8e33ab705d6bad08e181cabf1dbfbc48aed971072b6e9186 Deleted: sha256:7a29c8479192b1296a4beab3128c6c2ec4e73e05df8e95c19a7caaeefee95a3f Deleted: sha256:048efac9f241a7ae7fda5ca1d2f8f2de302dd7d0cf3155baa89a277c8c2f0eb3 Deleted: sha256:bbd3a77acf0a27bb95234ef787785d7a22600c23b515d311a2d1dedd4c93671f Deleted: sha256:0d536309ba02094678d50602f89da25125de0ed9a2dc6f667c068a1be95a1134 Deleted: sha256:98b29c48fbb75c3b001d012922c570086d6c7a1f9e31760dd80158fbddf14100 Deleted: sha256:efbbd005413209ad4d76b4b267958f304dd00f1de1e5e84c27cc17d2bfe5f51f Deleted: sha256:a84e95a45e38c9a415e5599c5291ba481a202e7cc470abc37de278b16aef79a5 Deleted: sha256:9e45e51811dfba94c7109d3eb7dc41618c78a9ebae4f7c0eefe8304f86be6114 Deleted: sha256:117f75f9a67b0ae341e5ed17b20460e0d61314f941213385a121165c422ed852 Build step 'Execute shell' marked build as failure
