You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/10/11 02:44:26 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1436
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 master@67.195.138.8: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 master@67.195.138.8: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] master@67.195.138.8: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 (master@67.195.138.8: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 : master@67.195.138.8: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: master@67.195.138.8:44353
I1011 00:44:25.086176 9217 slave.cpp:547] New master detected at master@67.195.138.8:44353
I1011 00:44:25.086288 9217 status_update_manager.cpp:157] New master detected at master@67.195.138.8: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 (master@67.195.138.8: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: master@67.195.138.8:44353
I1011 00:44:25.086993 9219 sched.cpp:195] New master at master@67.195.138.8:44353
I1011 00:44:25.087139 9219 sched.cpp:281] Authenticating with master master@67.195.138.8: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 (master@67.195.138.8:44353) got new master pid: master@67.195.138.8: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 master@67.195.138.8: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 master@67.195.138.8: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 master@67.195.138.8: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] master@67.195.138.8: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 (master@67.195.138.8: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 : master@67.195.138.8: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 (master@67.195.138.8: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: master@67.195.138.8:44353
I1011 00:44:26.125960 9217 detector.cpp:467] Master detector (master@67.195.138.8:44353) got new master pid: master@67.195.138.8:44353
I1011 00:44:26.126039 9216 sched.cpp:195] New master at master@67.195.138.8:44353
I1011 00:44:26.126257 9216 sched.cpp:281] Authenticating with master master@67.195.138.8: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: master@67.195.138.8:44353
I1011 00:44:26.126690 9212 slave.cpp:547] New master detected at master@67.195.138.8:44353
I1011 00:44:26.126871 9216 status_update_manager.cpp:157] New master detected at master@67.195.138.8: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 master@67.195.138.8: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 master@67.195.138.8: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] master@67.195.138.8: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