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
