commit 44682979fbe53667e4a2f6bfe50e90f473e74141
Author: Vinod Kone <[email protected]>
Date: Mon Nov 10 17:53:04 2014 -0800
Fixed MasterAuthorizationTest.FrameworkRemovedBeforeRegistration test.
On Mon, Nov 10, 2014 at 5:41 PM, Vinod Kone <[email protected]> wrote:
> same issue as https://issues.apache.org/jira/browse/MESOS-2008. will
> commit a fix.
>
> On Mon, Nov 10, 2014 at 4:31 PM, Apache Jenkins Server <
> [email protected]> wrote:
>
>> See <
>> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2267/changes
>> >
>>
>> Changes:
>>
>> [dhamon] Abort if Future::failure() is called on non-failed Future
>>
>> [dave] Adds Revisely to Mesos adopters list.
>>
>> ------------------------------------------
>> [...truncated 70652 lines...]
>> I1111 00:30:38.433552 25479 replica.cpp:375] Replica received explicit
>> promise request for position 0 with proposal 2
>> I1111 00:30:38.433882 25479 leveldb.cpp:343] Persisting action (8 bytes)
>> to leveldb took 296600ns
>> I1111 00:30:38.433908 25479 replica.cpp:676] Persisted action at 0
>> I1111 00:30:38.434942 25475 replica.cpp:508] Replica received write
>> request for position 0
>> I1111 00:30:38.435005 25475 leveldb.cpp:438] Reading position from
>> leveldb took 33361ns
>> I1111 00:30:38.435405 25475 leveldb.cpp:343] Persisting action (14 bytes)
>> to leveldb took 359089ns
>> I1111 00:30:38.435437 25475 replica.cpp:676] Persisted action at 0
>> I1111 00:30:38.435981 25486 replica.cpp:655] Replica received learned
>> notice for position 0
>> I1111 00:30:38.436410 25486 leveldb.cpp:343] Persisting action (16 bytes)
>> to leveldb took 397287ns
>> I1111 00:30:38.436447 25486 replica.cpp:676] Persisted action at 0
>> I1111 00:30:38.436471 25486 replica.cpp:661] Replica learned NOP action
>> at position 0
>> I1111 00:30:38.437064 25481 log.cpp:672] Writer started with ending
>> position 0
>> I1111 00:30:38.438364 25486 leveldb.cpp:438] Reading position from
>> leveldb took 40584ns
>> I1111 00:30:38.441010 25483 registrar.cpp:346] Successfully fetched the
>> registry (0B) in 13.239808ms
>> I1111 00:30:38.441122 25483 registrar.cpp:445] Applied 1 operations in
>> 15875ns; attempting to update the 'registry'
>> I1111 00:30:38.444015 25488 log.cpp:680] Attempting to append 139 bytes
>> to the log
>> I1111 00:30:38.444165 25483 coordinator.cpp:340] Coordinator attempting
>> to write APPEND action at position 1
>> I1111 00:30:38.445003 25476 replica.cpp:508] Replica received write
>> request for position 1
>> I1111 00:30:38.445494 25476 leveldb.cpp:343] Persisting action (158
>> bytes) to leveldb took 463174ns
>> I1111 00:30:38.445516 25476 replica.cpp:676] Persisted action at 1
>> I1111 00:30:38.446033 25484 replica.cpp:655] Replica received learned
>> notice for position 1
>> I1111 00:30:38.446496 25484 leveldb.cpp:343] Persisting action (160
>> bytes) to leveldb took 439473ns
>> I1111 00:30:38.446517 25484 replica.cpp:676] Persisted action at 1
>> I1111 00:30:38.446535 25484 replica.cpp:661] Replica learned APPEND
>> action at position 1
>> I1111 00:30:38.447254 25476 registrar.cpp:490] Successfully updated the
>> 'registry' in 6.08512ms
>> I1111 00:30:38.447383 25476 registrar.cpp:376] Successfully recovered
>> registrar
>> I1111 00:30:38.447568 25484 log.cpp:699] Attempting to truncate the log
>> to 1
>> I1111 00:30:38.447726 25482 coordinator.cpp:340] Coordinator attempting
>> to write TRUNCATE action at position 2
>> I1111 00:30:38.447746 25479 master.cpp:1121] Recovered 0 slaves from the
>> Registry (101B) ; allowing 10mins for slaves to re-register
>> I1111 00:30:38.448470 25487 replica.cpp:508] Replica received write
>> request for position 2
>> I1111 00:30:38.448860 25487 leveldb.cpp:343] Persisting action (16 bytes)
>> to leveldb took 357272ns
>> I1111 00:30:38.448887 25487 replica.cpp:676] Persisted action at 2
>> I1111 00:30:38.449442 25473 replica.cpp:655] Replica received learned
>> notice for position 2
>> I1111 00:30:38.449846 25473 leveldb.cpp:343] Persisting action (18 bytes)
>> to leveldb took 372600ns
>> I1111 00:30:38.449906 25473 leveldb.cpp:401] Deleting ~1 keys from
>> leveldb took 32627ns
>> I1111 00:30:38.449928 25473 replica.cpp:676] Persisted action at 2
>> I1111 00:30:38.449950 25473 replica.cpp:661] Replica learned TRUNCATE
>> action at position 2
>> I1111 00:30:38.458940 25459 containerizer.cpp:101] Using isolation:
>> posix/cpu,posix/mem
>> I1111 00:30:38.462609 25473 slave.cpp:169] Slave started on 222)@
>> 67.195.81.190:49921
>> I1111 00:30:38.462664 25473 credentials.hpp:84] Loading credential for
>> authentication from
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/credential'
>> I1111 00:30:38.462843 25473 slave.cpp:276] Slave using credential for:
>> test-principal
>> I1111 00:30:38.463093 25473 slave.cpp:289] Slave resources: cpus(*):2;
>> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
>> I1111 00:30:38.463188 25473 slave.cpp:318] Slave hostname:
>> pietas.apache.org
>> I1111 00:30:38.463201 25473 slave.cpp:319] Slave checkpoint: true
>> I1111 00:30:38.464102 25478 state.cpp:33] Recovering state from
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta'
>> I1111 00:30:38.464542 25478 status_update_manager.cpp:197] Recovering
>> status update manager
>> I1111 00:30:38.464813 25477 containerizer.cpp:282] Recovering
>> containerizer
>> I1111 00:30:38.465734 25476 slave.cpp:3464] Finished recovery
>> I1111 00:30:38.466253 25479 status_update_manager.cpp:171] Pausing
>> sending status updates
>> I1111 00:30:38.466264 25482 slave.cpp:602] New master detected at
>> [email protected]:49921
>> I1111 00:30:38.466303 25482 slave.cpp:665] Authenticating with master
>> [email protected]:49921
>> I1111 00:30:38.466411 25482 slave.cpp:638] Detecting new master
>> I1111 00:30:38.466460 25483 authenticatee.hpp:133] Creating new client
>> SASL connection
>> I1111 00:30:38.466639 25485 master.cpp:3901] Authenticating slave(222)@
>> 67.195.81.190:49921
>> I1111 00:30:38.466684 25485 master.cpp:3912] Using default CRAM-MD5
>> authenticator
>> I1111 00:30:38.466859 25478 authenticator.hpp:166] Creating new server
>> SASL connection
>> I1111 00:30:38.467005 25485 authenticatee.hpp:224] Received SASL
>> authentication mechanisms: CRAM-MD5
>> I1111 00:30:38.467036 25485 authenticatee.hpp:250] Attempting to
>> authenticate with mechanism 'CRAM-MD5'
>> I1111 00:30:38.467149 25474 authenticator.hpp:272] Received SASL
>> authentication start
>> I1111 00:30:38.467219 25474 authenticator.hpp:394] Authentication
>> requires more steps
>> I1111 00:30:38.467316 25485 authenticatee.hpp:270] Received SASL
>> authentication step
>> I1111 00:30:38.467478 25475 authenticator.hpp:300] Received SASL
>> authentication step
>> I1111 00:30:38.467516 25475 auxprop.cpp:99] Request to lookup properties
>> for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: '
>> pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
>> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
>> I1111 00:30:38.467540 25475 auxprop.cpp:171] Looking up auxiliary
>> property '*userPassword'
>> I1111 00:30:38.467592 25475 auxprop.cpp:171] Looking up auxiliary
>> property '*cmusaslsecretCRAM-MD5'
>> I1111 00:30:38.467635 25475 auxprop.cpp:99] Request to lookup properties
>> for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: '
>> pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
>> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
>> I1111 00:30:38.467654 25475 auxprop.cpp:121] Skipping auxiliary property
>> '*userPassword' since SASL_AUXPROP_AUTHZID == true
>> I1111 00:30:38.467663 25475 auxprop.cpp:121] Skipping auxiliary property
>> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
>> I1111 00:30:38.467684 25475 authenticator.hpp:386] Authentication success
>> I1111 00:30:38.467778 25480 authenticatee.hpp:310] Authentication success
>> I1111 00:30:38.467818 25481 master.cpp:3959] Successfully authenticated
>> principal 'test-principal' at slave(222)@67.195.81.190:49921
>> I1111 00:30:38.468039 25482 slave.cpp:722] Successfully authenticated
>> with master [email protected]:49921
>> I1111 00:30:38.468124 25459 sched.cpp:143] Version: 0.22.0
>> I1111 00:30:38.468161 25482 slave.cpp:1050] Will retry registration in
>> 5.225657ms if necessary
>> I1111 00:30:38.468437 25475 master.cpp:3068] Registering slave at
>> slave(222)@67.195.81.190:49921 (pietas.apache.org) with id
>> 20141111-003038-3193029443-49921-25459-S0
>> I1111 00:30:38.468611 25481 sched.cpp:239] New master detected at
>> [email protected]:49921
>> I1111 00:30:38.468652 25481 sched.cpp:295] Authenticating with master
>> [email protected]:49921
>> I1111 00:30:38.468905 25485 registrar.cpp:445] Applied 1 operations in
>> 52768ns; attempting to update the 'registry'
>> I1111 00:30:38.468929 25481 authenticatee.hpp:133] Creating new client
>> SASL connection
>> I1111 00:30:38.469148 25481 master.cpp:3901] Authenticating
>> [email protected]:49921
>> I1111 00:30:38.469179 25481 master.cpp:3912] Using default CRAM-MD5
>> authenticator
>> I1111 00:30:38.469422 25481 authenticator.hpp:166] Creating new server
>> SASL connection
>> I1111 00:30:38.469605 25484 authenticatee.hpp:224] Received SASL
>> authentication mechanisms: CRAM-MD5
>> I1111 00:30:38.469629 25484 authenticatee.hpp:250] Attempting to
>> authenticate with mechanism 'CRAM-MD5'
>> I1111 00:30:38.469696 25484 authenticator.hpp:272] Received SASL
>> authentication start
>> I1111 00:30:38.469735 25484 authenticator.hpp:394] Authentication
>> requires more steps
>> I1111 00:30:38.469805 25484 authenticatee.hpp:270] Received SASL
>> authentication step
>> I1111 00:30:38.469892 25484 authenticator.hpp:300] Received SASL
>> authentication step
>> I1111 00:30:38.469915 25484 auxprop.cpp:99] Request to lookup properties
>> for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: '
>> pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
>> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
>> I1111 00:30:38.469924 25484 auxprop.cpp:171] Looking up auxiliary
>> property '*userPassword'
>> I1111 00:30:38.469959 25484 auxprop.cpp:171] Looking up auxiliary
>> property '*cmusaslsecretCRAM-MD5'
>> I1111 00:30:38.469980 25484 auxprop.cpp:99] Request to lookup properties
>> for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: '
>> pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
>> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
>> I1111 00:30:38.469987 25484 auxprop.cpp:121] Skipping auxiliary property
>> '*userPassword' since SASL_AUXPROP_AUTHZID == true
>> I1111 00:30:38.469993 25484 auxprop.cpp:121] Skipping auxiliary property
>> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
>> I1111 00:30:38.470005 25484 authenticator.hpp:386] Authentication success
>> I1111 00:30:38.470077 25475 authenticatee.hpp:310] Authentication success
>> I1111 00:30:38.470103 25486 master.cpp:3959] Successfully authenticated
>> principal 'test-principal' at
>> [email protected]:49921
>> I1111 00:30:38.470298 25475 sched.cpp:369] Successfully authenticated
>> with master [email protected]:49921
>> I1111 00:30:38.470326 25475 sched.cpp:488] Sending registration request
>> to [email protected]:49921
>> I1111 00:30:38.470388 25475 sched.cpp:521] Will retry registration in
>> 294.078414ms if necessary
>> I1111 00:30:38.470481 25487 master.cpp:1383] Received registration
>> request for framework 'default' at
>> [email protected]:49921
>> I1111 00:30:38.470557 25487 master.cpp:1342] Authorizing framework
>> principal 'test-principal' to receive offers for role '*'
>> I1111 00:30:38.470990 25487 master.cpp:1447] Registering framework
>> 20141111-003038-3193029443-49921-25459-0000 (default) at
>> [email protected]:49921
>> I1111 00:30:38.471300 25473 hierarchical_allocator_process.hpp:329] Added
>> framework 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.471323 25475 log.cpp:680] Attempting to append 316 bytes
>> to the log
>> I1111 00:30:38.471328 25473 hierarchical_allocator_process.hpp:697] No
>> resources available to allocate!
>> I1111 00:30:38.471361 25473 hierarchical_allocator_process.hpp:659]
>> Performed allocation for 0 slaves in 37154ns
>> I1111 00:30:38.471418 25481 sched.cpp:419] Framework registered with
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.471496 25478 coordinator.cpp:340] Coordinator attempting
>> to write APPEND action at position 3
>> I1111 00:30:38.471514 25481 sched.cpp:433] Scheduler::registered took
>> 45168ns
>> I1111 00:30:38.472265 25484 replica.cpp:508] Replica received write
>> request for position 3
>> I1111 00:30:38.472503 25484 leveldb.cpp:343] Persisting action (335
>> bytes) to leveldb took 206085ns
>> I1111 00:30:38.472525 25484 replica.cpp:676] Persisted action at 3
>> I1111 00:30:38.473094 25484 replica.cpp:655] Replica received learned
>> notice for position 3
>> I1111 00:30:38.473592 25484 leveldb.cpp:343] Persisting action (337
>> bytes) to leveldb took 463294ns
>> I1111 00:30:38.473621 25484 replica.cpp:676] Persisted action at 3
>> I1111 00:30:38.473644 25484 replica.cpp:661] Replica learned APPEND
>> action at position 3
>> I1111 00:30:38.474622 25479 slave.cpp:1050] Will retry registration in
>> 15.039081ms if necessary
>> I1111 00:30:38.474681 25481 registrar.cpp:490] Successfully updated the
>> 'registry' in 5.724928ms
>> I1111 00:30:38.474746 25473 master.cpp:3056] Ignoring register slave
>> message from slave(222)@67.195.81.190:49921 (pietas.apache.org) as
>> admission is already in progress
>> I1111 00:30:38.475056 25479 log.cpp:699] Attempting to truncate the log
>> to 3
>> I1111 00:30:38.475194 25481 slave.cpp:2536] Received ping from
>> slave-observer(202)@67.195.81.190:49921
>> I1111 00:30:38.475208 25474 coordinator.cpp:340] Coordinator attempting
>> to write TRUNCATE action at position 4
>> I1111 00:30:38.475241 25487 master.cpp:3122] Registered slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org) with cpus(*):2; mem(*):1024;
>> disk(*):1024; ports(*):[31000-32000]
>> I1111 00:30:38.475356 25481 slave.cpp:756] Registered with master
>> [email protected]:49921; given slave ID
>> 20141111-003038-3193029443-49921-25459-S0
>> I1111 00:30:38.475566 25473 status_update_manager.cpp:178] Resuming
>> sending status updates
>> I1111 00:30:38.475523 25475 hierarchical_allocator_process.hpp:442] Added
>> slave 20141111-003038-3193029443-49921-25459-S0 (pietas.apache.org) with
>> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and
>> cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
>> I1111 00:30:38.475759 25481 slave.cpp:772] Checkpointing SlaveInfo to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/
>> slave.info'
>> I1111 00:30:38.475853 25475 hierarchical_allocator_process.hpp:734]
>> Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
>> slave 20141111-003038-3193029443-49921-25459-S0 to framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.476261 25476 replica.cpp:508] Replica received write
>> request for position 4
>> I1111 00:30:38.476313 25475 hierarchical_allocator_process.hpp:679]
>> Performed allocation for slave 20141111-003038-3193029443-49921-25459-S0 in
>> 667119ns
>> I1111 00:30:38.476639 25476 leveldb.cpp:343] Persisting action (16 bytes)
>> to leveldb took 348162ns
>> I1111 00:30:38.476646 25479 master.cpp:3843] Sending 1 offers to
>> framework 20141111-003038-3193029443-49921-25459-0000 (default) at
>> [email protected]:49921
>> I1111 00:30:38.476661 25476 replica.cpp:676] Persisted action at 4
>> I1111 00:30:38.477094 25480 sched.cpp:578] Scheduler::resourceOffers took
>> 132749ns
>> I1111 00:30:38.477279 25480 replica.cpp:655] Replica received learned
>> notice for position 4
>> I1111 00:30:38.477635 25480 leveldb.cpp:343] Persisting action (18 bytes)
>> to leveldb took 328226ns
>> I1111 00:30:38.477712 25480 leveldb.cpp:401] Deleting ~2 keys from
>> leveldb took 48164ns
>> I1111 00:30:38.477737 25480 replica.cpp:676] Persisted action at 4
>> I1111 00:30:38.477761 25480 replica.cpp:661] Replica learned TRUNCATE
>> action at position 4
>> I1111 00:30:38.480132 25487 master.cpp:2344] Processing reply for offers:
>> [ 20141111-003038-3193029443-49921-25459-O0 ] on slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org) for framework
>> 20141111-003038-3193029443-49921-25459-0000 (default) at
>> [email protected]:49921
>> I1111 00:30:38.480294 25487 master.cpp:2440] Authorizing framework
>> principal 'test-principal' to launch task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b as user 'jenkins'
>> I1111 00:30:38.481160 25473 master.hpp:877] Adding task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b with resources cpus(*):2; mem(*):1024;
>> disk(*):1024; ports(*):[31000-32000] on slave
>> 20141111-003038-3193029443-49921-25459-S0 (pietas.apache.org)
>> I1111 00:30:38.481248 25473 master.cpp:2503] Launching task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 (default) at
>> [email protected]:49921 with
>> resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on
>> slave 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org)
>> I1111 00:30:38.481559 25484 slave.cpp:1081] Got assigned task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b for framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.481708 25484 slave.cpp:3940] Checkpointing FrameworkInfo
>> to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/
>> framework.info'
>> I1111 00:30:38.481928 25484 slave.cpp:3947] Checkpointing framework pid '
>> [email protected]:49921' to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/framework.pid'
>> I1111 00:30:38.482604 25484 slave.cpp:1191] Launching task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b for framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.485540 25484 slave.cpp:4363] Checkpointing ExecutorInfo to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/
>> executor.info'
>> I1111 00:30:38.485965 25484 slave.cpp:3995] Launching executor
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 in work directory
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97'
>> I1111 00:30:38.486569 25484 slave.cpp:4386] Checkpointing TaskInfo to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97/tasks/6bec3efc-daca-437a-a469-98cf3895ce6b/
>> task.info'
>> I1111 00:30:38.486577 25486 containerizer.cpp:425] Starting container
>> '04bb1020-0041-48c1-bfa9-5111b6feca97' for executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> '20141111-003038-3193029443-49921-25459-0000'
>> I1111 00:30:38.486830 25484 slave.cpp:1314] Queuing task
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' for executor
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> '20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.486960 25484 slave.cpp:555] Successfully attached file
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97'
>> I1111 00:30:38.491061 25486 launcher.cpp:137] Forked child with pid
>> '1727' for container '04bb1020-0041-48c1-bfa9-5111b6feca97'
>> I1111 00:30:38.491245 25486 containerizer.cpp:699] Checkpointing
>> executor's forked pid 1727 to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97/pids/forked.pid'
>> I1111 00:30:38.502115 25481 fetcher.cpp:95] Fetching URIs using command '<
>> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src/mesos-fetcher
>> '>
>> I1111 00:30:38.649799 25478 slave.cpp:2838] Monitoring executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> '20141111-003038-3193029443-49921-25459-0000' in container
>> '04bb1020-0041-48c1-bfa9-5111b6feca97'
>> WARNING: Logging before InitGoogleLogging() is written to STDERR
>> I1111 00:30:38.695924 1762 process.cpp:1671] libprocess is initialized
>> on 67.195.81.190:41399 for 16 cpus
>> I1111 00:30:38.697172 1762 logging.cpp:177] Logging to STDERR
>> I1111 00:30:38.699761 1762 exec.cpp:132] Version: 0.22.0
>> I1111 00:30:38.703836 1785 exec.cpp:182] Executor started at:
>> executor(1)@67.195.81.190:41399 with pid 1762
>> I1111 00:30:38.704759 25483 slave.cpp:1858] Got registration for executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000 from executor(1)@
>> 67.195.81.190:41399
>> I1111 00:30:38.704849 25483 slave.cpp:1944] Checkpointing executor pid
>> 'executor(1)@67.195.81.190:41399' to
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97/pids/libprocess.pid'
>> I1111 00:30:38.705662 25483 slave.cpp:1977] Flushing queued task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b for executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.705963 25483 slave.cpp:484] Slave terminating
>> I1111 00:30:38.706190 25486 master.cpp:839] Slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org) disconnected
>> I1111 00:30:38.706223 25486 master.cpp:1789] Disconnecting slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org)
>> I1111 00:30:38.706274 25486 master.cpp:1808] Deactivating slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org)
>> I1111 00:30:38.706291 1789 exec.cpp:206] Executor registered on slave
>> 20141111-003038-3193029443-49921-25459-S0
>> I1111 00:30:38.706379 25483 hierarchical_allocator_process.hpp:481] Slave
>> 20141111-003038-3193029443-49921-25459-S0 deactivated
>> Registered executor on pietas.apache.org
>> I1111 00:30:38.708144 1789 exec.cpp:218] Executor::registered took
>> 197585ns
>> I1111 00:30:38.708464 1789 exec.cpp:293] Executor asked to run task
>> '6bec3efc-daca-437a-a469-98cf3895ce6b'
>> I1111 00:30:38.708565 1789 exec.cpp:302] Executor::launchTask took
>> 75229ns
>> Starting task 6bec3efc-daca-437a-a469-98cf3895ce6b
>> I1111 00:30:38.708951 25459 containerizer.cpp:101] Using isolation:
>> posix/cpu,posix/mem
>> sh -c 'sleep 1000'
>> Forked command at 1793
>> I1111 00:30:38.712635 1782 exec.cpp:525] Executor sending status update
>> TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.714143 25479 slave.cpp:169] Slave started on 223)@
>> 67.195.81.190:49921
>> I1111 00:30:38.714184 25479 credentials.hpp:84] Loading credential for
>> authentication from
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/credential'
>> I1111 00:30:38.714323 25479 slave.cpp:276] Slave using credential for:
>> test-principal
>> I1111 00:30:38.714546 25479 slave.cpp:289] Slave resources: cpus(*):2;
>> mem(*):1024; disk(*):1024; ports(*):[31000-32000]
>> I1111 00:30:38.714630 25479 slave.cpp:318] Slave hostname:
>> pietas.apache.org
>> I1111 00:30:38.714647 25479 slave.cpp:319] Slave checkpoint: true
>> I1111 00:30:38.715651 25487 state.cpp:33] Recovering state from
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta'
>> W1111 00:30:38.716778 25487 state.cpp:561] Failed to find status updates
>> file
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/meta/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97/tasks/6bec3efc-daca-437a-a469-98cf3895ce6b/task.updates'
>> I1111 00:30:38.718109 25481 slave.cpp:3546] Recovering framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.718189 25481 slave.cpp:4114] Recovering executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.719120 25473 status_update_manager.cpp:197] Recovering
>> status update manager
>> I1111 00:30:38.719153 25473 status_update_manager.cpp:205] Recovering
>> executor '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> W1111 00:30:38.719203 25473 status_update_manager.cpp:244] No updates
>> found for task 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.719825 25481 slave.cpp:555] Successfully attached file
>> '/tmp/MesosContainerizerSlaveRecoveryTest_ResourceStatistics_H9Jj0Q/slaves/20141111-003038-3193029443-49921-25459-S0/frameworks/20141111-003038-3193029443-49921-25459-0000/executors/6bec3efc-daca-437a-a469-98cf3895ce6b/runs/04bb1020-0041-48c1-bfa9-5111b6feca97'
>> I1111 00:30:38.720192 25488 containerizer.cpp:282] Recovering
>> containerizer
>> I1111 00:30:38.720296 25488 containerizer.cpp:324] Recovering container
>> '04bb1020-0041-48c1-bfa9-5111b6feca97' for executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.722615 25487 slave.cpp:3405] Sending reconnect request to
>> executor 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 at executor(1)@
>> 67.195.81.190:41399
>> I1111 00:30:38.723208 1781 exec.cpp:252] Received reconnect request from
>> slave 20141111-003038-3193029443-49921-25459-S0
>> I1111 00:30:38.724176 25479 slave.cpp:2037] Re-registering executor
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.724385 25479 slave.cpp:2213] Handling status update
>> TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 from executor(1)@
>> 67.195.81.190:41399
>> I1111 00:30:38.724705 25488 status_update_manager.cpp:317] Received
>> status update TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for
>> task 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.724769 25488 status_update_manager.cpp:494] Creating
>> StatusUpdate stream for task 6bec3efc-daca-437a-a469-98cf3895ce6b of
>> framework 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.724833 1784 exec.cpp:229] Executor re-registered on slave
>> 20141111-003038-3193029443-49921-25459-S0
>> I1111 00:30:38.725150 25488 status_update_manager.hpp:346] Checkpointing
>> UPDATE for status update TASK_RUNNING (UUID:
>> b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.726320 25488 status_update_manager.cpp:371] Forwarding
>> update TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 to the slave
>> W1111 00:30:38.726469 25483 slave.cpp:2411] Dropping status update
>> TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 sent by status update manager
>> because the slave is in RECOVERING state
>> I1111 00:30:38.726563 25475 slave.cpp:2383] Status update manager
>> successfully handled status update TASK_RUNNING (UUID:
>> b561161f-d79d-40f9-a134-ff6926892d26) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.726591 25475 slave.cpp:2389] Sending acknowledgement for
>> status update TASK_RUNNING (UUID: b561161f-d79d-40f9-a134-ff6926892d26) for
>> task 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 to executor(1)@
>> 67.195.81.190:41399
>> Re-registered executor on pietas.apache.org
>> I1111 00:30:38.727334 1784 exec.cpp:241] Executor::reregistered took
>> 91113ns
>> I1111 00:30:38.727494 1784 exec.cpp:339] Executor received status update
>> acknowledgement b561161f-d79d-40f9-a134-ff6926892d26 for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.751037 25473 containerizer.cpp:906] Destroying container
>> '04bb1020-0041-48c1-bfa9-5111b6feca97'
>> I1111 00:30:38.851568 25474 containerizer.cpp:1077] Executor for
>> container '04bb1020-0041-48c1-bfa9-5111b6feca97' has exited
>> I1111 00:30:38.853281 25475 slave.cpp:2896] Executor
>> '6bec3efc-daca-437a-a469-98cf3895ce6b' of framework
>> 20141111-003038-3193029443-49921-25459-0000 terminated with signal Killed
>> I1111 00:30:38.853572 25482 sched.cpp:779] Stopping framework
>> '20141111-003038-3193029443-49921-25459-0000'
>> I1111 00:30:38.853600 25484 master.cpp:698] Master terminating
>> W1111 00:30:38.853771 25484 master.cpp:4738] Removing task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b with resources cpus(*):2; mem(*):1024;
>> disk(*):1024; ports(*):[31000-32000] of framework
>> 20141111-003038-3193029443-49921-25459-0000 on slave
>> 20141111-003038-3193029443-49921-25459-S0 at slave(222)@
>> 67.195.81.190:49921 (pietas.apache.org) in non-terminal state
>> TASK_STAGING
>> I1111 00:30:38.854259 25476 hierarchical_allocator_process.hpp:563]
>> Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
>> (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024;
>> ports(*):[31000-32000]) on slave 20141111-003038-3193029443-49921-25459-S0
>> from framework 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.856153 25475 slave.cpp:2213] Handling status update
>> TASK_FAILED (UUID: 8d4649bb-2b00-4e13-9c6b-195a2bc622ca) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000 from @0.0.0.0:0
>> I1111 00:30:38.856289 25475 slave.cpp:4303] Terminating task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b
>> W1111 00:30:38.856874 25473 containerizer.cpp:812] Ignoring update for
>> unknown container: 04bb1020-0041-48c1-bfa9-5111b6feca97
>> I1111 00:30:38.857517 25485 status_update_manager.cpp:317] Received
>> status update TASK_FAILED (UUID: 8d4649bb-2b00-4e13-9c6b-195a2bc622ca) for
>> task 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.857604 25485 status_update_manager.hpp:346] Checkpointing
>> UPDATE for status update TASK_FAILED (UUID:
>> 8d4649bb-2b00-4e13-9c6b-195a2bc622ca) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.859050 25483 slave.cpp:2383] Status update manager
>> successfully handled status update TASK_FAILED (UUID:
>> 8d4649bb-2b00-4e13-9c6b-195a2bc622ca) for task
>> 6bec3efc-daca-437a-a469-98cf3895ce6b of framework
>> 20141111-003038-3193029443-49921-25459-0000
>> I1111 00:30:38.860232 25479 slave.cpp:484] Slave terminating
>> [ OK ] MesosContainerizerSlaveRecoveryTest.ResourceStatistics (446
>> ms)
>> [----------] 1 test from MesosContainerizerSlaveRecoveryTest (447 ms
>> total)
>>
>> [----------] Global test environment tear-down
>> [==========] 458 tests from 71 test cases ran. (318602 ms total)
>> [ PASSED ] 457 tests.
>> [ FAILED ] 1 test, listed below:
>> [ FAILED ] MasterAuthorizationTest.FrameworkRemovedBeforeReregistration
>>
>> 1 FAILED TEST
>> YOU HAVE 6 DISABLED TESTS
>>
>> 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
>> Test finished
>> Exit status: 2
>> Build step 'Execute shell' marked build as failure
>> Recording test results
>>
>
>
