See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4581/display/redirect>
------------------------------------------ [...truncated 16.58 MB...] 3: I1212 03:10:06.038347 16063 registrar.cpp:347] Recovering registrar 3: I1212 03:10:06.039088 16063 registrar.cpp:391] Successfully fetched the registry (0B) in 694016ns 3: I1212 03:10:06.039211 16063 registrar.cpp:495] Applied 1 operations in 47us; attempting to update the registry 3: I1212 03:10:06.039911 16066 registrar.cpp:552] Successfully updated the registry in 596992ns 3: I1212 03:10:06.040179 16066 registrar.cpp:424] Successfully recovered registrar 3: I1212 03:10:06.040735 16061 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1212 03:10:06.040774 16067 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1212 03:10:06.043651 16071 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/frameworks' 3: I1212 03:10:06.048032 16056 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/frameworks' 3: I1212 03:10:06.051983 16057 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/flags' 3: I1212 03:10:06.056175 16052 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/flags' 3: I1212 03:10:06.060632 16064 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/slaves' 3: I1212 03:10:06.064904 16073 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/slaves' 3: I1212 03:10:06.068743 16070 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state' 3: I1212 03:10:06.072585 16053 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state' 3: I1212 03:10:06.076544 16067 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state-summary' 3: I1212 03:10:06.080364 16054 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state-summary' 3: I1212 03:10:06.084422 16074 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/tasks' 3: I1212 03:10:06.088378 16067 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/tasks' 3: I1212 03:10:06.090500 16050 master.cpp:1147] Master terminating 3: [ OK ] MasterTest.EndpointsBadAuthentication (66 ms) 3: [ RUN ] MasterTest.ReadonlyEndpointsNoAuthentication 3: I1212 03:10:06.096439 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1212 03:10:06.099634 16053 master.cpp:456] Master 5be35374-fbfd-4461-b5fc-5a761d483864 (8f3c34eb5916) started on 172.17.0.4:43045 3: I1212 03:10:06.099673 16053 master.cpp:458] 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="false" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/Bh2lfj/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" --work_dir="/tmp/Bh2lfj/master" --zk_session_timeout="10secs" 3: I1212 03:10:06.100002 16053 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1212 03:10:06.100008 16053 master.cpp:513] Master only allowing authenticated agents to register 3: I1212 03:10:06.100013 16053 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1212 03:10:06.100018 16053 credentials.hpp:37] Loading credentials for authentication from '/tmp/Bh2lfj/credentials' 3: I1212 03:10:06.100283 16053 master.cpp:563] Using default 'crammd5' authenticator 3: I1212 03:10:06.100430 16053 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1212 03:10:06.100569 16053 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1212 03:10:06.100706 16053 master.cpp:642] Authorization enabled 3: I1212 03:10:06.100870 16071 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1212 03:10:06.100917 16069 whitelist_watcher.cpp:77] No whitelist given 3: I1212 03:10:06.103781 16066 master.cpp:2209] Elected as the leading master! 3: I1212 03:10:06.103807 16066 master.cpp:1689] Recovering from registrar 3: I1212 03:10:06.103955 16051 registrar.cpp:347] Recovering registrar 3: I1212 03:10:06.104460 16051 registrar.cpp:391] Successfully fetched the registry (0B) in 472832ns 3: I1212 03:10:06.104552 16051 registrar.cpp:495] Applied 1 operations in 28453ns; attempting to update the registry 3: I1212 03:10:06.105128 16061 registrar.cpp:552] Successfully updated the registry in 426240ns 3: I1212 03:10:06.105268 16061 registrar.cpp:424] Successfully recovered registrar 3: I1212 03:10:06.105725 16067 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1212 03:10:06.105806 16069 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1212 03:10:06.107978 16063 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state' 3: I1212 03:10:06.109279 16070 http.cpp:1185] HTTP GET for /master/state from 172.17.0.4:59930 3: I1212 03:10:06.115139 16053 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/quota' 3: I1212 03:10:06.117228 16050 master.cpp:1147] Master terminating 3: [ OK ] MasterTest.ReadonlyEndpointsNoAuthentication (26 ms) 3: [ RUN ] MasterTest.ReadwriteEndpointsNoAuthentication 3: I1212 03:10:06.123493 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1212 03:10:06.126399 16057 master.cpp:456] Master ebc01334-503a-4bd4-a751-d9e4a8199f67 (8f3c34eb5916) started on 172.17.0.4:43045 3: I1212 03:10:06.126418 16057 master.cpp:458] 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="false" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/hpEzOH/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" --work_dir="/tmp/hpEzOH/master" --zk_session_timeout="10secs" 3: I1212 03:10:06.126667 16057 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1212 03:10:06.126675 16057 master.cpp:513] Master only allowing authenticated agents to register 3: I1212 03:10:06.126680 16057 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1212 03:10:06.126685 16057 credentials.hpp:37] Loading credentials for authentication from '/tmp/hpEzOH/credentials' 3: I1212 03:10:06.126926 16057 master.cpp:563] Using default 'crammd5' authenticator 3: I1212 03:10:06.127050 16057 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1212 03:10:06.127182 16057 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1212 03:10:06.127292 16057 master.cpp:642] Authorization enabled 3: I1212 03:10:06.127456 16056 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1212 03:10:06.127490 16064 whitelist_watcher.cpp:77] No whitelist given 3: I1212 03:10:06.130199 16069 master.cpp:2209] Elected as the leading master! 3: I1212 03:10:06.130223 16069 master.cpp:1689] Recovering from registrar 3: I1212 03:10:06.130391 16062 registrar.cpp:347] Recovering registrar 3: I1212 03:10:06.130889 16062 registrar.cpp:391] Successfully fetched the registry (0B) in 463104ns 3: I1212 03:10:06.130972 16062 registrar.cpp:495] Applied 1 operations in 21231ns; attempting to update the registry 3: I1212 03:10:06.131453 16062 registrar.cpp:552] Successfully updated the registry in 428800ns 3: I1212 03:10:06.131561 16062 registrar.cpp:424] Successfully recovered registrar 3: I1212 03:10:06.132024 16074 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1212 03:10:06.132045 16065 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: I1212 03:10:06.134590 16051 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/quota' 3: I1212 03:10:06.136461 16059 http.cpp:1185] HTTP GET for /master/quota from 172.17.0.4:59934 3: I1212 03:10:06.136494 16059 quota_handler.cpp:360] Handling quota status request 3: I1212 03:10:06.139992 16065 process.cpp:3503] Handling HTTP event for process 'master' with path: '/master/state' 3: I1212 03:10:06.142355 16055 master.cpp:1147] Master terminating 3: [ OK ] MasterTest.ReadwriteEndpointsNoAuthentication (25 ms) 3: [ RUN ] MasterTest.RejectFrameworkWithInvalidFailoverTimeout 3: I1212 03:10:06.148120 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1212 03:10:06.151360 16074 master.cpp:456] Master d085fa05-21d1-4605-abdb-1ebac20f9ad1 (8f3c34eb5916) started on 172.17.0.4:43045 3: I1212 03:10:06.151382 16074 master.cpp:458] 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/FREyr6/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" --work_dir="/tmp/FREyr6/master" --zk_session_timeout="10secs" 3: I1212 03:10:06.151672 16074 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1212 03:10:06.151681 16074 master.cpp:513] Master only allowing authenticated agents to register 3: I1212 03:10:06.151686 16074 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1212 03:10:06.151690 16074 credentials.hpp:37] Loading credentials for authentication from '/tmp/FREyr6/credentials' 3: I1212 03:10:06.151984 16074 master.cpp:563] Using default 'crammd5' authenticator 3: I1212 03:10:06.152129 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1212 03:10:06.152287 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1212 03:10:06.152415 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1212 03:10:06.152526 16074 master.cpp:642] Authorization enabled 3: I1212 03:10:06.152709 16058 whitelist_watcher.cpp:77] No whitelist given 3: I1212 03:10:06.152710 16051 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1212 03:10:06.155205 16059 master.cpp:2209] Elected as the leading master! 3: I1212 03:10:06.155230 16059 master.cpp:1689] Recovering from registrar 3: I1212 03:10:06.155402 16063 registrar.cpp:347] Recovering registrar 3: I1212 03:10:06.156011 16063 registrar.cpp:391] Successfully fetched the registry (0B) in 572928ns 3: I1212 03:10:06.156111 16063 registrar.cpp:495] Applied 1 operations in 28403ns; attempting to update the registry 3: I1212 03:10:06.156644 16063 registrar.cpp:552] Successfully updated the registry in 488960ns 3: I1212 03:10:06.156759 16063 registrar.cpp:424] Successfully recovered registrar 3: I1212 03:10:06.157131 16064 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1212 03:10:06.157171 16069 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: W1212 03:10:06.157951 16050 process.cpp:2756] Attempted to spawn already running process [email protected]:43045 3: I1212 03:10:06.158875 16050 sched.cpp:232] Version: 1.5.0 3: I1212 03:10:06.159466 16060 sched.cpp:336] New master detected at [email protected]:43045 3: I1212 03:10:06.159569 16060 sched.cpp:396] Authenticating with master [email protected]:43045 3: I1212 03:10:06.159585 16060 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1212 03:10:06.159860 16059 authenticatee.cpp:121] Creating new client SASL connection 3: I1212 03:10:06.160157 16066 master.cpp:8888] Authenticating [email protected]:43045 3: I1212 03:10:06.160277 16071 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(839)@172.17.0.4:43045 3: I1212 03:10:06.160537 16053 authenticator.cpp:98] Creating new server SASL connection 3: I1212 03:10:06.160866 16065 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1212 03:10:06.160892 16065 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1212 03:10:06.161026 16070 authenticator.cpp:204] Received SASL authentication start 3: I1212 03:10:06.161108 16070 authenticator.cpp:326] Authentication requires more steps 3: I1212 03:10:06.161238 16070 authenticatee.cpp:259] Received SASL authentication step 3: I1212 03:10:06.161372 16070 authenticator.cpp:232] Received SASL authentication step 3: I1212 03:10:06.161404 16070 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1212 03:10:06.161423 16070 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1212 03:10:06.161469 16070 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1212 03:10:06.161497 16070 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1212 03:10:06.161511 16070 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.161532 16070 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.161554 16070 authenticator.cpp:318] Authentication success 3: I1212 03:10:06.161689 16052 authenticatee.cpp:299] Authentication success 3: I1212 03:10:06.161748 16063 master.cpp:8918] Successfully authenticated principal 'test-principal' at [email protected]:43045 3: I1212 03:10:06.162030 16064 sched.cpp:502] Successfully authenticated with master [email protected]:43045 3: I1212 03:10:06.162024 16057 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(839)@172.17.0.4:43045 3: I1212 03:10:06.162055 16064 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:43045 3: I1212 03:10:06.162175 16064 sched.cpp:857] Will retry registration in 657.83597ms if necessary 3: I1212 03:10:06.162411 16051 master.cpp:2947] Refusing subscription of framework 'default' at [email protected]:43045: The framework failover_timeout (1e+17) is invalid 3: I1212 03:10:06.162535 16051 sched.cpp:1175] Got error 'The framework failover_timeout (1e+17) is invalid' 3: I1212 03:10:06.162552 16051 sched.cpp:2043] Asked to abort the driver 3: I1212 03:10:06.162624 16051 sched.cpp:1186] Scheduler::error took 48773ns 3: I1212 03:10:06.162664 16051 sched.cpp:1221] Aborting framework 3: I1212 03:10:06.162674 16051 sched.cpp:1226] Not sending a deactivate message as master is disconnected 3: I1212 03:10:06.163705 16054 master.cpp:1147] Master terminating 3: [ OK ] MasterTest.RejectFrameworkWithInvalidFailoverTimeout (21 ms) 3: [ RUN ] MasterTest.FailoverAgentReregisterFirst 3: I1212 03:10:06.169744 16050 cluster.cpp:172] Creating default 'local' authorizer 3: I1212 03:10:06.172660 16074 master.cpp:456] Master 484bf1a5-d093-41c4-931a-becbd4529b9c (8f3c34eb5916) started on 172.17.0.4:43045 3: I1212 03:10:06.172682 16074 master.cpp:458] 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/PQjG7u/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" --work_dir="/tmp/PQjG7u/master" --zk_session_timeout="10secs" 3: I1212 03:10:06.172924 16074 master.cpp:507] Master only allowing authenticated frameworks to register 3: I1212 03:10:06.172931 16074 master.cpp:513] Master only allowing authenticated agents to register 3: I1212 03:10:06.172935 16074 master.cpp:519] Master only allowing authenticated HTTP frameworks to register 3: I1212 03:10:06.172940 16074 credentials.hpp:37] Loading credentials for authentication from '/tmp/PQjG7u/credentials' 3: I1212 03:10:06.173173 16074 master.cpp:563] Using default 'crammd5' authenticator 3: I1212 03:10:06.173297 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1212 03:10:06.173434 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1212 03:10:06.173558 16074 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1212 03:10:06.173682 16074 master.cpp:642] Authorization enabled 3: I1212 03:10:06.173837 16071 hierarchical.cpp:175] Initialized hierarchical allocator process 3: I1212 03:10:06.173844 16065 whitelist_watcher.cpp:77] No whitelist given 3: I1212 03:10:06.176242 16069 master.cpp:2209] Elected as the leading master! 3: I1212 03:10:06.176267 16069 master.cpp:1689] Recovering from registrar 3: I1212 03:10:06.176410 16061 registrar.cpp:347] Recovering registrar 3: I1212 03:10:06.176949 16061 registrar.cpp:391] Successfully fetched the registry (0B) in 502784ns 3: I1212 03:10:06.177034 16061 registrar.cpp:495] Applied 1 operations in 21694ns; attempting to update the registry 3: I1212 03:10:06.177530 16061 registrar.cpp:552] Successfully updated the registry in 452096ns 3: I1212 03:10:06.177640 16061 registrar.cpp:424] Successfully recovered registrar 3: I1212 03:10:06.177999 16055 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register 3: I1212 03:10:06.178046 16054 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover 3: W1212 03:10:06.182763 16050 process.cpp:2756] Attempted to spawn already running process [email protected]:43045 3: I1212 03:10:06.183753 16050 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1212 03:10:06.184299 16050 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1212 03:10:06.184422 16050 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1212 03:10:06.184453 16050 provisioner.cpp:299] Using default backend 'copy' 3: I1212 03:10:06.186766 16050 cluster.cpp:460] Creating default 'local' authorizer 3: I1212 03:10:06.189282 16054 slave.cpp:258] Mesos agent started on (425)@172.17.0.4:43045 3: I1212 03:10:06.189306 16054 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/MasterTest_FailoverAgentReregisterFirst_JPlRxi/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_FailoverAgentReregisterFirst_JPlRxi/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/MasterTest_FailoverAgentReregisterFirst_JPlRxi/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/MasterTest_FailoverAgentReregisterFirst_JPlRxi/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_FailoverAgentReregisterFirst_JPlRxi/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" --reconfiguration_policy="equal" --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_FailoverAgentReregisterFirst_JPlRxi" --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_FailoverAgentReregisterFirst_TacLPT" --zk_session_timeout="10secs" 3: I1212 03:10:06.189705 16054 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_FailoverAgentReregisterFirst_JPlRxi/credential' 3: W1212 03:10:06.189797 16050 process.cpp:2756] Attempted to spawn already running process [email protected]:43045 3: I1212 03:10:06.189980 16054 slave.cpp:291] Agent using credential for: test-principal 3: I1212 03:10:06.190001 16054 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_FailoverAgentReregisterFirst_JPlRxi/http_credentials' 3: I1212 03:10:06.190222 16054 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1212 03:10:06.190374 16054 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1212 03:10:06.190567 16050 sched.cpp:232] Version: 1.5.0 3: I1212 03:10:06.191141 16067 sched.cpp:336] New master detected at [email protected]:43045 3: I1212 03:10:06.191237 16067 sched.cpp:396] Authenticating with master [email protected]:43045 3: I1212 03:10:06.191253 16067 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1212 03:10:06.191491 16062 authenticatee.cpp:121] Creating new client SASL connection 3: I1212 03:10:06.191733 16053 master.cpp:8888] Authenticating [email protected]:43045 3: I1212 03:10:06.191854 16060 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(840)@172.17.0.4:43045 3: I1212 03:10:06.192060 16061 authenticator.cpp:98] Creating new server SASL connection 3: I1212 03:10:06.191872 16054 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1212 03:10:06.192091 16054 slave.cpp:598] Agent attributes: [ ] 3: I1212 03:10:06.192109 16054 slave.cpp:607] Agent hostname: 8f3c34eb5916 3: I1212 03:10:06.192248 16059 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1212 03:10:06.192255 16065 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1212 03:10:06.192282 16065 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1212 03:10:06.192392 16066 authenticator.cpp:204] Received SASL authentication start 3: I1212 03:10:06.192436 16066 authenticator.cpp:326] Authentication requires more steps 3: I1212 03:10:06.192543 16071 authenticatee.cpp:259] Received SASL authentication step 3: I1212 03:10:06.192680 16071 authenticator.cpp:232] Received SASL authentication step 3: I1212 03:10:06.192713 16071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1212 03:10:06.192721 16071 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1212 03:10:06.192751 16071 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1212 03:10:06.192766 16071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1212 03:10:06.192776 16071 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.192782 16071 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.192795 16071 authenticator.cpp:318] Authentication success 3: I1212 03:10:06.192905 16063 authenticatee.cpp:299] Authentication success 3: I1212 03:10:06.192986 16058 master.cpp:8918] Successfully authenticated principal 'test-principal' at [email protected]:43045 3: I1212 03:10:06.193044 16070 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(840)@172.17.0.4:43045 3: I1212 03:10:06.193343 16073 sched.cpp:502] Successfully authenticated with master [email protected]:43045 3: I1212 03:10:06.193367 16073 sched.cpp:824] Sending SUBSCRIBE call to [email protected]:43045 3: I1212 03:10:06.193490 16073 sched.cpp:857] Will retry registration in 208.669627ms if necessary 3: I1212 03:10:06.193697 16069 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at [email protected]:43045 3: I1212 03:10:06.193780 16069 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1212 03:10:06.193981 16064 state.cpp:66] Recovering state from '/tmp/MasterTest_FailoverAgentReregisterFirst_TacLPT/meta' 3: I1212 03:10:06.194296 16051 task_status_update_manager.cpp:207] Recovering task status update manager 3: I1212 03:10:06.194377 16056 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ] 3: I1212 03:10:06.194516 16060 containerizer.cpp:674] Recovering containerizer 3: I1212 03:10:06.194685 16056 master.cpp:9109] Adding framework 484bf1a5-d093-41c4-931a-becbd4529b9c-0000 (default) at [email protected]:43045 with roles { } suppressed 3: I1212 03:10:06.195271 16061 hierarchical.cpp:297] Added framework 484bf1a5-d093-41c4-931a-becbd4529b9c-0000 3: I1212 03:10:06.195420 16071 sched.cpp:751] Framework registered with 484bf1a5-d093-41c4-931a-becbd4529b9c-0000 3: I1212 03:10:06.195516 16061 hierarchical.cpp:1517] Performed allocation for 0 agents in 92926ns 3: I1212 03:10:06.195528 16071 sched.cpp:765] Scheduler::registered took 83977ns 3: I1212 03:10:06.196276 16062 provisioner.cpp:495] Provisioner recovery complete 3: I1212 03:10:06.196678 16053 slave.cpp:6724] Finished recovery 3: I1212 03:10:06.197396 16066 task_status_update_manager.cpp:181] Pausing sending task status updates 3: I1212 03:10:06.197410 16055 slave.cpp:1033] New master detected at [email protected]:43045 3: I1212 03:10:06.197513 16055 slave.cpp:1088] Detecting new master 3: I1212 03:10:06.203899 16070 slave.cpp:1115] Authenticating with master [email protected]:43045 3: I1212 03:10:06.203994 16070 slave.cpp:1124] Using default CRAM-MD5 authenticatee 3: I1212 03:10:06.204257 16060 authenticatee.cpp:121] Creating new client SASL connection 3: I1212 03:10:06.204545 16060 master.cpp:8888] Authenticating slave(425)@172.17.0.4:43045 3: I1212 03:10:06.204704 16063 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(841)@172.17.0.4:43045 3: I1212 03:10:06.204921 16061 authenticator.cpp:98] Creating new server SASL connection 3: I1212 03:10:06.205168 16071 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1212 03:10:06.205198 16071 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1212 03:10:06.205310 16071 authenticator.cpp:204] Received SASL authentication start 3: I1212 03:10:06.205390 16071 authenticator.cpp:326] Authentication requires more steps 3: I1212 03:10:06.205492 16071 authenticatee.cpp:259] Received SASL authentication step 3: I1212 03:10:06.205611 16071 authenticator.cpp:232] Received SASL authentication step 3: I1212 03:10:06.205658 16071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1212 03:10:06.205682 16071 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1212 03:10:06.205734 16071 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1212 03:10:06.205759 16071 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8f3c34eb5916' server FQDN: '8f3c34eb5916' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1212 03:10:06.205766 16071 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.205771 16071 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1212 03:10:06.205782 16071 authenticator.cpp:318] Authentication success 3: I1212 03:10:06.205899 16052 authenticatee.cpp:299] Authentication success 3: I1212 03:10:06.205971 16064 master.cpp:8918] Successfully authenticated principal 'test-principal' at slave(425)@172.17.0.4:43045 3: I1212 03:10:06.206012 16069 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(841)@172.17.0.4:43045 write /dev/stdout: resource temporarily unavailable 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]
