See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2165/changes>
Changes:
[adam] Enabled whitespace/semicolon rule for cpplint.
------------------------------------------
[...truncated 14642 lines...]
I1014 06:10:07.862639 25664 status_update_manager.cpp:167] New master detected
at master@67.195.81.187:33243
I1014 06:10:07.862673 25671 slave.cpp:647] Detecting new master
I1014 06:10:07.862689 25663 authenticatee.hpp:133] Creating new client SASL
connection
I1014 06:10:07.862814 25669 master.cpp:3787] Authenticating
slave(48)@67.195.81.187:33243
I1014 06:10:07.862895 25663 authenticator.hpp:161] Creating new server SASL
connection
I1014 06:10:07.862977 25657 authenticatee.hpp:224] Received SASL authentication
mechanisms: CRAM-MD5
I1014 06:10:07.862997 25657 authenticatee.hpp:250] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1014 06:10:07.863054 25660 authenticator.hpp:267] Received SASL authentication
start
I1014 06:10:07.863095 25660 authenticator.hpp:389] Authentication requires more
steps
I1014 06:10:07.863138 25660 authenticatee.hpp:270] Received SASL authentication
step
I1014 06:10:07.863205 25658 authenticator.hpp:295] Received SASL authentication
step
I1014 06:10:07.863230 25658 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1014 06:10:07.863239 25658 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I1014 06:10:07.863248 25658 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1014 06:10:07.863258 25658 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1014 06:10:07.863265 25658 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.863270 25658 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.863301 25658 authenticator.hpp:381] Authentication success
I1014 06:10:07.863358 25663 authenticatee.hpp:310] Authentication success
I1014 06:10:07.863401 25658 master.cpp:3827] Successfully authenticated
principal 'test-principal' at slave(48)@67.195.81.187:33243
I1014 06:10:07.863495 25663 slave.cpp:731] Successfully authenticated with
master master@67.195.81.187:33243
I1014 06:10:07.863564 25663 slave.cpp:1048] Will retry registration in
6.990899ms if necessary
I1014 06:10:07.863620 25671 master.cpp:2968] Registering slave at
slave(48)@67.195.81.187:33243 (pomona.apache.org) with id
20141014-061007-3142697795-33243-25643-1
I1014 06:10:07.863755 25671 registrar.cpp:445] Applied 1 operations in 16652ns;
attempting to update the 'registry'
I1014 06:10:07.865200 25673 log.cpp:680] Attempting to append 487 bytes to the
log
I1014 06:10:07.865267 25663 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 5
I1014 06:10:07.865694 25660 replica.cpp:508] Replica received write request for
position 5
I1014 06:10:07.865845 25660 leveldb.cpp:343] Persisting action (506 bytes) to
leveldb took 128557ns
I1014 06:10:07.865862 25660 replica.cpp:676] Persisted action at 5
I1014 06:10:07.866153 25665 replica.cpp:655] Replica received learned notice
for position 5
I1014 06:10:07.866665 25665 leveldb.cpp:343] Persisting action (508 bytes) to
leveldb took 490577ns
I1014 06:10:07.866682 25665 replica.cpp:676] Persisted action at 5
I1014 06:10:07.866688 25665 replica.cpp:661] Replica learned APPEND action at
position 5
I1014 06:10:07.867045 25673 registrar.cpp:490] Successfully updated the
'registry' in 3.266816ms
I1014 06:10:07.867125 25668 log.cpp:699] Attempting to truncate the log to 5
I1014 06:10:07.867202 25667 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 6
I1014 06:10:07.867260 25672 slave.cpp:2403] Received ping from
slave-observer(42)@67.195.81.187:33243
I1014 06:10:07.867429 25672 slave.cpp:765] Registered with master
master@67.195.81.187:33243; given slave ID
20141014-061007-3142697795-33243-25643-1
I1014 06:10:07.867254 25671 master.cpp:3022] Registered slave
20141014-061007-3142697795-33243-25643-1 at slave(48)@67.195.81.187:33243
(pomona.apache.org) with cpus(*):1; mem(*):512; disk(*):0;
ports(*):[31000-32000]
I1014 06:10:07.867511 25660 hierarchical_allocator_process.hpp:442] Added slave
20141014-061007-3142697795-33243-25643-1 (pomona.apache.org) with cpus(*):1;
mem(*):512; disk(*):0; ports(*):[31000-32000] (and cpus(*):1; mem(*):512;
disk(*):0; ports(*):[31000-32000] available)
I1014 06:10:07.867588 25660 hierarchical_allocator_process.hpp:734] Offering
cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] on slave
20141014-061007-3142697795-33243-25643-1 to framework
20141014-061007-3142697795-33243-25643-0001
I1014 06:10:07.867636 25673 replica.cpp:508] Replica received write request for
position 6
I1014 06:10:07.867737 25660 hierarchical_allocator_process.hpp:679] Performed
allocation for slave 20141014-061007-3142697795-33243-25643-1 in 185448ns
I1014 06:10:07.867910 25657 master.cpp:3729] Sending 1 offers to framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243
I1014 06:10:07.868041 25657 sched.cpp:544] Scheduler::resourceOffers took
33378ns
I1014 06:10:07.868392 25673 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 733801ns
I1014 06:10:07.868407 25673 replica.cpp:676] Persisted action at 6
I1014 06:10:07.868722 25672 replica.cpp:655] Replica received learned notice
for position 6
I1014 06:10:07.868820 25643 containerizer.cpp:89] Using isolation:
posix/cpu,posix/mem
I1014 06:10:07.869496 25672 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 754116ns
I1014 06:10:07.869542 25672 leveldb.cpp:401] Deleting ~2 keys from leveldb took
25761ns
I1014 06:10:07.869554 25672 replica.cpp:676] Persisted action at 6
I1014 06:10:07.869561 25672 replica.cpp:661] Replica learned TRUNCATE action at
position 6
I1014 06:10:07.870647 25658 slave.cpp:169] Slave started on
49)@67.195.81.187:33243
I1014 06:10:07.870676 25658 credentials.hpp:84] Loading credential for
authentication from
'/tmp/DRFAllocatorTest_DRFAllocatorProcess_Ea7CAM/credential'
I1014 06:10:07.870756 25658 slave.cpp:276] Slave using credential for:
test-principal
I1014 06:10:07.870833 25658 slave.cpp:289] Slave resources: cpus(*):3;
mem(*):2048; disk(*):0; ports(*):[31000-32000]
I1014 06:10:07.870888 25658 slave.cpp:318] Slave hostname: pomona.apache.org
I1014 06:10:07.870898 25658 slave.cpp:319] Slave checkpoint: false
I1014 06:10:07.871273 25666 state.cpp:33] Recovering state from
'/tmp/DRFAllocatorTest_DRFAllocatorProcess_Ea7CAM/meta'
I1014 06:10:07.871422 25664 status_update_manager.cpp:193] Recovering status
update manager
I1014 06:10:07.871543 25671 containerizer.cpp:252] Recovering containerizer
I1014 06:10:07.871875 25670 slave.cpp:3329] Finished recovery
I1014 06:10:07.872124 25670 slave.cpp:600] New master detected at
master@67.195.81.187:33243
I1014 06:10:07.872158 25670 slave.cpp:674] Authenticating with master
master@67.195.81.187:33243
I1014 06:10:07.872180 25662 status_update_manager.cpp:167] New master detected
at master@67.195.81.187:33243
I1014 06:10:07.872231 25670 slave.cpp:647] Detecting new master
I1014 06:10:07.872246 25657 authenticatee.hpp:133] Creating new client SASL
connection
I1014 06:10:07.872390 25664 master.cpp:3787] Authenticating
slave(49)@67.195.81.187:33243
I1014 06:10:07.872462 25665 authenticator.hpp:161] Creating new server SASL
connection
I1014 06:10:07.872531 25657 authenticatee.hpp:224] Received SASL authentication
mechanisms: CRAM-MD5
I1014 06:10:07.872555 25657 authenticatee.hpp:250] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1014 06:10:07.872603 25666 authenticator.hpp:267] Received SASL authentication
start
I1014 06:10:07.872644 25666 authenticator.hpp:389] Authentication requires more
steps
I1014 06:10:07.872680 25666 authenticatee.hpp:270] Received SASL authentication
step
I1014 06:10:07.872740 25659 authenticator.hpp:295] Received SASL authentication
step
I1014 06:10:07.872762 25659 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1014 06:10:07.872769 25659 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I1014 06:10:07.872777 25659 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1014 06:10:07.872786 25659 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1014 06:10:07.872792 25659 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.872797 25659 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.872807 25659 authenticator.hpp:381] Authentication success
I1014 06:10:07.872850 25673 authenticatee.hpp:310] Authentication success
I1014 06:10:07.872872 25659 master.cpp:3827] Successfully authenticated
principal 'test-principal' at slave(49)@67.195.81.187:33243
I1014 06:10:07.872970 25660 slave.cpp:731] Successfully authenticated with
master master@67.195.81.187:33243
I1014 06:10:07.873011 25660 slave.cpp:1048] Will retry registration in
13.715279ms if necessary
I1014 06:10:07.873077 25658 master.cpp:2968] Registering slave at
slave(49)@67.195.81.187:33243 (pomona.apache.org) with id
20141014-061007-3142697795-33243-25643-2
I1014 06:10:07.873190 25673 registrar.cpp:445] Applied 1 operations in 18805ns;
attempting to update the 'registry'
I1014 06:10:07.874595 25665 log.cpp:680] Attempting to append 659 bytes to the
log
I1014 06:10:07.874661 25657 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 7
I1014 06:10:07.875054 25659 replica.cpp:508] Replica received write request for
position 7
I1014 06:10:07.875799 25659 leveldb.cpp:343] Persisting action (678 bytes) to
leveldb took 723357ns
I1014 06:10:07.875815 25659 replica.cpp:676] Persisted action at 7
I1014 06:10:07.876035 25671 replica.cpp:655] Replica received learned notice
for position 7
I1014 06:10:07.876621 25671 leveldb.cpp:343] Persisting action (680 bytes) to
leveldb took 567539ns
I1014 06:10:07.876637 25671 replica.cpp:676] Persisted action at 7
I1014 06:10:07.876644 25671 replica.cpp:661] Replica learned APPEND action at
position 7
I1014 06:10:07.876961 25659 registrar.cpp:490] Successfully updated the
'registry' in 3.751168ms
I1014 06:10:07.877058 25660 log.cpp:699] Attempting to truncate the log to 7
I1014 06:10:07.877135 25671 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 8
I1014 06:10:07.877181 25665 slave.cpp:2403] Received ping from
slave-observer(43)@67.195.81.187:33243
I1014 06:10:07.877207 25673 master.cpp:3022] Registered slave
20141014-061007-3142697795-33243-25643-2 at slave(49)@67.195.81.187:33243
(pomona.apache.org) with cpus(*):3; mem(*):2048; disk(*):0;
ports(*):[31000-32000]
I1014 06:10:07.877316 25665 slave.cpp:765] Registered with master
master@67.195.81.187:33243; given slave ID
20141014-061007-3142697795-33243-25643-2
I1014 06:10:07.877372 25669 hierarchical_allocator_process.hpp:442] Added slave
20141014-061007-3142697795-33243-25643-2 (pomona.apache.org) with cpus(*):3;
mem(*):2048; disk(*):0; ports(*):[31000-32000] (and cpus(*):3; mem(*):2048;
disk(*):0; ports(*):[31000-32000] available)
I1014 06:10:07.877439 25669 hierarchical_allocator_process.hpp:734] Offering
cpus(*):3; mem(*):2048; disk(*):0; ports(*):[31000-32000] on slave
20141014-061007-3142697795-33243-25643-2 to framework
20141014-061007-3142697795-33243-25643-0001
I1014 06:10:07.877544 25663 replica.cpp:508] Replica received write request for
position 8
I1014 06:10:07.877586 25669 hierarchical_allocator_process.hpp:679] Performed
allocation for slave 20141014-061007-3142697795-33243-25643-2 in 181220ns
I1014 06:10:07.877682 25671 master.cpp:3729] Sending 1 offers to framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243
I1014 06:10:07.877812 25666 sched.cpp:544] Scheduler::resourceOffers took
23371ns
I1014 06:10:07.878108 25663 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 545630ns
I1014 06:10:07.878123 25663 replica.cpp:676] Persisted action at 8
I1014 06:10:07.878480 25670 replica.cpp:655] Replica received learned notice
for position 8
I1014 06:10:07.879099 25670 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 597875ns
I1014 06:10:07.879134 25670 leveldb.cpp:401] Deleting ~2 keys from leveldb took
18667ns
I1014 06:10:07.879145 25670 replica.cpp:676] Persisted action at 8
I1014 06:10:07.879153 25670 replica.cpp:661] Replica learned TRUNCATE action at
position 8
I1014 06:10:07.880727 25643 sched.cpp:137] Version: 0.21.0
I1014 06:10:07.880950 25668 sched.cpp:233] New master detected at
master@67.195.81.187:33243
I1014 06:10:07.880975 25668 sched.cpp:283] Authenticating with master
master@67.195.81.187:33243
I1014 06:10:07.881042 25665 authenticatee.hpp:133] Creating new client SASL
connection
I1014 06:10:07.881160 25660 master.cpp:3787] Authenticating
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:07.881247 25671 authenticator.hpp:161] Creating new server SASL
connection
I1014 06:10:07.881376 25668 authenticatee.hpp:224] Received SASL authentication
mechanisms: CRAM-MD5
I1014 06:10:07.881399 25668 authenticatee.hpp:250] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1014 06:10:07.881459 25666 authenticator.hpp:267] Received SASL authentication
start
I1014 06:10:07.881510 25666 authenticator.hpp:389] Authentication requires more
steps
I1014 06:10:07.881556 25666 authenticatee.hpp:270] Received SASL authentication
step
I1014 06:10:07.881606 25666 authenticator.hpp:295] Received SASL authentication
step
I1014 06:10:07.881628 25666 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1014 06:10:07.881638 25666 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I1014 06:10:07.881647 25666 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1014 06:10:07.881656 25666 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1014 06:10:07.881664 25666 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.881669 25666 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1014 06:10:07.881681 25666 authenticator.hpp:381] Authentication success
I1014 06:10:07.881727 25671 authenticatee.hpp:310] Authentication success
I1014 06:10:07.881747 25666 master.cpp:3827] Successfully authenticated
principal 'test-principal' at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:07.881872 25671 sched.cpp:357] Successfully authenticated with
master master@67.195.81.187:33243
I1014 06:10:07.881889 25671 sched.cpp:476] Sending registration request to
master@67.195.81.187:33243
I1014 06:10:07.881959 25658 master.cpp:1362] Received registration request for
framework 'framework3' at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:07.881988 25658 master.cpp:1321] Authorizing framework principal
'test-principal' to receive offers for role 'role1'
I1014 06:10:07.882143 25659 master.cpp:1426] Registering framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:07.882252 25664 sched.cpp:407] Framework registered with
20141014-061007-3142697795-33243-25643-0002
I1014 06:10:07.882298 25664 sched.cpp:421] Scheduler::registered took 27159ns
I1014 06:10:07.882362 25668 hierarchical_allocator_process.hpp:329] Added
framework 20141014-061007-3142697795-33243-25643-0002
I1014 06:10:07.882433 25668 hierarchical_allocator_process.hpp:659] Performed
allocation for 3 slaves in 55099ns
I1014 06:10:07.882995 25643 containerizer.cpp:89] Using isolation:
posix/cpu,posix/mem
I1014 06:10:07.884968 25659 slave.cpp:169] Slave started on
50)@67.195.81.187:33243
I1014 06:10:07.884997 25659 credentials.hpp:84] Loading credential for
authentication from
'/tmp/DRFAllocatorTest_DRFAllocatorProcess_Xb9tM5/credential'
I1014 06:10:07.885087 25659 slave.cpp:276] Slave using credential for:
test-principal
I1014 06:10:07.885176 25659 slave.cpp:289] Slave resources: cpus(*):4;
mem(*):4096; disk(*):0; ports(*):[31000-32000]
I1014 06:10:07.885238 25659 slave.cpp:318] Slave hostname: pomona.apache.org
I1014 06:10:07.885248 25659 slave.cpp:319] Slave checkpoint: false
I1014 06:10:07.885679 25666 state.cpp:33] Recovering state from
'/tmp/DRFAllocatorTest_DRFAllocatorProcess_Xb9tM5/meta'
I1014 06:10:07.885826 25667 status_update_manager.cpp:193] Recovering status
update manager
I1014 06:10:07.885933 25672 containerizer.cpp:252] Recovering containerizer
I1014 06:10:07.886214 25671 slave.cpp:3329] Finished recovery
I1014 06:10:07.886513 25671 slave.cpp:600] New master detected at
master@67.195.81.187:33243
I1014 06:10:07.886550 25671 slave.cpp:674] Authenticating with master
master@67.195.81.187:33243
I1014 06:10:07.886574 25673 status_update_manager.cpp:167] New master detected
at master@67.195.81.187:33243
I1014 06:10:07.886597 25671 slave.cpp:647] Detecting new master
I1014 06:10:07.886621 25670 authenticatee.hpp:133] Creating new client SASL
connection
I1014 06:10:07.886741 25664 master.cpp:3787] Authenticating
slave(50)@67.195.81.187:33243
Itests/allocator_tests.cpp:242: Failure
1014 06:10:07.886812 25670 authenticator.hpp:161] Creating new server SASL
connection
Failed to wait 10secs for offers4
I1014 06:10:08.821841 25671 hierarchical_allocator_process.hpp:659] Performed
allocation for 3 slaves in 61286ns
I1014 06:10:12.821713 25659 master.cpp:120] No whitelist given. Advertising
offers for all slaves
W1014 06:10:12.887408 25673 slave.cpp:748] Authentication timed out
W1014 06:10:12.887423 25672 master.cpp:3845] Authentication timed out
W1014 06:10:19.912761 25673 slave.cpp:712] Failed to authenticate with master
master@67.195.81.187:33243: Authentication discarded
tests/allocator_tests.cpp:230: Failure
Actual function call count doesn't match EXPECT_CALL(sched3, resourceOffers(_,
_))...
Expected: to be called once
Actual: never called - unsatisfied and active
I1014 06:10:19.912864 25673 slave.cpp:674] Authenticating with master
master@67.195.81.187:33243
I1014 06:10:19.912863 25672 master.cpp:768] Framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243 disconnected
I1014 06:10:19.912884 25672 master.cpp:1731] Disconnecting framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:19.912894 25672 master.cpp:1747] Deactivating framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:19.912917 25672 master.cpp:790] Giving framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243 0ns to
failover
GMOCK WARNING:
IUninteresting mock function call - taking default action specified at:
./tests/mesos.hpp:595:
1014 06:10:19.912972 25669 authenticatee.hpp:133] Creating new client SASL
connection
Function call: frameworkDeactivated(@0x2b285c000e10
20141014-061007-3142697795-33243-25643-0002)
Stack trace:
W1014 06:10:19.912981 25672 master.cpp:3822] Failed to authenticate
slave(50)@67.195.81.187:33243: Authentication discarded
I1014 06:10:19.913012 25662 hierarchical_allocator_process.hpp:405] Deactivated
framework 20141014-061007-3142697795-33243-25643-0002
I1014 06:10:19.913100 25672 master.cpp:768] Framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243 disconnected
I1014 06:10:19.913116 25672 master.cpp:1731] Disconnecting framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243
I1014 06:10:19.913127 25672 master.cpp:1747] Deactivating framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243
GMOCK WARNING:
Uninteresting mock function call - taking default action specified at:
./tests/mesos.hpp:595:
Function call: frameworkDeactivated(@0x2b285c02c7d0
20141014-061007-3142697795-33243-25643-0001)
Stack trace:
I1014 06:10:19.913233 25662 hierarchical_allocator_process.hpp:405] Deactivated
framework 20141014-061007-3142697795-33243-25643-0001
GMOCK WARNING:
Uninteresting mock function call - taking default action specified at:
./tests/mesos.hpp:616:
Function call: resourcesRecovered(@0x2b285c016ba0
20141014-061007-3142697795-33243-25643-0001, @0x2b285c016bc0
20141014-061007-3142697795-33243-25643-2, @0x2b285c016be0 { cpus(*):3,
mem(*):2048, disk(*):0, ports(*):[31000-32000] }, @0x2b285c016bf8 16-byte
object <01-00 00-00 00-00 00-00 00-00 00-00 00-00 00-00>)
Stack trace:
IF1014 06:10:19.913369 25666 logging.cpp:57] RAW: Pure virtual method called
1014 06:10:19.913369 25672 master.cpp:790] Giving framework
20141014-061007-3142697795-33243-25643-0001 (framework2) at
scheduler-b1c9555c-0b21-4af2-b9a8-16fa4b709ef2@67.195.81.187:33243 0ns to
failover
@ 0x2b282d3e6ddd google::LogMessage::Fail()
@ 0x2b282d3ee4d6 google::RawLog__()
@ 0x9b12d9
testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
tests/allocator_tests.cpp:227: Failure
Actual function call count doesn't match EXPECT_CALL(allocator, slaveAdded(_,
_, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I1014 06:10:34.297683 25672 master.cpp:3599] Framework failover timeout,
removing framework 20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:34.297713 25672 master.cpp:4128] Removing framework
20141014-061007-3142697795-33243-25643-0002 (framework3) at
scheduler-8af00ac6-4769-4f91-a0f1-dd50d8dc8074@67.195.81.187:33243
I1014 06:10:34.297854 25668 master.cpp:120] No whitelist given. Advertising
offers for all slaves
*** Aborted at 1413267034 (unix time) try "date -d @1413267034" if you are
using GNU date ***
I1014 06:10:34.297889 25669 slave.cpp:1487] Asked to shut down framework
20141014-061007-3142697795-33243-25643-0002 by master@67.195.81.187:33243
W1014 06:10:34.297911 25669 slave.cpp:1502] Cannot shut down unknown framework
20141014-061007-3142697795-33243-25643-0002
W1014 06:10:34.297955 25673 slave.cpp:748] Authentication timed out
I1014 06:10:34.298037 25673 slave.cpp:2403] Received ping from
slave-observer(42)@67.195.81.187:33243
W1014 06:10:34.298125 25662 slave.cpp:712] Failed to authenticate with master
master@67.195.81.187:33243: Authentication discarded
I1014 06:10:34.298156 25671 slave.cpp:1487] Asked to shut down framework
20141014-061007-3142697795-33243-25643-0002 by master@67.195.81.187:33243
W1014 06:10:34.298176 25671 slave.cpp:1502] Cannot shut down unknown framework
20141014-061007-3142697795-33243-25643-0002
I1014 06:10:34.298178 25662 slave.cpp:674] Authenticating with master
master@67.195.81.187:33243
I1014 06:10:34.298204 25671 slave.cpp:2403] Received ping from
slave-observer(43)@67.195.81.187:33243
I1014 06:10:34.298315 25671 authenticatee.hpp:133] Creating new client SASL
connection
I1014 06:10:34.298396 25658 slave.cpp:2403] Received ping from
slave-observer(41)@67.195.81.187:33243
I1014 06:10:34.298463 25658 slave.cpp:1487] Asked to shut down framework
20141014-061007-3142697795-33243-25643-0002 by master@67.195.81.187:33243
W1014 06:10:34.298481 25658 slave.cpp:1502] Cannot shut down unknown framework
20141014-061007-3142697795-33243-25643-0002
@ 0x4bb70d
mesos::internal::tests::MockAllocatorProcess<>::resourcesRecovered()
PC: @ 0x2b282cf5d810 process::dispatch<>()
@ 0x2b282d395d75 process::ProcessManager::resume()
@ 0x2b282d39601c process::schedule()
@ 0x2b282f0df182 start_thread
@ 0x2b282f3effbd (unknown)
make[3]: *** [check-local] Aborted
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
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
