See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4533/display/redirect?page=changes>
Changes: [bmahler] Added MESOS-7674 to the 1.3.2 CHANGELOG. [bmahler] Added MESOS-8237 to the 1.3.2 CHANGELOG. [bmahler] Added MESOS-8237 to the 1.4.2 CHANGELOG. [songzihao1990] Add a temporary filter for overlay backend related tests. [xujyan] Removed redundant get() call on Owned pointers. ------------------------------------------ [...truncated 13.78 MB...] 3: I1202 16:26:42.213268 20063 master.cpp:505] Master only allowing authenticated frameworks to register 3: I1202 16:26:42.213279 20063 master.cpp:511] Master only allowing authenticated agents to register 3: I1202 16:26:42.213284 20063 master.cpp:517] Master only allowing authenticated HTTP frameworks to register 3: I1202 16:26:42.213292 20063 credentials.hpp:37] Loading credentials for authentication from '/tmp/lHMtmi/credentials' 3: I1202 16:26:42.213706 20063 master.cpp:561] Using default 'crammd5' authenticator 3: I1202 16:26:42.213898 20063 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1202 16:26:42.214129 20063 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1202 16:26:42.214308 20063 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1202 16:26:42.214493 20063 master.cpp:640] Authorization enabled 3: I1202 16:26:42.214763 20083 hierarchical.cpp:174] Initialized hierarchical allocator process 3: I1202 16:26:42.214823 20069 whitelist_watcher.cpp:77] No whitelist given 3: I1202 16:26:42.218603 20068 master.cpp:2221] Elected as the leading master! 3: I1202 16:26:42.218657 20068 master.cpp:1701] Recovering from registrar 3: I1202 16:26:42.218863 20070 registrar.cpp:347] Recovering registrar 3: I1202 16:26:42.219676 20070 registrar.cpp:391] Successfully fetched the registry (0B) in 752128ns 3: I1202 16:26:42.219817 20070 registrar.cpp:495] Applied 1 operations in 28495ns; attempting to update the registry 3: I1202 16:26:42.220487 20070 registrar.cpp:552] Successfully updated the registry in 596992ns 3: I1202 16:26:42.220652 20070 registrar.cpp:424] Successfully recovered registrar 3: I1202 16:26:42.221194 20062 hierarchical.cpp:212] Skipping recovery of hierarchical allocator: nothing to recover 3: I1202 16:26:42.221359 20081 master.cpp:1814] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: W1202 16:26:42.229657 20060 process.cpp:2756] Attempted to spawn already running process [email protected]:34479 3: I1202 16:26:42.230162 20060 cluster.cpp:460] Creating default 'local' authorizer 3: I1202 16:26:42.234232 20068 slave.cpp:255] Mesos agent started on (313)@172.17.0.2:34479 3: I1202 16:26:42.234266 20068 slave.cpp:256] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/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/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/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/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --jwt_secret_key="/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/jwt_secret_key" --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:0;mem:200" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF" --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/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y" --zk_session_timeout="10secs" 3: I1202 16:26:42.234984 20068 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/credential' 3: W1202 16:26:42.235185 20060 process.cpp:2756] Attempted to spawn already running process [email protected]:34479 3: I1202 16:26:42.235275 20068 slave.cpp:288] Agent using credential for: test-principal 3: I1202 16:26:42.235327 20068 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_DqDxQF/http_credentials' 3: I1202 16:26:42.235819 20068 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1202 16:26:42.236043 20068 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1202 16:26:42.236407 20068 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1202 16:26:42.236553 20068 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1202 16:26:42.236842 20068 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1202 16:26:42.237095 20068 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1202 16:26:42.237182 20060 sched.cpp:232] Version: 1.5.0 3: I1202 16:26:42.237934 20061 sched.cpp:336] New master detected at [email protected]:34479 3: I1202 16:26:42.238106 20061 sched.cpp:396] Authenticating with master [email protected]:34479 3: I1202 16:26:42.238143 20061 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1202 16:26:42.238512 20067 authenticatee.cpp:121] Creating new client SASL connection 3: I1202 16:26:42.238848 20078 master.cpp:8607] Authenticating [email protected]:34479 3: I1202 16:26:42.238698 20068 slave.cpp:587] Agent resources: [{"name":"mem","scalar":{"value":200.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1202 16:26:42.238888 20068 slave.cpp:595] Agent attributes: [ ] 3: I1202 16:26:42.238900 20068 slave.cpp:604] Agent hostname: 4f09ac001f12 3: I1202 16:26:42.238975 20064 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(614)@172.17.0.2:34479 3: I1202 16:26:42.239023 20070 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1202 16:26:42.239257 20065 authenticator.cpp:98] Creating new server SASL connection 3: I1202 16:26:42.239588 20080 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1202 16:26:42.239626 20080 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1202 16:26:42.239841 20077 authenticator.cpp:204] Received SASL authentication start 3: I1202 16:26:42.239931 20077 authenticator.cpp:326] Authentication requires more steps 3: I1202 16:26:42.240110 20062 authenticatee.cpp:259] Received SASL authentication step 3: I1202 16:26:42.240283 20072 authenticator.cpp:232] Received SASL authentication step 3: I1202 16:26:42.240320 20072 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f09ac001f12' server FQDN: '4f09ac001f12' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1202 16:26:42.240332 20072 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1202 16:26:42.240375 20072 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1202 16:26:42.240397 20072 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f09ac001f12' server FQDN: '4f09ac001f12' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1202 16:26:42.240406 20072 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1202 16:26:42.240414 20072 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1202 16:26:42.240430 20072 authenticator.cpp:318] Authentication success 3: I1202 16:26:42.240538 20069 authenticatee.cpp:299] Authentication success 3: I1202 16:26:42.240658 20083 master.cpp:8637] Successfully authenticated principal 'test-principal' at [email protected]:34479 3: I1202 16:26:42.240696 20069 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(614)@172.17.0.2:34479 3: I1202 16:26:42.240922 20063 sched.cpp:502] Successfully authenticated with master [email protected]:34479 3: I1202 16:26:42.240947 20063 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:34479 3: I1202 16:26:42.241138 20063 sched.cpp:857] Will retry registration in 457.301421ms if necessary 3: I1202 16:26:42.241425 20076 master.cpp:2969] Received SUBSCRIBE call for framework 'default' at [email protected]:34479 3: I1202 16:26:42.241557 20076 master.cpp:2286] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1202 16:26:42.241704 20073 state.cpp:66] Recovering state from '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/meta' 3: I1202 16:26:42.242182 20067 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1202 16:26:42.242234 20070 master.cpp:3049] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1202 16:26:42.242410 20070 master.cpp:8828] Adding framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 with roles { } suppressed 3: I1202 16:26:42.242645 20079 slave.cpp:6515] Finished recovery 3: I1202 16:26:42.243029 20062 sched.cpp:751] Framework registered with d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.243073 20062 sched.cpp:765] Scheduler::registered took 18452ns 3: I1202 16:26:42.243129 20077 hierarchical.cpp:294] Added framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.243443 20077 hierarchical.cpp:1433] Performed allocation for 0 agents in 131342ns 3: I1202 16:26:42.243567 20083 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1202 16:26:42.243582 20084 slave.cpp:1001] New master detected at [email protected]:34479 3: I1202 16:26:42.243693 20084 slave.cpp:1036] Detecting new master 3: I1202 16:26:42.249467 20069 slave.cpp:1063] Authenticating with master [email protected]:34479 3: I1202 16:26:42.249547 20069 slave.cpp:1072] Using default CRAM-MD5 authenticatee 3: I1202 16:26:42.249851 20063 authenticatee.cpp:121] Creating new client SASL connection 3: I1202 16:26:42.250259 20075 master.cpp:8607] Authenticating slave(313)@172.17.0.2:34479 3: I1202 16:26:42.250437 20068 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(615)@172.17.0.2:34479 3: I1202 16:26:42.250751 20074 authenticator.cpp:98] Creating new server SASL connection 3: I1202 16:26:42.251001 20076 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1202 16:26:42.251035 20076 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1202 16:26:42.251230 20073 authenticator.cpp:204] Received SASL authentication start 3: I1202 16:26:42.251296 20073 authenticator.cpp:326] Authentication requires more steps 3: I1202 16:26:42.251399 20073 authenticatee.cpp:259] Received SASL authentication step 3: I1202 16:26:42.251508 20078 authenticator.cpp:232] Received SASL authentication step 3: I1202 16:26:42.251536 20078 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f09ac001f12' server FQDN: '4f09ac001f12' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1202 16:26:42.251552 20078 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1202 16:26:42.251581 20078 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1202 16:26:42.251598 20078 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f09ac001f12' server FQDN: '4f09ac001f12' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1202 16:26:42.251612 20078 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1202 16:26:42.251619 20078 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1202 16:26:42.251632 20078 authenticator.cpp:318] Authentication success 3: I1202 16:26:42.251778 20082 authenticatee.cpp:299] Authentication success 3: I1202 16:26:42.251826 20064 master.cpp:8637] Successfully authenticated principal 'test-principal' at slave(313)@172.17.0.2:34479 3: I1202 16:26:42.251858 20065 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(615)@172.17.0.2:34479 3: I1202 16:26:42.252194 20067 slave.cpp:1155] Successfully authenticated with master [email protected]:34479 3: I1202 16:26:42.252508 20067 slave.cpp:1698] Will retry registration in 3.146863ms if necessary 3: I1202 16:26:42.252756 20079 master.cpp:6042] Received register agent message from slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.252918 20079 master.cpp:3878] Authorizing agent with principal 'test-principal' 3: I1202 16:26:42.253410 20077 master.cpp:6104] Authorized registration of agent at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.253504 20077 master.cpp:6197] Registering agent at slave(313)@172.17.0.2:34479 (4f09ac001f12) with id d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: I1202 16:26:42.253985 20072 registrar.cpp:495] Applied 1 operations in 85385ns; attempting to update the registry 3: I1202 16:26:42.254698 20072 registrar.cpp:552] Successfully updated the registry in 637952ns 3: I1202 16:26:42.254983 20063 master.cpp:6246] Admitted agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.255911 20063 master.cpp:6282] Registered agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) with mem:200; disk:367489; ports:[31000-32000] 3: I1202 16:26:42.255966 20068 slave.cpp:1201] Registered with master [email protected]:34479; given agent ID d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: I1202 16:26:42.256086 20063 task_status_update_manager.cpp:188] Resuming sending task status updates 3: I1202 16:26:42.256274 20074 hierarchical.cpp:555] Added agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 (4f09ac001f12) with mem:200; disk:367489; ports:[31000-32000] (allocated: {}) 3: I1202 16:26:42.256489 20068 slave.cpp:1221] Checkpointing SlaveInfo to '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/meta/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/slave.info' 3: I1202 16:26:42.257133 20068 slave.cpp:1300] Forwarding total oversubscribed resources {} 3: I1202 16:26:42.257336 20068 master.cpp:7050] Received update of agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) with total oversubscribed resources {} 3: I1202 16:26:42.257511 20068 master.cpp:7093] Ignoring update on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) as it reports no changes 3: I1202 16:26:42.257691 20074 hierarchical.cpp:1433] Performed allocation for 1 agents in 1.18155ms 3: I1202 16:26:42.258342 20082 master.cpp:8437] Sending 1 offers to framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.259724 20082 sched.cpp:921] Scheduler::resourceOffers took 797147ns 3: I1202 16:26:42.261025 20062 master.cpp:10352] Removing offer d4dc6903-42a4-4b44-932e-f05c01433925-O0 3: I1202 16:26:42.261226 20062 master.cpp:4236] Processing ACCEPT call for offers: [ d4dc6903-42a4-4b44-932e-f05c01433925-O0 ] on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) for framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.261329 20062 master.cpp:3605] Authorizing framework principal 'test-principal' to launch task 0 3: W1202 16:26:42.262924 20065 validation.cpp:1391] Executor 'default' for task '0' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. 3: W1202 16:26:42.262955 20065 validation.cpp:1403] Executor 'default' for task '0' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. 3: I1202 16:26:42.263285 20065 master.cpp:11112] Adding task 0 with resources mem(allocated: *):200 on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.263475 20065 master.cpp:4984] Launching task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 with resources [{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":200.0},"type":"SCALAR"}] on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.263962 20067 slave.cpp:1819] Got assigned task '0' for framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.264411 20079 hierarchical.cpp:1108] Recovered ports(allocated: *):[31000-32000]; disk(allocated: *):367489 (total: mem:200; disk:367489; ports:[31000-32000], allocated: mem(allocated: *):200) on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 from framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.264472 20079 hierarchical.cpp:1154] Framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 filtered agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 for 5secs 3: I1202 16:26:42.264495 20067 slave.cpp:2087] Authorizing task '0' for framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.264539 20067 slave.cpp:7413] Authorizing framework principal 'test-principal' to launch task 0 3: I1202 16:26:42.265381 20071 slave.cpp:2255] Launching task '0' for framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.265956 20071 paths.cpp:692] Trying to chown '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' to user 'mesos' 3: I1202 16:26:42.266387 20071 slave.cpp:7887] Launching executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 with resources [] in work directory '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' 3: I1202 16:26:42.266499 20069 hierarchical.cpp:1433] Performed allocation for 1 agents in 160704ns 3: I1202 16:26:42.267442 20071 slave.cpp:2483] Queued task '0' for executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.267552 20071 slave.cpp:952] Successfully attached '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' to virtual path '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/latest' 3: I1202 16:26:42.267598 20071 slave.cpp:952] Successfully attached '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' to virtual path '/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/latest' 3: I1202 16:26:42.267632 20071 slave.cpp:952] Successfully attached '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' to virtual path '/tmp/MasterAllocatorTest_1_MemoryOnlyOfferedAndTaskLaunched_NvJu6Y/slaves/d4dc6903-42a4-4b44-932e-f05c01433925-S0/frameworks/d4dc6903-42a4-4b44-932e-f05c01433925-0000/executors/default/runs/61046dd2-0fea-41cb-bca6-ba94e6b16bb5' 3: I1202 16:26:42.267940 20071 slave.cpp:2946] Launching container 61046dd2-0fea-41cb-bca6-ba94e6b16bb5 for executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: W1202 16:26:42.271767 20075 process.cpp:2756] Attempted to spawn already running process [email protected]:34479 3: I1202 16:26:42.272043 20075 exec.cpp:162] Version: 1.5.0 3: I1202 16:26:42.272410 20061 exec.cpp:212] Executor started at: executor(113)@172.17.0.2:34479 with pid 20060 3: I1202 16:26:42.272594 20061 slave.cpp:4135] Got registration for executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 from executor(113)@172.17.0.2:34479 3: I1202 16:26:42.272943 20073 exec.cpp:237] Executor registered on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: I1202 16:26:42.272985 20073 exec.cpp:249] Executor::registered took 21582ns 3: I1202 16:26:42.273371 20061 slave.cpp:2685] Sending queued task '0' to executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 at executor(113)@172.17.0.2:34479 3: I1202 16:26:42.273757 20068 exec.cpp:331] Executor asked to run task '0' 3: I1202 16:26:42.273896 20068 exec.cpp:340] Executor::launchTask took 111747ns 3: I1202 16:26:42.274006 20068 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.274354 20068 slave.cpp:4589] Handling status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 from executor(113)@172.17.0.2:34479 3: I1202 16:26:42.274463 20070 exec.cpp:571] Executor sending status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.274749 20070 slave.cpp:4589] Handling status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 from executor(113)@172.17.0.2:34479 3: I1202 16:26:42.275353 20079 task_status_update_manager.cpp:328] Received task status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.275395 20079 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.275830 20079 task_status_update_manager.cpp:383] Forwarding task status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to the agent 3: I1202 16:26:42.275987 20069 slave.cpp:5072] Forwarding the update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to [email protected]:34479 3: I1202 16:26:42.276082 20079 task_status_update_manager.cpp:328] Received task status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276166 20069 slave.cpp:4965] Task status update manager successfully handled status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276207 20069 slave.cpp:4981] Sending acknowledgement for status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to executor(113)@172.17.0.2:34479 3: I1202 16:26:42.276306 20069 slave.cpp:4965] Task status update manager successfully handled status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276343 20069 slave.cpp:4981] Sending acknowledgement for status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to executor(113)@172.17.0.2:34479 3: I1202 16:26:42.276368 20072 master.cpp:7558] Status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 from agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.276418 20072 master.cpp:7614] Forwarding status update TASK_RUNNING (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276445 20071 exec.cpp:388] Executor received status update acknowledgement d1b34e0f-19b6-4c80-bd56-b41a87d4c117 for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276563 20072 master.cpp:9828] Updating the state of task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (latest state: TASK_FINISHED, status update state: TASK_RUNNING) 3: I1202 16:26:42.276607 20071 exec.cpp:388] Executor received status update acknowledgement 4e547a7b-f602-4d6a-af83-e2db56537bf1 for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.276820 20076 sched.cpp:1029] Scheduler::statusUpdate took 20588ns 3: I1202 16:26:42.277174 20063 hierarchical.cpp:1108] Recovered mem(allocated: *):200 (total: mem:200; disk:367489; ports:[31000-32000], allocated: {}) on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 from framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.277292 20075 master.cpp:5799] Processing ACKNOWLEDGE call d1b34e0f-19b6-4c80-bd56-b41a87d4c117 for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: I1202 16:26:42.277688 20073 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.277817 20073 task_status_update_manager.cpp:383] Forwarding task status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to the agent 3: I1202 16:26:42.278017 20061 slave.cpp:5072] Forwarding the update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 to [email protected]:34479 3: I1202 16:26:42.278257 20061 slave.cpp:3873] Task status update manager successfully handled status update acknowledgement (UUID: d1b34e0f-19b6-4c80-bd56-b41a87d4c117) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.278386 20074 master.cpp:7558] Status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 from agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.278434 20074 master.cpp:7614] Forwarding status update TASK_FINISHED (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.278579 20074 master.cpp:9828] Updating the state of task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED) 3: I1202 16:26:42.278797 20064 sched.cpp:1029] Scheduler::statusUpdate took 17578ns 3: I1202 16:26:42.279075 20080 master.cpp:5799] Processing ACKNOWLEDGE call 4e547a7b-f602-4d6a-af83-e2db56537bf1 for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: I1202 16:26:42.279166 20080 master.cpp:9934] Removing task 0 with resources mem(allocated: *):200 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.279546 20082 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.279649 20082 task_status_update_manager.cpp:538] Cleaning up status update stream for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.279942 20082 slave.cpp:3873] Task status update manager successfully handled status update acknowledgement (UUID: 4e547a7b-f602-4d6a-af83-e2db56537bf1) for task 0 of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.279978 20082 slave.cpp:8492] Completing task 0 3: I1202 16:26:42.318171 20068 hierarchical.cpp:1433] Performed allocation for 1 agents in 798906ns 3: I1202 16:26:42.318572 20077 master.cpp:8437] Sending 1 offers to framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.319010 20077 sched.cpp:921] Scheduler::resourceOffers took 43695ns 3: I1202 16:26:42.319327 20060 sched.cpp:2009] Asked to stop the driver 3: I1202 16:26:42.319512 20079 sched.cpp:1191] Stopping framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.319711 20069 master.cpp:9117] Processing TEARDOWN call for framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.319736 20069 master.cpp:9129] Removing framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.319747 20069 master.cpp:3339] Deactivating framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 (default) at [email protected]:34479 3: I1202 16:26:42.319981 20063 hierarchical.cpp:407] Deactivated framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.320482 20069 master.cpp:10352] Removing offer d4dc6903-42a4-4b44-932e-f05c01433925-O1 3: I1202 16:26:42.320741 20060 slave.cpp:877] Agent terminating 3: I1202 16:26:42.320755 20069 master.cpp:9963] Removing executor 'default' with resources [] of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.320791 20060 slave.cpp:3283] Asked to shut down framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 by @0.0.0.0:0 3: I1202 16:26:42.320813 20060 slave.cpp:3308] Shutting down framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.320847 20060 slave.cpp:5948] Shutting down executor 'default' of framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 at executor(113)@172.17.0.2:34479 3: I1202 16:26:42.320946 20081 exec.cpp:435] Executor asked to shutdown 3: I1202 16:26:42.320983 20081 exec.cpp:450] Executor::shutdown took 17713ns 3: I1202 16:26:42.320981 20063 hierarchical.cpp:1108] Recovered mem(allocated: *):200; disk(allocated: *):367489; ports(allocated: *):[31000-32000] (total: mem:200; disk:367489; ports:[31000-32000], allocated: {}) on agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 from framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.321496 20069 master.cpp:1317] Agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) disconnected 3: I1202 16:26:42.321517 20069 master.cpp:3376] Disconnecting agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.321557 20069 master.cpp:3395] Deactivating agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 at slave(313)@172.17.0.2:34479 (4f09ac001f12) 3: I1202 16:26:42.321727 20084 hierarchical.cpp:346] Removed framework d4dc6903-42a4-4b44-932e-f05c01433925-0000 3: I1202 16:26:42.321791 20084 hierarchical.cpp:673] Agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 deactivated 3: I1202 16:26:42.328447 20082 master.cpp:1159] Master terminating 3: I1202 16:26:42.329262 20080 hierarchical.cpp:588] Removed agent d4dc6903-42a4-4b44-932e-f05c01433925-S0 3: [ OK ] MasterAllocatorTest/1.MemoryOnlyOfferedAndTaskLaunched (129 ms) 3: [ RUN ] MasterAllocatorTest/1.Whitelist 3: I1202 16:26:42.340073 20060 cluster.cpp:172] Creating default 'local' authorizer 3: I1202 16:26:42.344051 20072 master.cpp:454] Master 9727e6d9-e20b-4c4b-912f-22fec7fb420a (4f09ac001f12) started on 172.17.0.2:34479 3: I1202 16:26:42.344072 20072 master.cpp:456] 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/oMcEsK/credentials" --filter_gpu_resources="true" --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" --whitelist="whitelist.txt" --work_dir="/tmp/oMcEsK/master" --zk_session_timeout="10secs" 3: I1202 16:26:42.344324 20072 master.cpp:505] Master only allowing authenticated frameworks to register 3: I1202 16:26:42.344331 20072 master.cpp:511] Master only allowing authenticated agents to register 3: I1202 16:26:42.344336 20072 master.cpp:517] Master only allowing authenticated HTTP frameworks to register 3: I1202 16:26:42.344339 20072 credentials.hpp:37] Loading credentials for authentication from '/tmp/oMcEsK/credentials' 3: I1202 16:26:42.344583 20072 master.cpp:561] Using default 'crammd5' authenticator 3: I1202 16:26:42.344740 20072 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1202 16:26:42.344926 20072 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1202 16:26:42.345137 20072 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1202 16:26:42.345310 20072 master.cpp:640] Authorization enabled 3: I1202 16:26:42.345600 20078 hierarchical.cpp:174] Initialized hierarchical allocator process 3: I1202 16:26:42.345829 20077 hierarchical.cpp:685] Updated agent whitelist: { dummy-agent1 } 3: I1202 16:26:42.348945 20065 master.cpp:2221] Elected as the leading master! 3: I1202 16:26:42.348973 20065 master.cpp:1701] Recovering from registrar 3: I1202 16:26:42.349190 20075 registrar.cpp:347] Recovering registrar 3: I1202 16:26:42.350002 20075 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns 3: I1202 16:26:42.350121 20075 registrar.cpp:495] Applied 1 operations in 27120ns; attempting to update the registry 3: I1202 16:26:42.350764 20075 registrar.cpp:552] Successfully updated the registry in 0ns 3: I1202 16:26:42.350920 20075 registrar.cpp:424] Successfully recovered registrar 3: I1202 16:26:42.351503 20069 hierarchical.cpp:212] Skipping recovery of hierarchical allocator: nothing to recover 3: I1202 16:26:42.351491 20076 master.cpp:1814] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1202 16:26:42.353193 20073 hierarchical.cpp:1433] Performed allocation for 0 agents in 40956ns 3: I1202 16:26:42.354470 20061 hierarchical.cpp:685] Updated agent whitelist: { dummy-agent1, dummy-agent2 } 3: I1202 16:26:42.354712 20060 master.cpp:1159] Master terminating 3: [ OK ] MasterAllocatorTest/1.Whitelist (23 ms) 3: [ RUN ] MasterAllocatorTest/1.RoleTest 3: I1202 16:26:42.363430 20060 cluster.cpp:172] Creating default 'local' authorizer 3: I1202 16:26:42.366926 20063 master.cpp:454] Master 2ef72edf-0538-4e35-95f9-d26b7c310f07 (4f09ac001f12) started on 172.17.0.2:34479 write /dev/stdout: resource temporarily unavailable + docker rmi mesos-1512229806-13892 Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information Build step 'Execute shell' marked build as failure Not sending mail to unregistered user [email protected] Not sending mail to unregistered user [email protected]
