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=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3428/display/redirect?page=changes>
Changes: [bmahler] Fixed the missing disk utilization in the webui. ------------------------------------------ [...truncated 12.30 MB...] I0328 22:09:55.834846 26694 master.cpp:6243] Status update TASK_RUNNING (UUID: 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3) for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 from agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.834890 26690 exec.cpp:374] Executor received status update acknowledgement 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3 for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.834898 26694 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3) for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.835011 26694 master.cpp:8345] Updating the state of task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0328 22:09:55.835147 26695 sched.cpp:1041] Scheduler::statusUpdate took 55754ns I0328 22:09:55.835270 26690 master.cpp:5151] Processing ACKNOWLEDGE call 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3 for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (default) at [email protected]:49582 on agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 I0328 22:09:55.835500 26695 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3) for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.835610 26697 exec.cpp:396] Executor received framework message I0328 22:09:55.835644 26701 slave.cpp:3462] Status update manager successfully handled status update acknowledgement (UUID: 70e8ccab-b3e6-4a14-9e0c-5aa671a58aa3) for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.835712 26697 exec.cpp:405] Executor::frameworkMessage took 33402ns I0328 22:09:55.836040 26704 slave.cpp:4681] Sending message for framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 to [email protected]:49582 I0328 22:09:55.836136 26696 sched.cpp:1177] Scheduler::frameworkMessage took 18796ns I0328 22:09:55.836429 26688 sched.cpp:2021] Asked to stop the driver I0328 22:09:55.836488 26695 sched.cpp:1203] Stopping framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.836616 26704 master.cpp:7765] Processing TEARDOWN call for framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (default) at [email protected]:49582 I0328 22:09:55.836648 26704 master.cpp:7777] Removing framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (default) at [email protected]:49582 I0328 22:09:55.836714 26704 master.cpp:3148] Deactivating framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (default) at [email protected]:49582 I0328 22:09:55.836792 26692 hierarchical.cpp:376] Deactivated framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.836822 26704 master.cpp:8345] Updating the state of task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0328 22:09:55.836848 26698 slave.cpp:3015] Asked to shut down framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 by [email protected]:49582 I0328 22:09:55.836899 26698 slave.cpp:3040] Shutting down framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.836918 26698 slave.cpp:5433] Shutting down executor 'default' of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 at executor(120)@172.17.0.2:49582 I0328 22:09:55.837077 26698 exec.cpp:415] Executor asked to shutdown I0328 22:09:55.837041 26704 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 on agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.837182 26698 exec.cpp:430] Executor::shutdown took 36299ns I0328 22:09:55.837232 26704 master.cpp:8468] Removing executor 'default' with resources {} of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 on agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.837236 26689 hierarchical.cpp:1116] 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 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 from framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.837290 26701 slave.cpp:4745] Got exited event for executor(120)@172.17.0.2:49582 I0328 22:09:55.837594 26696 hierarchical.cpp:323] Removed framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.837899 26702 slave.cpp:5119] Executor 'default' of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 exited with status 0 I0328 22:09:55.837954 26702 slave.cpp:5219] Cleaning up executor 'default' of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 at executor(120)@172.17.0.2:49582 W0328 22:09:55.838012 26699 master.cpp:6367] Ignoring unknown exited executor 'default' of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 on agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.838191 26691 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_cowV0d/slaves/8e3cf728-e9c5-40c5-b989-32acdc358358-S0/frameworks/8e3cf728-e9c5-40c5-b989-32acdc358358-0000/executors/default/runs/c5a07543-1501-447d-8485-35e5ebf852e1' for gc 6.99999029954963days in the future I0328 22:09:55.838276 26702 slave.cpp:5307] Cleaning up framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.838421 26691 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_cowV0d/slaves/8e3cf728-e9c5-40c5-b989-32acdc358358-S0/frameworks/8e3cf728-e9c5-40c5-b989-32acdc358358-0000/executors/default' for gc 6.99999029814815days in the future I0328 22:09:55.838438 26690 status_update_manager.cpp:285] Closing status update streams for framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.838467 26690 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 8e3cf728-e9c5-40c5-b989-32acdc358358-0000 I0328 22:09:55.838557 26694 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_cowV0d/slaves/8e3cf728-e9c5-40c5-b989-32acdc358358-S0/frameworks/8e3cf728-e9c5-40c5-b989-32acdc358358-0000' for gc 6.99999029524444days in the future I0328 22:09:55.838644 26689 slave.cpp:748] Agent terminating I0328 22:09:55.838722 26689 master.cpp:1317] Agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) disconnected I0328 22:09:55.838742 26689 master.cpp:3185] Disconnecting agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.838765 26689 master.cpp:3204] Deactivating agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 at slave(292)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.838873 26696 hierarchical.cpp:655] Agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 deactivated I0328 22:09:55.839910 26688 master.cpp:1156] Master terminating I0328 22:09:55.840083 26692 hierarchical.cpp:560] Removed agent 8e3cf728-e9c5-40c5-b989-32acdc358358-S0 [ OK ] MasterTest.FrameworkMessage (28 ms) [ RUN ] MasterTest.MultipleExecutors I0328 22:09:55.844799 26688 cluster.cpp:160] Creating default 'local' authorizer I0328 22:09:55.846086 26693 master.cpp:437] Master 5f75d2f9-875a-49f0-9a0b-fe4f537007ff (355e414abf87) started on 172.17.0.2:49582 I0328 22:09:55.846180 26693 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/ujwDSg/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/ujwDSg/master" --zk_session_timeout="10secs" I0328 22:09:55.846422 26693 master.cpp:489] Master only allowing authenticated frameworks to register I0328 22:09:55.846433 26693 master.cpp:503] Master only allowing authenticated agents to register I0328 22:09:55.846441 26693 master.cpp:516] Master only allowing authenticated HTTP frameworks to register I0328 22:09:55.846449 26693 credentials.hpp:37] Loading credentials for authentication from '/tmp/ujwDSg/credentials' I0328 22:09:55.846611 26693 master.cpp:561] Using default 'crammd5' authenticator I0328 22:09:55.846658 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0328 22:09:55.846755 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0328 22:09:55.846830 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0328 22:09:55.846985 26693 master.cpp:641] Authorization enabled I0328 22:09:55.847077 26695 hierarchical.cpp:159] Initialized hierarchical allocator process I0328 22:09:55.847136 26703 whitelist_watcher.cpp:77] No whitelist given I0328 22:09:55.847913 26698 master.cpp:2165] Elected as the leading master! I0328 22:09:55.847934 26698 master.cpp:1704] Recovering from registrar I0328 22:09:55.848116 26694 registrar.cpp:331] Recovering registrar I0328 22:09:55.848389 26689 registrar.cpp:364] Successfully fetched the registry (0B) in 235008ns I0328 22:09:55.848428 26689 registrar.cpp:463] Applied 1 operations in 4164ns; attempting to update the registry I0328 22:09:55.848687 26698 registrar.cpp:508] Successfully updated the registry in 238080ns I0328 22:09:55.848870 26698 registrar.cpp:394] Successfully recovered registrar I0328 22:09:55.849092 26691 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0328 22:09:55.849123 26703 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0328 22:09:55.851500 26688 cluster.cpp:446] Creating default 'local' authorizer I0328 22:09:55.852218 26693 slave.cpp:226] Mesos agent started on (293)@172.17.0.2:49582 I0328 22:09:55.852239 26693 slave.cpp:227] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_MultipleExecutors_8JOrqi/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_secret_key="secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_8JOrqi/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_8JOrqi/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/MasterTest_MultipleExecutors_8JOrqi" --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/MasterTest_MultipleExecutors_iWdxEM" I0328 22:09:55.852604 26693 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_8JOrqi/credential' I0328 22:09:55.852728 26693 slave.cpp:259] Agent using credential for: test-principal I0328 22:09:55.852747 26693 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_8JOrqi/http_credentials' I0328 22:09:55.852895 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0328 22:09:55.852952 26693 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0328 22:09:55.853108 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0328 22:09:55.853209 26693 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0328 22:09:55.853267 26688 sched.cpp:232] Version: 1.3.0 I0328 22:09:55.853379 26693 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0328 22:09:55.853430 26693 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0328 22:09:55.853508 26699 sched.cpp:336] New master detected at [email protected]:49582 I0328 22:09:55.853565 26699 sched.cpp:407] Authenticating with master [email protected]:49582 I0328 22:09:55.853610 26699 sched.cpp:414] Using default CRAM-MD5 authenticatee I0328 22:09:55.853724 26691 authenticatee.cpp:121] Creating new client SASL connection I0328 22:09:55.853979 26696 master.cpp:7252] Authenticating [email protected]:49582 I0328 22:09:55.854044 26698 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(639)@172.17.0.2:49582 I0328 22:09:55.854111 26693 slave.cpp:486] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0328 22:09:55.854153 26689 authenticator.cpp:98] Creating new server SASL connection I0328 22:09:55.854171 26693 slave.cpp:494] Agent attributes: [ ] I0328 22:09:55.854182 26693 slave.cpp:499] Agent hostname: 355e414abf87 I0328 22:09:55.854264 26691 status_update_manager.cpp:177] Pausing sending status updates I0328 22:09:55.854372 26701 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0328 22:09:55.854415 26701 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0328 22:09:55.854501 26701 authenticator.cpp:204] Received SASL authentication start I0328 22:09:55.854555 26701 authenticator.cpp:326] Authentication requires more steps I0328 22:09:55.854605 26693 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_iWdxEM/meta' I0328 22:09:55.854626 26701 authenticatee.cpp:259] Received SASL authentication step I0328 22:09:55.854781 26704 status_update_manager.cpp:203] Recovering status update manager I0328 22:09:55.854794 26695 authenticator.cpp:232] Received SASL authentication step I0328 22:09:55.854820 26695 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '355e414abf87' server FQDN: '355e414abf87' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0328 22:09:55.854833 26695 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0328 22:09:55.854849 26695 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0328 22:09:55.854866 26695 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '355e414abf87' server FQDN: '355e414abf87' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0328 22:09:55.854876 26695 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0328 22:09:55.854887 26695 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0328 22:09:55.854924 26695 authenticator.cpp:318] Authentication success I0328 22:09:55.854933 26693 slave.cpp:5921] Finished recovery I0328 22:09:55.854990 26696 authenticatee.cpp:299] Authentication success I0328 22:09:55.855010 26704 master.cpp:7282] Successfully authenticated principal 'test-principal' at [email protected]:49582 I0328 22:09:55.855027 26697 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(639)@172.17.0.2:49582 I0328 22:09:55.855343 26704 sched.cpp:513] Successfully authenticated with master [email protected]:49582 I0328 22:09:55.855350 26693 slave.cpp:6103] Querying resource estimator for oversubscribable resources I0328 22:09:55.855365 26704 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:49582 I0328 22:09:55.855430 26704 sched.cpp:869] Will retry registration in 1.877610851secs if necessary I0328 22:09:55.855525 26694 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at [email protected]:49582 I0328 22:09:55.855561 26694 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0328 22:09:55.855564 26704 status_update_manager.cpp:177] Pausing sending status updates I0328 22:09:55.855562 26693 slave.cpp:876] New master detected at [email protected]:49582 I0328 22:09:55.855602 26693 slave.cpp:911] Detecting new master I0328 22:09:55.855689 26693 slave.cpp:6117] Received oversubscribable resources {} from the resource estimator I0328 22:09:55.855748 26702 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ] I0328 22:09:55.855933 26701 hierarchical.cpp:271] Added framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.855962 26700 sched.cpp:759] Framework registered with 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.855996 26700 sched.cpp:773] Scheduler::registered took 12887ns I0328 22:09:55.856006 26701 hierarchical.cpp:1862] No allocations performed I0328 22:09:55.856025 26701 hierarchical.cpp:1952] No inverse offers to send out! I0328 22:09:55.856037 26701 hierarchical.cpp:1446] Performed allocation for 0 agents in 44459ns I0328 22:09:55.862751 26698 slave.cpp:938] Authenticating with master [email protected]:49582 I0328 22:09:55.862782 26698 slave.cpp:949] Using default CRAM-MD5 authenticatee I0328 22:09:55.862864 26704 authenticatee.cpp:121] Creating new client SASL connection I0328 22:09:55.863047 26704 master.cpp:7252] Authenticating slave(293)@172.17.0.2:49582 I0328 22:09:55.863124 26702 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(640)@172.17.0.2:49582 I0328 22:09:55.863219 26703 authenticator.cpp:98] Creating new server SASL connection I0328 22:09:55.863386 26693 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0328 22:09:55.863410 26693 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0328 22:09:55.863477 26693 authenticator.cpp:204] Received SASL authentication start I0328 22:09:55.863569 26693 authenticator.cpp:326] Authentication requires more steps I0328 22:09:55.863631 26693 authenticatee.cpp:259] Received SASL authentication step I0328 22:09:55.863710 26693 authenticator.cpp:232] Received SASL authentication step I0328 22:09:55.863741 26693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '355e414abf87' server FQDN: '355e414abf87' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0328 22:09:55.863761 26693 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0328 22:09:55.863783 26693 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0328 22:09:55.863809 26693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '355e414abf87' server FQDN: '355e414abf87' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0328 22:09:55.863829 26693 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0328 22:09:55.863844 26693 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0328 22:09:55.863869 26693 authenticator.cpp:318] Authentication success I0328 22:09:55.863931 26697 authenticatee.cpp:299] Authentication success I0328 22:09:55.863970 26698 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(293)@172.17.0.2:49582 I0328 22:09:55.864001 26693 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(640)@172.17.0.2:49582 I0328 22:09:55.864284 26694 slave.cpp:1033] Successfully authenticated with master [email protected]:49582 I0328 22:09:55.864352 26694 slave.cpp:1461] Will retry registration in 14.529386ms if necessary I0328 22:09:55.864431 26696 master.cpp:5437] Registering agent at slave(293)@172.17.0.2:49582 (355e414abf87) with id 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 I0328 22:09:55.864630 26693 registrar.cpp:463] Applied 1 operations in 15392ns; attempting to update the registry I0328 22:09:55.865108 26697 registrar.cpp:508] Successfully updated the registry in 449024ns I0328 22:09:55.865422 26703 master.cpp:5511] Registered agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0328 22:09:55.865463 26699 slave.cpp:4703] Received ping from slave-observer(287)@172.17.0.2:49582 I0328 22:09:55.865506 26704 hierarchical.cpp:527] Added agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 (355e414abf87) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0328 22:09:55.865577 26699 slave.cpp:1079] Registered with master [email protected]:49582; given agent ID 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 I0328 22:09:55.865597 26699 fetcher.cpp:94] Clearing fetcher cache I0328 22:09:55.865798 26690 status_update_manager.cpp:184] Resuming sending status updates I0328 22:09:55.865895 26704 hierarchical.cpp:1952] No inverse offers to send out! I0328 22:09:55.865921 26704 hierarchical.cpp:1446] Performed allocation for 1 agents in 339722ns I0328 22:09:55.866013 26699 slave.cpp:1107] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_iWdxEM/meta/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/slave.info' I0328 22:09:55.866178 26692 master.cpp:7081] Sending 1 offers to framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 (default) at [email protected]:49582 I0328 22:09:55.866263 26699 slave.cpp:1145] Forwarding total oversubscribed resources {} I0328 22:09:55.866370 26690 master.cpp:6098] Received update of agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) with total oversubscribed resources {} I0328 22:09:55.866420 26701 sched.cpp:933] Scheduler::resourceOffers took 48846ns I0328 22:09:55.867079 26694 master.cpp:3839] Processing ACCEPT call for offers: [ 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-O0 ] on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 (default) at [email protected]:49582 I0328 22:09:55.867123 26694 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1 I0328 22:09:55.867221 26694 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2 W0328 22:09:55.867820 26693 validation.cpp:1113] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0328 22:09:55.867844 26693 validation.cpp:1125] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0328 22:09:55.867928 26693 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.867985 26693 master.cpp:4489] Launching task 1 of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 (default) at [email protected]:49582 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.868149 26700 slave.cpp:1571] Got assigned task '1' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 W0328 22:09:55.868330 26693 validation.cpp:1113] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0328 22:09:55.868355 26693 validation.cpp:1125] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0328 22:09:55.868453 26693 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.868480 26700 slave.cpp:1852] Authorizing task '1' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.868501 26700 slave.cpp:6540] Authorizing framework principal 'test-principal' to launch task 1 I0328 22:09:55.868525 26693 master.cpp:4489] Launching task 2 of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 (default) at [email protected]:49582 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 at slave(293)@172.17.0.2:49582 (355e414abf87) I0328 22:09:55.868837 26700 slave.cpp:1571] Got assigned task '2' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.869012 26700 slave.cpp:2039] Launching task '1' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.869117 26704 hierarchical.cpp:852] Updated allocation of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-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] I0328 22:09:55.869267 26704 hierarchical.cpp:1116] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 from framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.869297 26704 hierarchical.cpp:1153] Framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 filtered agent 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0 for 5secs I0328 22:09:55.869469 26700 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-1/runs/7ea0b445-3fb6-46f6-99d9-d89e917be891' to user 'mesos' I0328 22:09:55.869644 26700 slave.cpp:6884] Launching executor 'executor-1' of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-1/runs/7ea0b445-3fb6-46f6-99d9-d89e917be891' I0328 22:09:55.869895 26700 slave.cpp:2268] Queued task '1' for executor 'executor-1' of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.869936 26700 slave.cpp:1852] Authorizing task '2' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.869951 26700 slave.cpp:6540] Authorizing framework principal 'test-principal' to launch task 2 I0328 22:09:55.870088 26700 slave.cpp:829] Successfully attached file '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-1/runs/7ea0b445-3fb6-46f6-99d9-d89e917be891' I0328 22:09:55.870328 26700 slave.cpp:2039] Launching task '2' for framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.870710 26700 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-2/runs/c556bc4e-62b0-45b4-9ebd-939acb075c70' to user 'mesos' I0328 22:09:55.870898 26700 slave.cpp:6884] Launching executor 'executor-2' of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-2/runs/c556bc4e-62b0-45b4-9ebd-939acb075c70' I0328 22:09:55.871129 26700 slave.cpp:2268] Queued task '2' for executor 'executor-2' of framework 5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000 I0328 22:09:55.871168 26700 slave.cpp:829] Successfully attached file '/tmp/MasterTest_MultipleExecutors_iWdxEM/slaves/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-S0/frameworks/5f75d2f9-875a-49f0-9a0b-fe4f537007ff-0000/executors/executor-2/runs/c556bc4e-62b0-45b4-9ebd-939acb075c70' *** Aborted at 1490738995 (unix time) try "date -d @1490738995" if you are using GNU date *** I0328 22:09:55.871429 26698 exec.cpp:162] Version: 1.3.0 I0328 22:09:55.871542 26695 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:49582 with pid 26688 PC: @ 0x7ff9bc1db0cd __GI_getenv *** SIGSEGV (@0x41) received by PID 26688 (TID 0x7ff9b12ba700) from PID 65; stack trace: *** @ 0x7ff9bcd94370 (unknown) @ 0x7ff9bc1db0cd __GI_getenv @ 0x7ff9c0e51194 mesos::internal::slave::executorEnvironment() @ 0x7ff9c0e76d37 mesos::internal::slave::Slave::launchExecutor() @ 0x7ff9c0081271 process::ProcessManager::resume() @ 0x7ff9c008a2f7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x7ff9bcb31230 (unknown) @ 0x7ff9bcd8cdc5 start_thread @ 0x7ff9bc29a73d __clone 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-1490736130-4341 Untagged: mesos-1490736130-4341:latest Deleted: sha256:7dd695860dffded3d36c3929863daf431f09b9b8ef42fc08a83db9bf25f1fe01 Deleted: sha256:a2052e2bb5b8676a7c0b1132a51f079b7ab36883e5844d30ff3e9d8f9bf1e8e0 Deleted: sha256:08c7aa216723d6ec2b930cc437568bb5d654b17080c75a6ac8ed9e98c5cecd1a Deleted: sha256:1d28e8a79aa39a65334605cde4bfcb925aa1bb7c4ed78178862bb139dda8a18c Deleted: sha256:7b580071a871835fff129571c1a2efe120fe61297bce9a0bc77c4bc3ba839150 Deleted: sha256:798210f407e3a650e917e01d7e488dd50ba76a2e622c3761385d6bae05dd6c5a Deleted: sha256:425f90128b291f29d91f5b4bc0f5116ad64f6ff9510eecb5fa340fe3a8846f82 Deleted: sha256:bb1e384d8cb554a448b8982a480eae47a02964cfd41449f15096f5870e8c55de Deleted: sha256:b806a58c6d2dcd49d89cfc3607e4bf65d53a9db03fec2a508b068536e74fad77 Deleted: sha256:fd8807ff3f9864cc414480b274a846f8d06b89833d4529d99c83ebd2aecd2d83 Deleted: sha256:b7ad8669d6062ccea1226c49f3512372a158a8caa78a50aa82f66d31f00a2267 Deleted: sha256:b3f8729881fffe73457fbaaf2dbcccc6b6e6306377fa2885c53bd2c8f8c59fee Deleted: sha256:6b81f238b720c4cfaeb807fbe95f5a76d0d9b9431c317d49879f8359fc1d419f Deleted: sha256:5fec41413eda0439efc74e49367e407f23716fc0648d1e0bda0f9a5f2dca62a7 Deleted: sha256:5565f63c8ac1c6e0bc5ecd67ef5502fb14b0e1ca625452ce2cc3d217d5d05f81 Deleted: sha256:a3d62ca31fee1977f6d91a3d177d630ef65a31f8ecce4bca85d8aee803690953 Deleted: sha256:11285d8909d2e9ea485afbaa3b77fd8115d5940fdee1bafcde262667e0d4a768 Deleted: sha256:cfb6815c83305c847e3d4f94db81ae4834dbf132ed401adfd4d10d82ee292797 Deleted: sha256:d419f9cd19d3131fae52bc7fda6ca5101255d3d890e14e4427d11b7e71494a1f Deleted: sha256:7fcfeade7f5a6c26f89f2ab96b54a1daffe1419870c892aff563ab0ecac8cbb1 Deleted: sha256:9d33ade4476fbb108017add58cd8001d0b7cc94d01869398e3dddc7aca6df379 Deleted: sha256:95b750d53fbe7050dcb348cc31dcd03c94d52fd60f396477e987e7287c034894 Deleted: sha256:bb049e232807ada0655e3b237551eec74a1cbd4db848e1083c5b72ca8d0f4ff6 Deleted: sha256:1ac56535e074b561c2d065886f27a6aa24ca92dcb41ca91852f5e96c2f507b84 Deleted: sha256:66e192c173335a6789ed1945f3246250c702708a9fda904d533da346f7b21a86 Build step 'Execute shell' marked build as failure
