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)/3561/display/redirect?page=changes>
Changes: [yujie.jay] Fixed an ordering issue in 1.2.1 CHANGELOG. [yujie.jay] Added MESOS-5172 to 1.2.1 CHANGELOG. [yujie.jay] Fixed an ordering issue in 1.1.2 CHANGELOG. [yujie.jay] Added MESOS-5172 to 1.1.2 CHANGELOG. ------------------------------------------ [...truncated 12.96 MB...] I0426 04:09:13.627856 28499 master.cpp:6251] Status update TASK_RUNNING (UUID: 12b08137-a451-4750-be6b-9beca96d349a) for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 from agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.627890 28499 master.cpp:6319] Forwarding status update TASK_RUNNING (UUID: 12b08137-a451-4750-be6b-9beca96d349a) for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.627923 28491 exec.cpp:374] Executor received status update acknowledgement 12b08137-a451-4750-be6b-9beca96d349a for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.627951 28499 master.cpp:8350] Updating the state of task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0426 04:09:13.628031 28495 sched.cpp:1041] Scheduler::statusUpdate took 32055ns I0426 04:09:13.628134 28501 master.cpp:5165] Processing ACKNOWLEDGE call 12b08137-a451-4750-be6b-9beca96d349a for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (default) at [email protected]:46062 on agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 I0426 04:09:13.628262 28492 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 12b08137-a451-4750-be6b-9beca96d349a) for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.628360 28490 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: 12b08137-a451-4750-be6b-9beca96d349a) for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.628459 28506 exec.cpp:396] Executor received framework message I0426 04:09:13.628496 28506 exec.cpp:405] Executor::frameworkMessage took 24738ns I0426 04:09:13.628736 28496 slave.cpp:4723] Sending message for framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 to [email protected]:46062 I0426 04:09:13.628828 28486 sched.cpp:1177] Scheduler::frameworkMessage took 25867ns I0426 04:09:13.628998 28484 sched.cpp:2021] Asked to stop the driver I0426 04:09:13.629093 28503 sched.cpp:1203] Stopping framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.629168 28488 master.cpp:7770] Processing TEARDOWN call for framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (default) at [email protected]:46062 I0426 04:09:13.629187 28488 master.cpp:7782] Removing framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (default) at [email protected]:46062 I0426 04:09:13.629201 28488 master.cpp:3162] Deactivating framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (default) at [email protected]:46062 I0426 04:09:13.629259 28493 hierarchical.cpp:376] Deactivated framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.629266 28488 master.cpp:8350] Updating the state of task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0426 04:09:13.629323 28495 slave.cpp:3057] Asked to shut down framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 by [email protected]:46062 I0426 04:09:13.629345 28495 slave.cpp:3082] Shutting down framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.629362 28495 slave.cpp:5475] Shutting down executor 'default' of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 at executor(121)@172.17.0.3:46062 I0426 04:09:13.629477 28505 exec.cpp:415] Executor asked to shutdown I0426 04:09:13.629511 28505 exec.cpp:430] Executor::shutdown took 21272ns I0426 04:09:13.629539 28494 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 7534069a-a69e-4490-a94f-b76d33774b21-S0 from framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.629534 28488 master.cpp:8444] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 on agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.629691 28488 master.cpp:8473] Removing executor 'default' with resources {} of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 on agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.629737 28500 slave.cpp:4787] Got exited event for executor(121)@172.17.0.3:46062 I0426 04:09:13.629989 28501 slave.cpp:5161] Executor 'default' of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 exited with status 0 I0426 04:09:13.630034 28501 slave.cpp:5261] Cleaning up executor 'default' of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 at executor(121)@172.17.0.3:46062 W0426 04:09:13.630064 28490 master.cpp:6374] Ignoring unknown exited executor 'default' of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 on agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.630226 28485 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_O2MfeL/slaves/7534069a-a69e-4490-a94f-b76d33774b21-S0/frameworks/7534069a-a69e-4490-a94f-b76d33774b21-0000/executors/default/runs/d633bfc1-9b0d-4b64-83c3-2621bb84f4fc' for gc 6.99999270678222days in the future I0426 04:09:13.630259 28508 hierarchical.cpp:323] Removed framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.630316 28501 slave.cpp:5349] Cleaning up framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.630353 28495 status_update_manager.cpp:285] Closing status update streams for framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.630378 28495 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 7534069a-a69e-4490-a94f-b76d33774b21-0000 I0426 04:09:13.630399 28501 slave.cpp:790] Agent terminating I0426 04:09:13.630441 28497 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_O2MfeL/slaves/7534069a-a69e-4490-a94f-b76d33774b21-S0/frameworks/7534069a-a69e-4490-a94f-b76d33774b21-0000/executors/default' for gc 6.99999270509333days in the future I0426 04:09:13.630502 28497 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_O2MfeL/slaves/7534069a-a69e-4490-a94f-b76d33774b21-S0/frameworks/7534069a-a69e-4490-a94f-b76d33774b21-0000' for gc 6.99999270394667days in the future I0426 04:09:13.630600 28490 master.cpp:1315] Agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) disconnected I0426 04:09:13.630615 28490 master.cpp:3199] Disconnecting agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.630674 28490 master.cpp:3218] Deactivating agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 at slave(304)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.630911 28500 hierarchical.cpp:655] Agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 deactivated I0426 04:09:13.631657 28484 master.cpp:1157] Master terminating I0426 04:09:13.631780 28498 hierarchical.cpp:560] Removed agent 7534069a-a69e-4490-a94f-b76d33774b21-S0 [ OK ] MasterTest.FrameworkMessage (28 ms) [ RUN ] MasterTest.MultipleExecutors I0426 04:09:13.634472 28484 cluster.cpp:162] Creating default 'local' authorizer I0426 04:09:13.635166 28496 master.cpp:438] Master cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd (47899f28c2eb) started on 172.17.0.3:46062 I0426 04:09:13.635177 28496 master.cpp:440] 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/giyIpJ/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" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/giyIpJ/master" --zk_session_timeout="10secs" I0426 04:09:13.635293 28496 master.cpp:490] Master only allowing authenticated frameworks to register I0426 04:09:13.635304 28496 master.cpp:504] Master only allowing authenticated agents to register I0426 04:09:13.635313 28496 master.cpp:517] Master only allowing authenticated HTTP frameworks to register I0426 04:09:13.635323 28496 credentials.hpp:37] Loading credentials for authentication from '/tmp/giyIpJ/credentials' I0426 04:09:13.635432 28496 master.cpp:562] Using default 'crammd5' authenticator I0426 04:09:13.635478 28496 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0426 04:09:13.635537 28496 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0426 04:09:13.635591 28496 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0426 04:09:13.635687 28496 master.cpp:642] Authorization enabled I0426 04:09:13.635812 28508 hierarchical.cpp:159] Initialized hierarchical allocator process I0426 04:09:13.635813 28502 whitelist_watcher.cpp:77] No whitelist given I0426 04:09:13.636364 28501 master.cpp:2163] Elected as the leading master! I0426 04:09:13.636380 28501 master.cpp:1702] Recovering from registrar I0426 04:09:13.636441 28494 registrar.cpp:345] Recovering registrar I0426 04:09:13.636723 28502 registrar.cpp:389] Successfully fetched the registry (0B) in 265216ns I0426 04:09:13.636746 28502 registrar.cpp:493] Applied 1 operations in 2357ns; attempting to update the registry I0426 04:09:13.636912 28502 registrar.cpp:550] Successfully updated the registry in 152064ns I0426 04:09:13.636955 28502 registrar.cpp:422] Successfully recovered registrar I0426 04:09:13.637161 28506 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0426 04:09:13.637200 28498 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover I0426 04:09:13.638793 28484 cluster.cpp:448] Creating default 'local' authorizer I0426 04:09:13.639238 28485 slave.cpp:225] Mesos agent started on (305)@172.17.0.3:46062 I0426 04:09:13.639251 28485 slave.cpp:226] 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_2ny1Ad/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="/tmp/MasterTest_MultipleExecutors_2ny1Ad/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_2ny1Ad/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_2ny1Ad/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" --port="5051" --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_2ny1Ad" --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_6AGmvt" I0426 04:09:13.639402 28485 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_2ny1Ad/credential' I0426 04:09:13.639482 28485 slave.cpp:258] Agent using credential for: test-principal I0426 04:09:13.639497 28485 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_2ny1Ad/http_credentials' I0426 04:09:13.639607 28485 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' I0426 04:09:13.639647 28485 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' I0426 04:09:13.639708 28485 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0426 04:09:13.639736 28485 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' I0426 04:09:13.639755 28484 sched.cpp:232] Version: 1.3.0 I0426 04:09:13.639803 28485 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0426 04:09:13.639875 28485 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' I0426 04:09:13.639897 28501 sched.cpp:336] New master detected at [email protected]:46062 I0426 04:09:13.639930 28501 sched.cpp:407] Authenticating with master [email protected]:46062 I0426 04:09:13.639942 28501 sched.cpp:414] Using default CRAM-MD5 authenticatee I0426 04:09:13.640120 28507 authenticatee.cpp:121] Creating new client SASL connection I0426 04:09:13.640257 28487 master.cpp:7257] Authenticating [email protected]:46062 I0426 04:09:13.640316 28508 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(656)@172.17.0.3:46062 I0426 04:09:13.640378 28492 authenticator.cpp:98] Creating new server SASL connection I0426 04:09:13.640410 28485 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0426 04:09:13.640442 28485 slave.cpp:533] Agent attributes: [ ] I0426 04:09:13.640450 28485 slave.cpp:538] Agent hostname: 47899f28c2eb I0426 04:09:13.640508 28486 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0426 04:09:13.640513 28498 status_update_manager.cpp:177] Pausing sending status updates I0426 04:09:13.640525 28486 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0426 04:09:13.640579 28496 authenticator.cpp:204] Received SASL authentication start I0426 04:09:13.640622 28496 authenticator.cpp:326] Authentication requires more steps I0426 04:09:13.640688 28506 authenticatee.cpp:259] Received SASL authentication step I0426 04:09:13.640796 28508 authenticator.cpp:232] Received SASL authentication step I0426 04:09:13.640815 28508 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '47899f28c2eb' server FQDN: '47899f28c2eb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0426 04:09:13.640823 28508 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0426 04:09:13.640830 28508 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0426 04:09:13.640842 28508 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '47899f28c2eb' server FQDN: '47899f28c2eb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0426 04:09:13.640854 28508 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0426 04:09:13.640864 28508 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0426 04:09:13.640883 28508 authenticator.cpp:318] Authentication success I0426 04:09:13.640890 28499 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_6AGmvt/meta' I0426 04:09:13.640930 28507 authenticatee.cpp:299] Authentication success I0426 04:09:13.640947 28486 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(656)@172.17.0.3:46062 I0426 04:09:13.641001 28490 master.cpp:7287] Successfully authenticated principal 'test-principal' at [email protected]:46062 I0426 04:09:13.641100 28502 sched.cpp:513] Successfully authenticated with master [email protected]:46062 I0426 04:09:13.641113 28502 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:46062 I0426 04:09:13.641160 28505 status_update_manager.cpp:203] Recovering status update manager I0426 04:09:13.641216 28502 sched.cpp:869] Will retry registration in 125.485063ms if necessary I0426 04:09:13.641257 28500 master.cpp:2815] Received SUBSCRIBE call for framework 'default' at [email protected]:46062 I0426 04:09:13.641284 28500 master.cpp:2199] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0426 04:09:13.641306 28503 slave.cpp:5963] Finished recovery I0426 04:09:13.641474 28488 master.cpp:2892] Subscribing framework default with checkpointing disabled and capabilities [ ] I0426 04:09:13.641599 28485 hierarchical.cpp:271] Added framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.641602 28503 slave.cpp:6145] Querying resource estimator for oversubscribable resources I0426 04:09:13.641633 28492 sched.cpp:759] Framework registered with cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.641645 28485 hierarchical.cpp:1862] No allocations performed I0426 04:09:13.641661 28485 hierarchical.cpp:1952] No inverse offers to send out! I0426 04:09:13.641680 28485 hierarchical.cpp:1446] Performed allocation for 0 agents in 39815ns I0426 04:09:13.641661 28492 sched.cpp:773] Scheduler::registered took 11237ns I0426 04:09:13.641741 28486 slave.cpp:918] New master detected at [email protected]:46062 I0426 04:09:13.641748 28497 status_update_manager.cpp:177] Pausing sending status updates I0426 04:09:13.641795 28486 slave.cpp:953] Detecting new master I0426 04:09:13.641840 28486 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator I0426 04:09:13.648898 28488 slave.cpp:980] Authenticating with master [email protected]:46062 I0426 04:09:13.648918 28488 slave.cpp:991] Using default CRAM-MD5 authenticatee I0426 04:09:13.648967 28499 authenticatee.cpp:121] Creating new client SASL connection I0426 04:09:13.649129 28502 master.cpp:7257] Authenticating slave(305)@172.17.0.3:46062 I0426 04:09:13.649224 28498 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(657)@172.17.0.3:46062 I0426 04:09:13.649359 28495 authenticator.cpp:98] Creating new server SASL connection I0426 04:09:13.649461 28486 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0426 04:09:13.649474 28486 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0426 04:09:13.649514 28497 authenticator.cpp:204] Received SASL authentication start I0426 04:09:13.649550 28497 authenticator.cpp:326] Authentication requires more steps I0426 04:09:13.649590 28497 authenticatee.cpp:259] Received SASL authentication step I0426 04:09:13.649632 28503 authenticator.cpp:232] Received SASL authentication step I0426 04:09:13.649653 28503 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '47899f28c2eb' server FQDN: '47899f28c2eb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0426 04:09:13.649665 28503 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0426 04:09:13.649677 28503 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0426 04:09:13.649684 28503 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '47899f28c2eb' server FQDN: '47899f28c2eb' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0426 04:09:13.649691 28503 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0426 04:09:13.649694 28503 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0426 04:09:13.649705 28503 authenticator.cpp:318] Authentication success I0426 04:09:13.649744 28497 authenticatee.cpp:299] Authentication success I0426 04:09:13.649758 28495 master.cpp:7287] Successfully authenticated principal 'test-principal' at slave(305)@172.17.0.3:46062 I0426 04:09:13.649773 28503 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(657)@172.17.0.3:46062 I0426 04:09:13.649881 28488 slave.cpp:1075] Successfully authenticated with master [email protected]:46062 I0426 04:09:13.649938 28488 slave.cpp:1503] Will retry registration in 2.822659ms if necessary I0426 04:09:13.649989 28502 master.cpp:5447] Registering agent at slave(305)@172.17.0.3:46062 (47899f28c2eb) with id cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 I0426 04:09:13.650092 28501 registrar.cpp:493] Applied 1 operations in 13697ns; attempting to update the registry I0426 04:09:13.650303 28501 registrar.cpp:550] Successfully updated the registry in 193792ns I0426 04:09:13.650508 28506 slave.cpp:4745] Received ping from slave-observer(300)@172.17.0.3:46062 I0426 04:09:13.650571 28505 master.cpp:5521] Registered agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0426 04:09:13.650609 28493 slave.cpp:1121] Registered with master [email protected]:46062; given agent ID cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 I0426 04:09:13.650625 28493 fetcher.cpp:94] Clearing fetcher cache I0426 04:09:13.650651 28506 hierarchical.cpp:527] Added agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 (47899f28c2eb) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0426 04:09:13.650712 28501 status_update_manager.cpp:184] Resuming sending status updates I0426 04:09:13.650847 28493 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_6AGmvt/meta/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/slave.info' I0426 04:09:13.650880 28506 hierarchical.cpp:1952] No inverse offers to send out! I0426 04:09:13.650897 28506 hierarchical.cpp:1446] Performed allocation for 1 agents in 202175ns I0426 04:09:13.651013 28486 master.cpp:7087] Sending 1 offers to framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 (default) at [email protected]:46062 I0426 04:09:13.651041 28493 slave.cpp:1187] Forwarding total oversubscribed resources {} I0426 04:09:13.651095 28486 master.cpp:6106] Received update of agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) with total oversubscribed resources {} I0426 04:09:13.651180 28494 sched.cpp:933] Scheduler::resourceOffers took 45241ns I0426 04:09:13.651729 28499 master.cpp:3853] Processing ACCEPT call for offers: [ cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-O0 ] on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 (default) at [email protected]:46062 I0426 04:09:13.651762 28499 master.cpp:3429] Authorizing framework principal 'test-principal' to launch task 1 I0426 04:09:13.651814 28499 master.cpp:3429] Authorizing framework principal 'test-principal' to launch task 2 W0426 04:09:13.652230 28496 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. W0426 04:09:13.652250 28496 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. I0426 04:09:13.652328 28496 master.cpp:9102] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.652375 28496 master.cpp:4503] Launching task 1 of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 (default) at [email protected]:46062 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.652513 28489 slave.cpp:1613] Got assigned task '1' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 W0426 04:09:13.652653 28496 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. W0426 04:09:13.652671 28496 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. I0426 04:09:13.652714 28489 slave.cpp:1894] Authorizing task '1' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.652732 28489 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 1 I0426 04:09:13.652743 28496 master.cpp:9102] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.652817 28496 master.cpp:4503] Launching task 2 of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 (default) at [email protected]:46062 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 at slave(305)@172.17.0.3:46062 (47899f28c2eb) I0426 04:09:13.652957 28493 slave.cpp:1613] Got assigned task '2' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.653165 28493 slave.cpp:2081] Launching task '1' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.653620 28485 hierarchical.cpp:852] Updated allocation of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 on agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-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] I0426 04:09:13.653676 28493 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-1/runs/5e984fd8-1ded-4841-af64-ac7e03fe9ee9' to user 'mesos' I0426 04:09:13.653831 28485 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 cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 from framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.653859 28493 slave.cpp:6926] Launching executor 'executor-1' of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-1/runs/5e984fd8-1ded-4841-af64-ac7e03fe9ee9' I0426 04:09:13.653867 28485 hierarchical.cpp:1153] Framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 filtered agent cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0 for 5secs I0426 04:09:13.654031 28493 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.654068 28493 slave.cpp:1894] Authorizing task '2' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.654083 28493 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task 2 I0426 04:09:13.654212 28493 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-1/runs/5e984fd8-1ded-4841-af64-ac7e03fe9ee9' I0426 04:09:13.654392 28493 slave.cpp:2081] Launching task '2' for framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.654649 28493 paths.cpp:556] Trying to chown '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-2/runs/54b18294-a4b4-4202-93c3-67ae6ecff990' to user 'mesos' I0426 04:09:13.654764 28493 slave.cpp:6926] Launching executor 'executor-2' of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-2/runs/54b18294-a4b4-4202-93c3-67ae6ecff990' I0426 04:09:13.654917 28493 slave.cpp:2310] Queued task '2' for executor 'executor-2' of framework cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000 I0426 04:09:13.654953 28493 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_6AGmvt/slaves/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-S0/frameworks/cb4a2ca6-2ec2-4b95-b9e0-a3ec37e396dd-0000/executors/executor-2/runs/54b18294-a4b4-4202-93c3-67ae6ecff990' *** Aborted at 1493179753 (unix time) try "date -d @1493179753" if you are using GNU date *** I0426 04:09:13.655103 28491 exec.cpp:162] Version: 1.3.0 I0426 04:09:13.655156 28502 exec.cpp:212] Executor started at: executor(122)@172.17.0.3:46062 with pid 28484 PC: @ 0x2b3fe12b782d (unknown) *** SIGSEGV (@0x20) received by PID 28484 (TID 0x2b3fea468700) from PID 32; stack trace: *** @ 0x2b3fe084e330 (unknown) @ 0x2b3fe12b782d (unknown) @ 0x2b3fdd89a614 mesos::internal::slave::executorEnvironment() @ 0x2b3fdd8c02e8 mesos::internal::slave::Slave::launchExecutor() @ 0x2b3fdec17011 process::ProcessManager::resume() @ 0x2b3fdec1fe97 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2b3fe0b0da60 (unknown) @ 0x2b3fe0846184 start_thread @ 0x2b3fe1379bed (unknown) make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped) make[3]: Leaving directory `/mesos/build' make[2]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1493177137-13326 Untagged: mesos-1493177137-13326:latest Deleted: sha256:d867f915f0ff91031c1885b8c92c78171b6af548a9b2e1adaa880d4ee1b655ce Deleted: sha256:6311d84a88391797e6baf475bcab1e8abc42bbe508c94df248f5e6d6bb7f67ad Deleted: sha256:334756e5376412e2604840dd86352e6ede1d8dbaf1994fc5d2c4fec588c30081 Deleted: sha256:e833b745c5b946bc886a1740292b85e262578985d99724e1906e28f4e95f3355 Deleted: sha256:46b258d21c58c3fb2145f88da7b50f2e53b733e61426c0dbf0881cac61decf4f Deleted: sha256:88ee24cacde93d5f2cab49e1891073b0a6f042b223153f08d7a04e94401b636f Deleted: sha256:5f5226cc4c350160fb046918efc3051e6199c5495e2f56869539ccb604428e0b Deleted: sha256:a53bc6a9fa7fa98eb03cd345bf75f7c553571e4a0f0cfdfc09611c5bdb86c447 Deleted: sha256:1ee8c279dddcd684b45ec6d09b12b8d14643227002c5396b71883316c45b813f Deleted: sha256:6a16bd0716ed2e4a59361350b541d81970ca42c064a5baac17b70ecaaf27757e Deleted: sha256:d1839e5bce0c9b32b9548e50e06878c41501ef8d05d7142d2f7c643678621261 Deleted: sha256:e42adaef10fccc9b94d6f1c1216c2b0f87774bd20820f1a493e45cbaef7d9156 Deleted: sha256:8a80dd3cbfda0c788a257033251c8cc2fc16bff17405686b667e170e71ab474d Deleted: sha256:6fc595924f3c0be9200cdb3743a10152a2c684ecc49c6a5bf958a56098dc3bd9 Deleted: sha256:cdda45f7abed204c7b62702cf4d4d8e491aa4456a3541aee0a24941019c1fa3b Deleted: sha256:b293ca8166dcde50c52c044cd8b4b03448f1b6162fc0d0db27f73c4486523e81 Deleted: sha256:391c445d25d6a4105307cdce38b4261e641c23e086c9e177344e3af151f8b3db Deleted: sha256:556e4daf04e3d78c665b69628d45022e6d65562ca762cd2f9f9198682d36e69b Deleted: sha256:e8d138df10f8cf5d9eca0fb0d4aae5d67a5608931278e996ce257ac06ca1cbf7 Deleted: sha256:2b54a60e24c81a4977880829f7adf616e21024fccc25c10c7cd5eea2e70800b4 Deleted: sha256:c38620ac232027aed2cc6a50732ba701725d965acbf64b0e36a55c6cd09d7f50 Deleted: sha256:13da87bbd783b393bd852d3dcb29a1c659f6e7940d84c75f8a4ba593d39ae4e8 Deleted: sha256:0d164ade3e78c7535d595424fcd9d044f0ad2bb2eeab4bc2b5e65cad3bcab8f7 Deleted: sha256:6eee1852ec93beb212bfeb299b9fc26fe43f51c8f808ab418385146ff5e8bd57 Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected]
