Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4523

[Apache Jenkins Server <je...@builds.apache.org>]

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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4523/display/redirect>

------------------------------------------
[...truncated 3.46 MB...]
3: I1201 02:45:44.576741 17333 master.cpp:8589] Authenticating slave(58)@172.17.0.4:43742
3: I1201 02:45:44.576880 17329 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(129)@172.17.0.4:43742
3: I1201 02:45:44.577122 17332 authenticator.cpp:98] Creating new server SASL connection
3: I1201 02:45:44.577337 17338 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 02:45:44.577360 17338 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 02:45:44.577512 17332 authenticator.cpp:204] Received SASL authentication start
3: I1201 02:45:44.577558 17332 authenticator.cpp:326] Authentication requires more steps
3: I1201 02:45:44.577651 17331 authenticatee.cpp:259] Received SASL authentication step
3: I1201 02:45:44.577759 17334 authenticator.cpp:232] Received SASL authentication step
3: I1201 02:45:44.577785 17334 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1201 02:45:44.577797 17334 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1201 02:45:44.577827 17334 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1201 02:45:44.577848 17334 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1201 02:45:44.577859 17334 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.577867 17334 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.577879 17334 authenticator.cpp:318] Authentication success
3: I1201 02:45:44.578050 17323 authenticatee.cpp:299] Authentication success
3: I1201 02:45:44.578069 17327 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(129)@172.17.0.4:43742
3: I1201 02:45:44.578110 17334 master.cpp:8619] Successfully authenticated principal 'test-principal' at slave(58)@172.17.0.4:43742
3: I1201 02:45:44.578508 17330 slave.cpp:1153] Successfully authenticated with master master@172.17.0.4:43742
3: I1201 02:45:44.578809 17330 slave.cpp:1696] Will retry registration in 9.870972ms if necessary
3: I1201 02:45:44.579300 17325 master.cpp:6042] Received register agent message from slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.579444 17325 master.cpp:3878] Authorizing agent with principal 'test-principal'
3: I1201 02:45:44.579919 17335 master.cpp:6104] Authorized registration of agent at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.580103 17335 master.cpp:6197] Registering agent at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) with id 395db372-c241-4fe4-b74c-e70984dfe8a3-S0
3: I1201 02:45:44.580665 17329 registrar.cpp:495] Applied 1 operations in 78326ns; attempting to update the registry
3: I1201 02:45:44.581332 17329 registrar.cpp:552] Successfully updated the registry in 605952ns
3: I1201 02:45:44.581599 17329 master.cpp:6246] Admitted agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.582314 17329 master.cpp:6282] Registered agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1201 02:45:44.582362 17323 slave.cpp:1199] Registered with master master@172.17.0.4:43742; given agent ID 395db372-c241-4fe4-b74c-e70984dfe8a3-S0
3: I1201 02:45:44.582468 17327 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1201 02:45:44.582674 17337 hierarchical.cpp:553] Added agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 (5dc74a8b2fd6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1201 02:45:44.582743 17323 slave.cpp:1219] Checkpointing SlaveInfo to '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/meta/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/slave.info'
3: I1201 02:45:44.583225 17323 slave.cpp:1298] Forwarding total oversubscribed resources {}
3: I1201 02:45:44.583411 17330 master.cpp:7049] Received update of agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) with total oversubscribed resources {}
3: I1201 02:45:44.583647 17330 master.cpp:7092] Ignoring update on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) as it reports no changes
3: I1201 02:45:44.584095 17337 hierarchical.cpp:1431] Performed allocation for 1 agents in 1.262029ms
3: I1201 02:45:44.584681 17336 master.cpp:8419] Sending 1 offers to framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 (default) at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.586227 17336 sched.cpp:921] Scheduler::resourceOffers took 1.028674ms
3: I1201 02:45:44.587608 17336 master.cpp:10370] Removing offer 395db372-c241-4fe4-b74c-e70984dfe8a3-O0
3: I1201 02:45:44.587752 17336 master.cpp:4236] Processing ACCEPT call for offers: [ 395db372-c241-4fe4-b74c-e70984dfe8a3-O0 ] on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 (default) at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.587854 17336 master.cpp:3605] Authorizing framework principal 'test-principal' to launch task 0
3: W1201 02:45:44.589829 17332 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: W1201 02:45:44.589941 17332 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: I1201 02:45:44.590425 17332 master.cpp:11130] Adding task 0 with resources cpus(allocated: *):1; mem(allocated: *):512 on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.590749 17332 master.cpp:4984] Launching task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 (default) at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.591437 17331 slave.cpp:1817] Got assigned task '0' for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.592123 17331 slave.cpp:2085] Authorizing task '0' for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.592180 17331 slave.cpp:7436] Authorizing framework principal 'test-principal' to launch task 0
3: I1201 02:45:44.593178 17325 slave.cpp:2253] Launching task '0' for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.593863 17325 paths.cpp:692] Trying to chown '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3' to user 'mesos'
3: I1201 02:45:44.594097 17323 hierarchical.cpp:1106] Recovered cpus(allocated: *):1; mem(allocated: *):512; disk(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):1; mem(allocated: *):512) on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 from framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.594158 17325 slave.cpp:7910] Launching executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 with resources [] in work directory '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3'
3: I1201 02:45:44.594174 17323 hierarchical.cpp:1152] Framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 filtered agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 for 5secs
3: I1201 02:45:44.594691 17325 slave.cpp:2944] Launching container d989f9d7-97fb-4ce7-a53c-0552d59da4d3 for executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.595034 17325 slave.cpp:2481] Queued task '0' for executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.595126 17325 slave.cpp:950] Successfully attached '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3' to virtual path '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/latest'
3: I1201 02:45:44.595171 17325 slave.cpp:950] Successfully attached '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3' to virtual path '/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/latest'
3: I1201 02:45:44.595232 17325 slave.cpp:950] Successfully attached '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3' to virtual path '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3'
3: W1201 02:45:44.597539 17337 process.cpp:2756] Attempted to spawn already running process version@172.17.0.4:43742
3: I1201 02:45:44.597718 17337 exec.cpp:162] Version: 1.5.0
3: I1201 02:45:44.598001 17328 exec.cpp:212] Executor started at: executor(10)@172.17.0.4:43742 with pid 17322
3: I1201 02:45:44.598206 17333 slave.cpp:4133] Got registration for executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 from executor(10)@172.17.0.4:43742
3: I1201 02:45:44.598695 17336 exec.cpp:237] Executor registered on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0
3: I1201 02:45:44.598770 17336 exec.cpp:249] Executor::registered took 50216ns
3: I1201 02:45:44.599100 17333 slave.cpp:2683] Sending queued task '0' to executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 at executor(10)@172.17.0.4:43742
3: I1201 02:45:44.599445 17338 exec.cpp:331] Executor asked to run task '0'
3: I1201 02:45:44.599550 17338 exec.cpp:340] Executor::launchTask took 81620ns
3: I1201 02:45:44.599664 17338 exec.cpp:571] Executor sending status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.599993 17331 slave.cpp:4587] Handling status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 from executor(10)@172.17.0.4:43742
3: I1201 02:45:44.601047 17334 task_status_update_manager.cpp:328] Received task status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.601109 17334 task_status_update_manager.cpp:507] Creating StatusUpdate stream for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.601627 17334 task_status_update_manager.cpp:383] Forwarding task status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 to the agent
3: I1201 02:45:44.601954 17334 slave.cpp:5070] Forwarding the update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 to master@172.17.0.4:43742
3: I1201 02:45:44.602130 17334 slave.cpp:4963] Task status update manager successfully handled status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.602177 17334 slave.cpp:4979] Sending acknowledgement for status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 to executor(10)@172.17.0.4:43742
3: I1201 02:45:44.602365 17334 exec.cpp:388] Executor received status update acknowledgement 7d5e5399-d73c-40cb-bdef-51c913bc9d80 for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.602494 17325 master.cpp:7557] Status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 from agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.602565 17325 master.cpp:7613] Forwarding status update TASK_FINISHED (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.602774 17325 master.cpp:9807] Updating the state of task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 (latest state: TASK_FINISHED, status update state: TASK_FINISHED)
3: I1201 02:45:44.603061 17326 sched.cpp:1029] Scheduler::statusUpdate took 88193ns
3: I1201 02:45:44.603459 17335 master.cpp:5799] Processing ACKNOWLEDGE call 7d5e5399-d73c-40cb-bdef-51c913bc9d80 for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 (default) at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742 on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0
3: I1201 02:45:44.603551 17335 master.cpp:9913] Removing task 0 with resources cpus(allocated: *):1; mem(allocated: *):512 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.603549 17328 hierarchical.cpp:1106] Recovered cpus(allocated: *):1; mem(allocated: *):512 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 from framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.603978 17327 task_status_update_manager.cpp:401] Received task status update acknowledgement (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.604089 17327 task_status_update_manager.cpp:538] Cleaning up status update stream for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.604457 17327 slave.cpp:3871] Task status update manager successfully handled status update acknowledgement (UUID: 7d5e5399-d73c-40cb-bdef-51c913bc9d80) for task 0 of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.604496 17327 slave.cpp:8515] Completing task 0
3: I1201 02:45:44.604910 17322 master.cpp:1159] Master terminating
3: I1201 02:45:44.605058 17322 master.cpp:9942] Removing executor 'default' with resources [] of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.605971 17337 hierarchical.cpp:586] Removed agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0
3: I1201 02:45:44.606082 17327 slave.cpp:5202] Got exited event for master@172.17.0.4:43742
3: W1201 02:45:44.606106 17327 slave.cpp:5207] Master disconnected! Waiting for a new master to be elected
3: I1201 02:45:44.606290 17337 hierarchical.cpp:345] Removed framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.616603 17322 cluster.cpp:170] Creating default 'local' authorizer
3: I1201 02:45:44.619850 17325 master.cpp:454] Master 34022412-d792-42a8-af9c-413e6b89011d (5dc74a8b2fd6) started on 172.17.0.4:43742
3: I1201 02:45:44.619910 17325 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/FeXBS7/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/FeXBS7/master" --zk_session_timeout="10secs"
3: I1201 02:45:44.620373 17325 master.cpp:505] Master only allowing authenticated frameworks to register
3: I1201 02:45:44.620411 17325 master.cpp:511] Master only allowing authenticated agents to register
3: I1201 02:45:44.620445 17325 master.cpp:517] Master only allowing authenticated HTTP frameworks to register
3: I1201 02:45:44.620477 17325 credentials.hpp:37] Loading credentials for authentication from '/tmp/FeXBS7/credentials'
3: I1201 02:45:44.620888 17325 master.cpp:561] Using default 'crammd5' authenticator
3: I1201 02:45:44.621155 17325 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1201 02:45:44.621368 17325 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1201 02:45:44.621526 17325 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1201 02:45:44.621680 17325 master.cpp:640] Authorization enabled
3: I1201 02:45:44.621969 17335 hierarchical.cpp:173] Initialized hierarchical allocator process
3: I1201 02:45:44.621984 17331 whitelist_watcher.cpp:77] No whitelist given
3: I1201 02:45:44.625002 17327 master.cpp:2221] Elected as the leading master!
3: I1201 02:45:44.625028 17327 master.cpp:1701] Recovering from registrar
3: I1201 02:45:44.625322 17324 registrar.cpp:347] Recovering registrar
3: I1201 02:45:44.625980 17324 registrar.cpp:391] Successfully fetched the registry (0B) in 568064ns
3: I1201 02:45:44.626129 17324 registrar.cpp:495] Applied 1 operations in 33618ns; attempting to update the registry
3: I1201 02:45:44.626734 17324 registrar.cpp:552] Successfully updated the registry in 518912ns
3: I1201 02:45:44.626883 17324 registrar.cpp:424] Successfully recovered registrar
3: I1201 02:45:44.627334 17337 master.cpp:1814] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1201 02:45:44.627398 17330 hierarchical.cpp:211] Skipping recovery of hierarchical allocator: nothing to recover
3: I1201 02:45:44.628278 17338 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1201 02:45:44.628288 17331 slave.cpp:999] New master detected at master@172.17.0.4:43742
3: I1201 02:45:44.628340 17331 slave.cpp:1034] Detecting new master
3: I1201 02:45:44.634207 17324 slave.cpp:1061] Authenticating with master master@172.17.0.4:43742
3: I1201 02:45:44.634300 17324 slave.cpp:1070] Using default CRAM-MD5 authenticatee
3: I1201 02:45:44.634651 17324 authenticatee.cpp:121] Creating new client SASL connection
3: I1201 02:45:44.634995 17324 master.cpp:8589] Authenticating slave(58)@172.17.0.4:43742
3: I1201 02:45:44.635185 17334 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(130)@172.17.0.4:43742
3: I1201 02:45:44.635512 17325 authenticator.cpp:98] Creating new server SASL connection
3: I1201 02:45:44.635920 17329 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 02:45:44.636008 17329 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 02:45:44.636232 17325 authenticator.cpp:204] Received SASL authentication start
3: I1201 02:45:44.636348 17325 authenticator.cpp:326] Authentication requires more steps
3: I1201 02:45:44.636512 17337 authenticatee.cpp:259] Received SASL authentication step
3: I1201 02:45:44.636628 17337 authenticator.cpp:232] Received SASL authentication step
3: I1201 02:45:44.636657 17337 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1201 02:45:44.636669 17337 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1201 02:45:44.636710 17337 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1201 02:45:44.636729 17337 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1201 02:45:44.636739 17337 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.636746 17337 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.636762 17337 authenticator.cpp:318] Authentication success
3: I1201 02:45:44.636909 17330 authenticatee.cpp:299] Authentication success
3: I1201 02:45:44.636977 17327 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(130)@172.17.0.4:43742
3: I1201 02:45:44.636945 17328 master.cpp:8619] Successfully authenticated principal 'test-principal' at slave(58)@172.17.0.4:43742
3: I1201 02:45:44.637331 17330 slave.cpp:1153] Successfully authenticated with master master@172.17.0.4:43742
3: I1201 02:45:44.637866 17330 slave.cpp:1696] Will retry registration in 12.465401ms if necessary
3: I1201 02:45:44.638366 17333 master.cpp:6371] Received re-register agent message from agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.638576 17333 master.cpp:3878] Authorizing agent with principal 'test-principal'
3: I1201 02:45:44.639155 17324 master.cpp:6442] Authorized re-registration of agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.639233 17324 master.cpp:6624] Re-registering agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: W1201 02:45:44.639813 17334 registry_operations.cpp:133] Allowing UNKNOWN agent to reregister: hostname: "5dc74a8b2fd6"
3: resources {
3:   name: "cpus"
3:   type: SCALAR
3:   scalar {
3:     value: 2
3:   }
3:   role: "*"
3: }
3: resources {
3:   name: "mem"
3:   type: SCALAR
3:   scalar {
3:     value: 1024
3:   }
3:   role: "*"
3: }
3: resources {
3:   name: "disk"
3:   type: SCALAR
3:   scalar {
3:     value: 1024
3:   }
3:   role: "*"
3: }
3: resources {
3:   name: "ports"
3:   type: RANGES
3:   ranges {
3:     range {
3:       begin: 31000
3:       end: 32000
3:     }
3:   }
3:   role: "*"
3: }
3: id {
3:   value: "395db372-c241-4fe4-b74c-e70984dfe8a3-S0"
3: }
3: checkpoint: true
3: port: 43742
3: I1201 02:45:44.640156 17334 registrar.cpp:495] Applied 1 operations in 382893ns; attempting to update the registry
3: I1201 02:45:44.640955 17334 registrar.cpp:552] Successfully updated the registry in 736us
3: I1201 02:45:44.641248 17328 master.cpp:6696] Re-admitted agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.642204 17328 master.cpp:6849] Re-registered agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1201 02:45:44.642343 17328 master.cpp:6912] Recovering framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 from re-registering agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6)
3: I1201 02:45:44.642421 17338 slave.cpp:1343] Re-registered with master master@172.17.0.4:43742
3: I1201 02:45:44.642546 17336 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1201 02:45:44.642562 17338 slave.cpp:1411] Forwarding total oversubscribed resources {}
3: I1201 02:45:44.642573 17331 hierarchical.cpp:553] Added agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 (5dc74a8b2fd6) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1201 02:45:44.642925 17331 hierarchical.cpp:1431] Performed allocation for 1 agents in 159029ns
3: I1201 02:45:44.643100 17328 master.cpp:7049] Received update of agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) with total oversubscribed resources {}
3: I1201 02:45:44.643383 17328 master.cpp:7092] Ignoring update on agent 395db372-c241-4fe4-b74c-e70984dfe8a3-S0 at slave(58)@172.17.0.4:43742 (5dc74a8b2fd6) as it reports no changes
3: I1201 02:45:44.643474 17331 hierarchical.cpp:293] Added framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.643512 17331 hierarchical.cpp:406] Deactivated framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.643971 17327 sched.cpp:330] Scheduler::disconnected took 17877ns
3: I1201 02:45:44.643988 17327 sched.cpp:336] New master detected at master@172.17.0.4:43742
3: I1201 02:45:44.644076 17327 sched.cpp:396] Authenticating with master master@172.17.0.4:43742
3: I1201 02:45:44.644093 17327 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1201 02:45:44.644352 17330 authenticatee.cpp:121] Creating new client SASL connection
3: I1201 02:45:44.644731 17338 master.cpp:8589] Authenticating scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.644824 17336 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(131)@172.17.0.4:43742
3: I1201 02:45:44.645198 17336 authenticator.cpp:98] Creating new server SASL connection
3: I1201 02:45:44.645468 17336 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1201 02:45:44.645491 17336 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1201 02:45:44.645615 17323 authenticator.cpp:204] Received SASL authentication start
3: I1201 02:45:44.645675 17323 authenticator.cpp:326] Authentication requires more steps
3: I1201 02:45:44.645776 17323 authenticatee.cpp:259] Received SASL authentication step
3: I1201 02:45:44.645884 17323 authenticator.cpp:232] Received SASL authentication step
3: I1201 02:45:44.645912 17323 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1201 02:45:44.645923 17323 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1201 02:45:44.645962 17323 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1201 02:45:44.645982 17323 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5dc74a8b2fd6' server FQDN: '5dc74a8b2fd6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1201 02:45:44.645990 17323 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.645998 17323 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1201 02:45:44.646013 17323 authenticator.cpp:318] Authentication success
3: I1201 02:45:44.646085 17328 authenticatee.cpp:299] Authentication success
3: I1201 02:45:44.646149 17331 master.cpp:8619] Successfully authenticated principal 'test-principal' at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.646221 17328 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(131)@172.17.0.4:43742
3: I1201 02:45:44.646502 17334 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:43742
3: I1201 02:45:44.646522 17334 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:43742
3: I1201 02:45:44.646631 17334 sched.cpp:857] Will retry registration in 633.790614ms if necessary
3: I1201 02:45:44.646838 17330 master.cpp:2969] Received SUBSCRIBE call for framework 'default' at scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.647001 17330 master.cpp:2286] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1201 02:45:44.647575 17332 master.cpp:3049] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1201 02:45:44.647630 17332 master.cpp:6950] Updating info for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.647882 17333 hierarchical.cpp:372] Activated framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.648028 17323 sched.cpp:751] Framework registered with 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
3: I1201 02:45:44.648094 17323 sched.cpp:765] Scheduler::registered took 44906ns
3: I1201 02:45:44.648123 17332 slave.cpp:3452] Updating info for framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 with pid updated to scheduler-50372b46-3a04-4dc9-9ee5-0b262e3a08e4@172.17.0.4:43742
3: I1201 02:45:44.648490 17331 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1201 02:45:44.648952 17330 slave.cpp:5202] Got exited event for executor(10)@172.17.0.4:43742
3: I1201 02:45:44.649173 17338 slave.cpp:5611] Executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 exited with status 0
3: I1201 02:45:44.649273 17338 slave.cpp:5715] Cleaning up executor 'default' of framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000 at executor(10)@172.17.0.4:43742
3: I1201 02:45:44.649456 17333 hierarchical.cpp:1431] Performed allocation for 1 agents in 1.434638ms
3: I1201 02:45:44.649621 17325 gc.cpp:90] Scheduling '/tmp/FaultToleranceTest_FrameworkReregisterEmptyExecutor_DYbHnh/slaves/395db372-c241-4fe4-b74c-e70984dfe8a3-S0/frameworks/395db372-c241-4fe4-b74c-e70984dfe8a3-0000/executors/default/runs/d989f9d7-97fb-4ce7-a53c-0552d59da4d3' for gc 6.99999248344889days in the future
3: I1201 02:45:44.649691 17338 slave.cpp:5822] Cleaning up framework 395db372-c241-4fe4-b74c-e70984dfe8a3-0000
write /dev/stdout: resource temporarily unavailable
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Not sending mail to unregistered user songzihao1990@gmail.com

Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4524

[Apache Jenkins Server <je...@builds.apache.org>]

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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4524/display/redirect?page=changes>