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 >
