See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1436/changes>
Changes: [vinod] Changed Authenticatee to embed pid in AuthenticateMessage. [vinod] Added authentication support to scheduler and master. [vinod] Improved MesosSchedulerDriver initialize() method to properly handle [vinod] Updated all tests to do authentication. [vinod] Added authentication tests. ------------------------------------------ [...truncated 20270 lines...] I1011 00:44:24.073735 9212 sched.cpp:326] Successfully authenticated with master [email protected]:44353 I1011 00:44:24.073930 9214 master.cpp:867] Re-registering framework 201310110044-143311683-44353-9187-0000 at scheduler(127)@67.195.138.8:44353 I1011 00:44:24.074182 9215 sched.cpp:365] Framework registered with 201310110044-143311683-44353-9187-0000 I1011 00:44:24.074257 9214 hierarchical_allocator_process.hpp:332] Added framework 201310110044-143311683-44353-9187-0000 I1011 00:44:24.074337 9214 hierarchical_allocator_process.hpp:726] No resources available to allocate! I1011 00:44:24.074456 9214 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 119.299us I1011 00:44:24.074254 9215 sched.cpp:379] Scheduler::registered took 18.297us I1011 00:44:25.039746 9214 master.cpp:1344] Attempting to re-register slave 201310110044-143311683-44353-9187-0 at slave(134)@67.195.138.8:44353 (minerva.apache.org) I1011 00:44:25.039870 9214 master.cpp:2448] Adding slave 201310110044-143311683-44353-9187-0 at minerva.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1011 00:44:25.040103 9219 slave.cpp:660] Re-registered with master [email protected]:44353 I1011 00:44:25.040141 9214 master.hpp:357] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:25.040511 9216 slave.cpp:1352] Updating framework 201310110044-143311683-44353-9187-0000 pid to scheduler(127)@67.195.138.8:44353 I1011 00:44:25.040689 9217 hierarchical_allocator_process.hpp:445] Added slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available) I1011 00:44:25.040833 9217 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.041031 9217 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310110044-143311683-44353-9187-0 in 227.648us I1011 00:44:25.041126 9214 master.hpp:385] Adding offer 201310110044-143311683-44353-9187-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:25.041266 9214 master.cpp:1635] Sending 1 offers to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.041498 9219 sched.cpp:472] Scheduler::resourceOffers took 27.219us I1011 00:44:25.041718 9214 sched.cpp:630] Stopping framework '201310110044-143311683-44353-9187-0000' I1011 00:44:25.041733 9187 master.cpp:552] Master terminating I1011 00:44:25.041946 9187 master.cpp:209] Shutting down master I1011 00:44:25.041985 9218 slave.cpp:1993] [email protected]:44353 exited I1011 00:44:25.042085 9187 master.hpp:375] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) W1011 00:44:25.042112 9218 slave.cpp:1996] Master disconnected! Waiting for a new master to be elected I1011 00:44:25.042268 9187 master.hpp:395] Removing offer 201310110044-143311683-44353-9187-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:25.042387 9217 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201310110044-143311683-44353-9187-0 from framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.042471 9187 master.cpp:252] Removing slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:25.043258 9187 slave.cpp:454] Slave terminating I1011 00:44:25.043366 9187 slave.cpp:1184] Asked to shut down framework 201310110044-143311683-44353-9187-0000 by @0.0.0.0:0 I1011 00:44:25.044282 9218 detector.cpp:420] Master detector (slave(134)@67.195.138.8:44353) found 0 registered masters I1011 00:44:25.049018 9216 monitor.cpp:187] Publishing resource usage for executor 'default' of framework '201310110044-143311683-44353-9187-0000' I1011 00:44:25.049870 9187 slave.cpp:1209] Shutting down framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.050220 9187 slave.cpp:2446] Shutting down executor 'default' of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.049983 9218 detector.cpp:441] Master detector (slave(134)@67.195.138.8:44353) couldn't find any masters I1011 00:44:25.050384 9219 exec.cpp:371] Executor asked to shutdown GMOCK WARNING: Uninteresting mock function call - returning directly. Function call: shutdown(0x2aeb8401c5f0) Stack trace: I1011 00:44:25.050525 9219 exec.cpp:386] Executor::shutdown took 30.157us [ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1032 ms) [ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst I1011 00:44:25.065882 9214 master.cpp:284] Master started on 67.195.138.8:44353 2013-10-11 00:44:25,071:9187(0x2aeb644e5700):ZOO_INFO@log_env@658: Client environment:zookeeper.version=zookeeper C client 3.3.4 2013-10-11 00:44:25,071:9187(0x2aeb644e5700):ZOO_INFO@log_env@662: Client environment:host.name=minerva 2013-10-11 00:44:25,071:9187(0x2aeb644e5700):ZOO_INFO@log_env@669: Client environment:os.name=Linux 2013-10-11 00:44:25,071:9187(0x2aeb644e5700):ZOO_INFO@log_env@670: Client environment:os.arch=3.2.0-51-generic 2013-10-11 00:44:25,071:9187(0x2aeb644e5700):ZOO_INFO@log_env@671: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013 I1011 00:44:25.066123 9214 master.cpp:299] Master ID: 201310110044-143311683-44353-9187 I1011 00:44:25.067579 9215 detector.cpp:234] Master detector ([email protected]:44353) connected to ZooKeeper ... I1011 00:44:25.071461 9212 slave.cpp:108] Slave started on 135)@67.195.138.8:44353 I1011 00:44:25.073345 9217 detector.cpp:234] Master detector (slave(135)@67.195.138.8:44353) connected to ZooKeeper ... I1011 00:44:25.073978 9219 detector.cpp:234] Master detector (scheduler(128)@67.195.138.8:44353) connected to ZooKeeper ... I1011 00:44:25.077955 9214 master.cpp:302] Master only allowing authenticated frameworks to register! I1011 00:44:25.078099 9215 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I1011 00:44:25.078361 9212 slave.cpp:208] Slave resources: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1011 00:44:25.078474 9217 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I1011 00:44:25.078587 9219 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I1011 00:44:25.078984 9213 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : [email protected]:44353 I1011 00:44:25.079120 9216 master.cpp:85] No whitelist given. Advertising offers for all slaves I1011 00:44:25.079704 9214 slave.cpp:399] Finished recovery I1011 00:44:25.081841 9217 detector.cpp:420] Master detector (slave(135)@67.195.138.8:44353) found 1 registered masters I1011 00:44:25.081882 9215 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000004' I1011 00:44:25.082391 9219 detector.cpp:420] Master detector (scheduler(128)@67.195.138.8:44353) found 1 registered masters I1011 00:44:25.082568 9217 detector.cpp:467] Master detector (slave(135)@67.195.138.8:44353) got new master pid: [email protected]:44353 I1011 00:44:25.086176 9217 slave.cpp:547] New master detected at [email protected]:44353 I1011 00:44:25.086288 9217 status_update_manager.cpp:157] New master detected at [email protected]:44353 W1011 00:44:25.086356 9213 master.cpp:1212] Ignoring register slave message from minerva.apache.org since not elected yet I1011 00:44:25.086520 9215 detector.cpp:420] Master detector ([email protected]:44353) found 1 registered masters I1011 00:44:25.086796 9219 detector.cpp:467] Master detector (scheduler(128)@67.195.138.8:44353) got new master pid: [email protected]:44353 I1011 00:44:25.086993 9219 sched.cpp:195] New master at [email protected]:44353 I1011 00:44:25.087139 9219 sched.cpp:281] Authenticating with master [email protected]:44353 I1011 00:44:25.087386 9213 authenticatee.hpp:124] Creating new client SASL connection I1011 00:44:25.087496 9215 detector.cpp:467] Master detector ([email protected]:44353) got new master pid: [email protected]:44353 I1011 00:44:25.087674 9215 master.cpp:692] Elected as master! I1011 00:44:25.087805 9215 master.cpp:1669] Authenticating framework at scheduler(128)@67.195.138.8:44353 I1011 00:44:25.087991 9213 authenticator.hpp:140] Creating new server SASL connection I1011 00:44:25.088207 9215 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1011 00:44:25.088273 9215 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1011 00:44:25.088382 9215 authenticator.hpp:243] Received SASL authentication start I1011 00:44:25.088543 9215 authenticator.hpp:325] Authentication requires more steps I1011 00:44:25.088628 9215 authenticatee.hpp:258] Received SASL authentication step I1011 00:44:25.088740 9215 authenticator.hpp:271] Received SASL authentication step I1011 00:44:25.088819 9215 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1011 00:44:25.088907 9215 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1011 00:44:25.088978 9215 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1011 00:44:25.089051 9215 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1011 00:44:25.089130 9215 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1011 00:44:25.089229 9215 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1011 00:44:25.089308 9215 authenticator.hpp:317] Authentication success I1011 00:44:25.089400 9215 authenticatee.hpp:298] Authentication success I1011 00:44:25.089504 9215 master.cpp:1709] Successfully authenticated framework at scheduler(128)@67.195.138.8:44353 I1011 00:44:25.089620 9214 sched.cpp:326] Successfully authenticated with master [email protected]:44353 I1011 00:44:25.089889 9212 master.cpp:763] Received registration request from scheduler(128)@67.195.138.8:44353 I1011 00:44:25.090029 9212 master.cpp:781] Registering framework 201310110044-143311683-44353-9187-0000 at scheduler(128)@67.195.138.8:44353 I1011 00:44:25.090164 9213 sched.cpp:365] Framework registered with 201310110044-143311683-44353-9187-0000 I1011 00:44:25.090168 9212 hierarchical_allocator_process.hpp:332] Added framework 201310110044-143311683-44353-9187-0000 I1011 00:44:25.090286 9213 sched.cpp:379] Scheduler::registered took 18.152us I1011 00:44:25.090380 9212 hierarchical_allocator_process.hpp:726] No resources available to allocate! I1011 00:44:25.090584 9212 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 203.211us I1011 00:44:26.079877 9217 hierarchical_allocator_process.hpp:726] No resources available to allocate! I1011 00:44:26.080068 9217 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 207.388us I1011 00:44:26.087143 9212 master.cpp:1243] Attempting to register slave on minerva.apache.org at slave(135)@67.195.138.8:44353 I1011 00:44:26.087276 9212 master.cpp:2448] Adding slave 201310110044-143311683-44353-9187-0 at minerva.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1011 00:44:26.087463 9217 slave.cpp:613] Registered with master [email protected]:44353; given slave ID 201310110044-143311683-44353-9187-0 I1011 00:44:26.087602 9212 hierarchical_allocator_process.hpp:445] Added slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available) I1011 00:44:26.087774 9212 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.087941 9212 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310110044-143311683-44353-9187-0 in 191.855us I1011 00:44:26.088047 9217 master.hpp:385] Adding offer 201310110044-143311683-44353-9187-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.088212 9217 master.cpp:1635] Sending 1 offers to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.088667 9216 sched.cpp:472] Scheduler::resourceOffers took 172.263us I1011 00:44:26.088870 9217 master.cpp:1961] Processing reply for offer 201310110044-143311683-44353-9187-0 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) for framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.089058 9217 master.hpp:357] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.089215 9217 master.cpp:2085] Launching task 0 of framework 201310110044-143311683-44353-9187-0000 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.089373 9216 slave.cpp:786] Got assigned task 0 for framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.089450 9217 master.hpp:395] Removing offer 201310110044-143311683-44353-9187-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.089455 9214 hierarchical_allocator_process.hpp:547] Framework 201310110044-143311683-44353-9187-0000 left cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] unused on slave 201310110044-143311683-44353-9187-0 I1011 00:44:26.089776 9216 slave.cpp:897] Launching task 0 for framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.089897 9214 hierarchical_allocator_process.hpp:590] Framework 201310110044-143311683-44353-9187-0000 filtered slave 201310110044-143311683-44353-9187-0 for 5secs I1011 00:44:26.091442 9216 slave.cpp:1008] Queuing task '0' for executor default of framework '201310110044-143311683-44353-9187-0000 I1011 00:44:26.091673 9216 slave.cpp:529] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_fIOybi/slaves/201310110044-143311683-44353-9187-0/frameworks/201310110044-143311683-44353-9187-0000/executors/default/runs/6000d8f3-d247-47c8-a689-20bf1a034ad6' I1011 00:44:26.092815 9215 exec.cpp:174] Executor started at: executor(42)@67.195.138.8:44353 with pid 9187 I1011 00:44:26.092984 9213 slave.cpp:1460] Got registration for executor 'default' of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.093235 9213 slave.cpp:1581] Flushing queued task 0 for executor 'default' of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.093257 9219 exec.cpp:198] Executor registered on slave 201310110044-143311683-44353-9187-0 I1011 00:44:26.094668 9219 exec.cpp:210] Executor::registered took 15.223us I1011 00:44:26.094841 9219 exec.cpp:285] Executor asked to run task '0' I1011 00:44:26.094974 9219 exec.cpp:294] Executor::launchTask took 20.958us I1011 00:44:26.096218 9219 exec.cpp:501] Executor sending status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.096379 9219 slave.cpp:1793] Handling status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 from executor(42)@67.195.138.8:44353 I1011 00:44:26.096564 9214 status_update_manager.cpp:300] Received status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.096693 9214 status_update_manager.cpp:471] Creating StatusUpdate stream for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.096833 9214 status_update_manager.cpp:351] Forwarding status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 to [email protected]:44353 I1011 00:44:26.097071 9219 master.cpp:1443] Status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 from slave(135)@67.195.138.8:44353 I1011 00:44:26.097084 9217 slave.cpp:1912] Status update manager successfully handled status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.097152 9213 sched.cpp:527] Scheduler::statusUpdate took 33.763us I1011 00:44:26.097328 9217 slave.cpp:1918] Sending acknowledgement for status update TASK_RUNNING (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 to executor(42)@67.195.138.8:44353 I1011 00:44:26.097434 9187 master.cpp:552] Master terminating I1011 00:44:26.097673 9187 master.cpp:209] Shutting down master I1011 00:44:26.097702 9218 exec.cpp:331] Executor received status update acknowledgement 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.097825 9187 master.hpp:375] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.097955 9217 slave.cpp:1993] [email protected]:44353 exited I1011 00:44:26.097982 9187 master.cpp:252] Removing slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.098094 9212 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 201310110044-143311683-44353-9187-0 from framework 201310110044-143311683-44353-9187-0000 W1011 00:44:26.098151 9217 slave.cpp:1996] Master disconnected! Waiting for a new master to be elected I1011 00:44:26.098165 9219 status_update_manager.cpp:375] Received status update acknowledgement (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.098902 9213 slave.cpp:1406] Status update manager successfully handled status update acknowledgement (UUID: 9a4c4aa0-1e98-43c2-9ee7-d91e265a55ed) for task 0 of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.100461 9219 detector.cpp:420] Master detector (scheduler(128)@67.195.138.8:44353) found 0 registered masters I1011 00:44:26.100574 9219 detector.cpp:441] Master detector (scheduler(128)@67.195.138.8:44353) couldn't find any masters I1011 00:44:26.100637 9212 detector.cpp:420] Master detector (slave(135)@67.195.138.8:44353) found 0 registered masters I1011 00:44:26.104801 9216 master.cpp:284] Master started on 67.195.138.8:44353 I1011 00:44:26.105913 9219 sched.cpp:235] No master detected, waiting for another master I1011 00:44:26.105978 9212 detector.cpp:441] Master detector (slave(135)@67.195.138.8:44353) couldn't find any masters I1011 00:44:26.106683 9217 detector.cpp:234] Master detector ([email protected]:44353) connected to ZooKeeper ... I1011 00:44:26.113872 9216 master.cpp:299] Master ID: 201310110044-143311683-44353-9187 I1011 00:44:26.114032 9219 sched.cpp:247] Scheduler::disconnected took 13.455us I1011 00:44:26.114188 9212 slave.cpp:581] Lost master(s) ... waiting I1011 00:44:26.114307 9217 detector.cpp:251] Trying to create path '/znode' in ZooKeeper I1011 00:44:26.114423 9216 master.cpp:302] Master only allowing authenticated frameworks to register! I1011 00:44:26.115290 9216 master.cpp:85] No whitelist given. Advertising offers for all slaves I1011 00:44:26.115502 9216 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : [email protected]:44353 I1011 00:44:26.117435 9217 detector.cpp:281] Created ephemeral/sequence znode at '/znode/0000000006' I1011 00:44:26.117897 9216 detector.cpp:420] Master detector (scheduler(128)@67.195.138.8:44353) found 1 registered masters I1011 00:44:26.117984 9212 detector.cpp:420] Master detector (slave(135)@67.195.138.8:44353) found 1 registered masters I1011 00:44:26.118178 9217 detector.cpp:420] Master detector ([email protected]:44353) found 1 registered masters I1011 00:44:26.125777 9216 detector.cpp:467] Master detector (scheduler(128)@67.195.138.8:44353) got new master pid: [email protected]:44353 I1011 00:44:26.125960 9217 detector.cpp:467] Master detector ([email protected]:44353) got new master pid: [email protected]:44353 I1011 00:44:26.126039 9216 sched.cpp:195] New master at [email protected]:44353 I1011 00:44:26.126257 9216 sched.cpp:281] Authenticating with master [email protected]:44353 I1011 00:44:26.126276 9219 master.cpp:692] Elected as master! I1011 00:44:26.125854 9212 detector.cpp:467] Master detector (slave(135)@67.195.138.8:44353) got new master pid: [email protected]:44353 I1011 00:44:26.126690 9212 slave.cpp:547] New master detected at [email protected]:44353 I1011 00:44:26.126871 9216 status_update_manager.cpp:157] New master detected at [email protected]:44353 I1011 00:44:26.126478 9217 authenticatee.hpp:124] Creating new client SASL connection I1011 00:44:26.127012 9219 master.cpp:1344] Attempting to re-register slave 201310110044-143311683-44353-9187-0 at slave(135)@67.195.138.8:44353 (minerva.apache.org) I1011 00:44:26.127224 9219 master.cpp:2448] Adding slave 201310110044-143311683-44353-9187-0 at minerva.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] I1011 00:44:26.127387 9213 slave.cpp:660] Re-registered with master [email protected]:44353 I1011 00:44:26.127387 9219 master.hpp:357] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) W1011 00:44:26.127593 9219 master.cpp:2543] Possibly orphaned task 0 of framework 201310110044-143311683-44353-9187-0000 running on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.127814 9219 master.cpp:1669] Authenticating framework at scheduler(128)@67.195.138.8:44353 I1011 00:44:26.127949 9213 hierarchical_allocator_process.hpp:445] Added slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available) I1011 00:44:26.128161 9216 authenticator.hpp:140] Creating new server SASL connection I1011 00:44:26.128401 9218 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5 I1011 00:44:26.128402 9213 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310110044-143311683-44353-9187-0 in 10.829us I1011 00:44:26.128476 9218 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5' I1011 00:44:26.128656 9218 authenticator.hpp:243] Received SASL authentication start I1011 00:44:26.128793 9218 authenticator.hpp:325] Authentication requires more steps I1011 00:44:26.128868 9218 authenticatee.hpp:258] Received SASL authentication step I1011 00:44:26.128981 9218 authenticator.hpp:271] Received SASL authentication step I1011 00:44:26.129066 9218 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1011 00:44:26.129142 9218 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1011 00:44:26.129230 9218 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1011 00:44:26.129292 9218 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'minerva.apache.org' server FQDN: 'minerva.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1011 00:44:26.129375 9218 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1011 00:44:26.129451 9218 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1011 00:44:26.129523 9218 authenticator.hpp:317] Authentication success I1011 00:44:26.129652 9216 authenticatee.hpp:298] Authentication success I1011 00:44:26.129669 9217 master.cpp:1709] Successfully authenticated framework at scheduler(128)@67.195.138.8:44353 I1011 00:44:26.129823 9218 sched.cpp:326] Successfully authenticated with master [email protected]:44353 I1011 00:44:26.130020 9217 master.cpp:867] Re-registering framework 201310110044-143311683-44353-9187-0000 at scheduler(128)@67.195.138.8:44353 I1011 00:44:26.130261 9216 sched.cpp:365] Framework registered with 201310110044-143311683-44353-9187-0000 I1011 00:44:26.130332 9217 slave.cpp:1352] Updating framework 201310110044-143311683-44353-9187-0000 pid to scheduler(128)@67.195.138.8:44353 I1011 00:44:26.130334 9216 sched.cpp:379] Scheduler::registered took 10.744us I1011 00:44:26.130430 9215 hierarchical_allocator_process.hpp:332] Added framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.130637 9215 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.130833 9215 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 234.447us I1011 00:44:26.130899 9218 master.hpp:385] Adding offer 201310110044-143311683-44353-9187-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.131074 9218 master.cpp:1635] Sending 1 offers to framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.131268 9214 sched.cpp:472] Scheduler::resourceOffers took 20.359us I1011 00:44:26.131510 9212 sched.cpp:630] Stopping framework '201310110044-143311683-44353-9187-0000' I1011 00:44:26.131528 9213 master.cpp:552] Master terminating I1011 00:44:26.131731 9218 slave.cpp:1993] [email protected]:44353 exited I1011 00:44:26.131757 9187 master.cpp:209] Shutting down master W1011 00:44:26.131831 9218 slave.cpp:1996] Master disconnected! Waiting for a new master to be elected I1011 00:44:26.131974 9187 master.hpp:375] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.132346 9187 master.hpp:395] Removing offer 201310110044-143311683-44353-9187-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.132398 9212 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 201310110044-143311683-44353-9187-0 from framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.132529 9187 master.cpp:252] Removing slave 201310110044-143311683-44353-9187-0 (minerva.apache.org) I1011 00:44:26.138576 9187 slave.cpp:454] Slave terminating I1011 00:44:26.138672 9187 slave.cpp:1184] Asked to shut down framework 201310110044-143311683-44353-9187-0000 by @0.0.0.0:0 I1011 00:44:26.138773 9187 slave.cpp:1209] Shutting down framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.138895 9187 slave.cpp:2446] Shutting down executor 'default' of framework 201310110044-143311683-44353-9187-0000 I1011 00:44:26.139236 9212 exec.cpp:371] Executor asked to shutdown GMOCK WARNING: Uninteresting mock function call - returning directly. Function call: shutdown(0x2aeb7c025d70) Stack trace: I1011 00:44:26.145923 9212 exec.cpp:386] Executor::shutdown took 40.222us [ OK ] AllocatorZooKeeperTest/0.SlaveReregistersFirst (1095 ms) I1011 00:44:26.147429 9187 zookeeper_test_server.cpp:93] Shutdown ZooKeeperTestServer on port 50296 [----------] 2 tests from AllocatorZooKeeperTest/0 (2127 ms total) [----------] Global test environment tear-down [==========] 235 tests from 41 test cases ran. (101915 ms total) [ PASSED ] 234 tests. [ FAILED ] 1 test, listed below: [ FAILED ] GroupTest.MultipleGroups 1 FAILED TEST make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'> make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure
