Already reported: https://issues.apache.org/jira/browse/MESOS-2239
On 10 February 2015 at 15:01, Apache Jenkins Server < jenk...@builds.apache.org> wrote: > See < > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2734/changes > > > > Changes: > > [niklas] Renamed test allocator actions for consistency. > > [niklas] Disabled installing module libraries. > > ------------------------------------------ > [...truncated 68044 lines...] > I0210 23:00:45.319550 14675 replica.cpp:678] Persisted action at 1 > I0210 23:00:45.322527 14684 replica.cpp:657] Replica received learned > notice for position 1 > I0210 23:00:45.323204 14684 leveldb.cpp:342] Persisting action (160 bytes) > to leveldb took 647894ns > I0210 23:00:45.323235 14684 replica.cpp:678] Persisted action at 1 > I0210 23:00:45.323259 14684 replica.cpp:663] Replica learned 2 action at > position 1 > I0210 23:00:45.324751 14681 log.cpp:702] Attempting to truncate the log to > 1 > I0210 23:00:45.324867 14675 coordinator.cpp:339] Coordinator attempting to > write 3 action at position 2 > I0210 23:00:45.325716 14674 registrar.cpp:489] Successfully updated the > 'registry' in 10.863104ms > I0210 23:00:45.325798 14686 replica.cpp:510] Replica received write > request for position 2 > I0210 23:00:45.325850 14674 registrar.cpp:375] Successfully recovered > registrar > I0210 23:00:45.326208 14686 leveldb.cpp:342] Persisting action (16 bytes) > to leveldb took 371952ns > I0210 23:00:45.326239 14686 replica.cpp:678] Persisted action at 2 > I0210 23:00:45.326689 14674 master.cpp:1213] Recovered 0 slaves from the > Registry (101B) ; allowing 10mins for slaves to re-register > I0210 23:00:45.327877 14686 replica.cpp:657] Replica received learned > notice for position 2 > I0210 23:00:45.328297 14686 leveldb.cpp:342] Persisting action (18 bytes) > to leveldb took 400127ns > I0210 23:00:45.328348 14686 leveldb.cpp:400] Deleting ~1 keys from leveldb > took 30941ns > I0210 23:00:45.328363 14686 replica.cpp:678] Persisted action at 2 > I0210 23:00:45.328378 14686 replica.cpp:663] Replica learned 3 action at > position 2 > I0210 23:00:45.344521 14682 slave.cpp:174] Slave started on 223)@ > 67.195.81.187:53677 > I0210 23:00:45.344549 14682 credentials.hpp:83] Loading credential for > authentication from '/tmp/MasterTest_KillUnknownTask_zfbUpZ/credential' > I0210 23:00:45.344686 14682 slave.cpp:283] Slave using credential for: > test-principal > I0210 23:00:45.344851 14682 slave.cpp:301] Slave resources: cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] > I0210 23:00:45.344928 14682 slave.cpp:330] Slave hostname: > pomona.apache.org > I0210 23:00:45.344941 14682 slave.cpp:331] Slave checkpoint: false > W0210 23:00:45.344948 14682 slave.cpp:333] Disabling checkpointing is > deprecated and the --checkpoint flag will be removed in a future release. > Please avoid using this flag > I0210 23:00:45.345929 14683 state.cpp:34] Recovering state from > '/tmp/MasterTest_KillUnknownTask_zfbUpZ/meta' > I0210 23:00:45.350471 14679 status_update_manager.cpp:196] Recovering > status update manager > I0210 23:00:45.350747 14672 slave.cpp:3619] Finished recovery > I0210 23:00:45.350924 14658 sched.cpp:151] Version: 0.22.0 > I0210 23:00:45.351358 14687 status_update_manager.cpp:170] Pausing sending > status updates > I0210 23:00:45.351358 14676 slave.cpp:626] New master detected at > master@67.195.81.187:53677 > I0210 23:00:45.351428 14677 sched.cpp:248] New master detected at > master@67.195.81.187:53677 > I0210 23:00:45.351480 14676 slave.cpp:689] Authenticating with master > master@67.195.81.187:53677 > I0210 23:00:45.351501 14676 slave.cpp:694] Using default CRAM-MD5 > authenticatee > I0210 23:00:45.351541 14677 sched.cpp:304] Authenticating with master > master@67.195.81.187:53677 > I0210 23:00:45.351562 14677 sched.cpp:311] Using default CRAM-MD5 > authenticatee > I0210 23:00:45.351666 14676 slave.cpp:662] Detecting new master > I0210 23:00:45.351686 14684 authenticatee.hpp:138] Creating new client > SASL connection > I0210 23:00:45.351790 14682 authenticatee.hpp:138] Creating new client > SASL connection > I0210 23:00:45.351944 14680 master.cpp:3791] Authenticating slave(223)@ > 67.195.81.187:53677 > I0210 23:00:45.351969 14680 master.cpp:3802] Using default CRAM-MD5 > authenticator > I0210 23:00:45.352133 14684 authenticator.hpp:169] Creating new server > SASL connection > I0210 23:00:45.352247 14680 master.cpp:3791] Authenticating > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.352273 14680 master.cpp:3802] Using default CRAM-MD5 > authenticator > I0210 23:00:45.352354 14677 authenticatee.hpp:229] Received SASL > authentication mechanisms: CRAM-MD5 > I0210 23:00:45.352380 14677 authenticatee.hpp:255] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0210 23:00:45.352485 14676 authenticator.hpp:275] Received SASL > authentication start > I0210 23:00:45.352509 14681 authenticator.hpp:169] Creating new server > SASL connection > I0210 23:00:45.352545 14676 authenticator.hpp:397] Authentication requires > more steps > I0210 23:00:45.352676 14686 authenticatee.hpp:229] Received SASL > authentication mechanisms: CRAM-MD5 > I0210 23:00:45.352689 14683 authenticatee.hpp:275] Received SASL > authentication step > I0210 23:00:45.352708 14686 authenticatee.hpp:255] Attempting to > authenticate with mechanism 'CRAM-MD5' > I0210 23:00:45.352849 14678 authenticator.hpp:303] Received SASL > authentication step > I0210 23:00:45.352874 14678 auxprop.cpp:98] Request to lookup properties > for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: ' > pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0210 23:00:45.352882 14678 auxprop.cpp:170] Looking up auxiliary property > '*userPassword' > I0210 23:00:45.352880 14672 authenticator.hpp:275] Received SASL > authentication start > I0210 23:00:45.352913 14678 auxprop.cpp:170] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0210 23:00:45.352932 14678 auxprop.cpp:98] Request to lookup properties > for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: ' > pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0210 23:00:45.352931 14672 authenticator.hpp:397] Authentication requires > more steps > I0210 23:00:45.352946 14678 auxprop.cpp:120] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0210 23:00:45.352962 14678 auxprop.cpp:120] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0210 23:00:45.352977 14678 authenticator.hpp:389] Authentication success > I0210 23:00:45.353026 14672 authenticatee.hpp:275] Received SASL > authentication step > I0210 23:00:45.353070 14683 authenticatee.hpp:315] Authentication success > I0210 23:00:45.353170 14678 authenticator.hpp:303] Received SASL > authentication step > I0210 23:00:45.353199 14678 auxprop.cpp:98] Request to lookup properties > for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: ' > pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false > I0210 23:00:45.353214 14678 auxprop.cpp:170] Looking up auxiliary property > '*userPassword' > I0210 23:00:45.353236 14678 auxprop.cpp:170] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0210 23:00:45.353274 14678 auxprop.cpp:98] Request to lookup properties > for user: 'test-principal' realm: 'pomona.apache.org' server FQDN: ' > pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false > SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true > I0210 23:00:45.353307 14678 auxprop.cpp:120] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0210 23:00:45.353317 14678 auxprop.cpp:120] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0210 23:00:45.353333 14678 authenticator.hpp:389] Authentication success > I0210 23:00:45.353425 14680 master.cpp:3849] Successfully authenticated > principal 'test-principal' at slave(223)@67.195.81.187:53677 > I0210 23:00:45.353564 14672 slave.cpp:760] Successfully authenticated with > master master@67.195.81.187:53677 > I0210 23:00:45.353605 14686 authenticatee.hpp:315] Authentication success > I0210 23:00:45.530272 14680 master.cpp:3849] Successfully authenticated > principal 'test-principal' at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.530347 14672 slave.cpp:1094] Will retry registration in > 6.229043ms if necessary > I0210 23:00:45.530845 14683 sched.cpp:392] Successfully authenticated with > master master@67.195.81.187:53677 > I0210 23:00:45.530869 14683 sched.cpp:515] Sending registration request to > master@67.195.81.187:53677 > I0210 23:00:45.530907 14683 sched.cpp:548] Will retry registration in > 1.804465831secs if necessary > I0210 23:00:45.531074 14680 master.cpp:2916] Registering slave at > slave(223)@67.195.81.187:53677 (pomona.apache.org) with id > 20150210-230045-3142697795-53677-14658-S0 > I0210 23:00:45.531380 14680 master.cpp:1573] Received registration request > for framework 'default' at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.531468 14680 master.cpp:1434] Authorizing framework > principal 'test-principal' to receive offers for role '*' > I0210 23:00:45.531527 14681 registrar.cpp:444] Applied 1 operations in > 41713ns; attempting to update the 'registry' > I0210 23:00:45.531945 14682 master.cpp:1637] Registering framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.532315 14672 hierarchical_allocator_process.hpp:318] Added > framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.532316 14686 sched.cpp:442] Framework registered with > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.532348 14672 hierarchical_allocator_process.hpp:828] No > resources available to allocate! > I0210 23:00:45.532361 14672 hierarchical_allocator_process.hpp:735] > Performed allocation for 0 slaves in 20240ns > I0210 23:00:45.532379 14686 sched.cpp:456] Scheduler::registered took > 20069ns > I0210 23:00:45.533927 14687 log.cpp:683] Attempting to append 316 bytes to > the log > I0210 23:00:45.534123 14682 coordinator.cpp:339] Coordinator attempting to > write 2 action at position 3 > I0210 23:00:45.534669 14681 replica.cpp:510] Replica received write > request for position 3 > I0210 23:00:45.535218 14681 leveldb.cpp:342] Persisting action (335 bytes) > to leveldb took 522665ns > I0210 23:00:45.535240 14681 replica.cpp:678] Persisted action at 3 > I0210 23:00:45.536377 14678 replica.cpp:657] Replica received learned > notice for position 3 > I0210 23:00:45.536782 14681 slave.cpp:1094] Will retry registration in > 6.412077ms if necessary > I0210 23:00:45.536921 14678 leveldb.cpp:342] Persisting action (337 bytes) > to leveldb took 509680ns > I0210 23:00:45.536942 14678 replica.cpp:678] Persisted action at 3 > I0210 23:00:45.536937 14672 master.cpp:2904] Ignoring register slave > message from slave(223)@67.195.81.187:53677 (pomona.apache.org) as > admission is already in progress > I0210 23:00:45.536957 14678 replica.cpp:663] Replica learned 2 action at > position 3 > I0210 23:00:45.538419 14675 registrar.cpp:489] Successfully updated the > 'registry' in 6.812928ms > I0210 23:00:45.538553 14674 log.cpp:702] Attempting to truncate the log to > 3 > I0210 23:00:45.538704 14675 coordinator.cpp:339] Coordinator attempting to > write 3 action at position 4 > I0210 23:00:45.539005 14686 slave.cpp:2674] Received ping from > slave-observer(205)@67.195.81.187:53677 > I0210 23:00:45.539023 14683 master.cpp:2973] Registered slave > 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) with cpus(*):2; mem(*):1024; > disk(*):1024; ports(*):[31000-32000] > I0210 23:00:45.539274 14687 hierarchical_allocator_process.hpp:450] Added > slave 20150210-230045-3142697795-53677-14658-S0 (pomona.apache.org) with > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) > I0210 23:00:45.539489 14686 slave.cpp:794] Registered with master > master@67.195.81.187:53677; given slave ID > 20150210-230045-3142697795-53677-14658-S0 > I0210 23:00:45.539628 14678 replica.cpp:510] Replica received write > request for position 4 > I0210 23:00:45.539634 14675 status_update_manager.cpp:177] Resuming > sending status updates > I0210 23:00:45.539839 14687 hierarchical_allocator_process.hpp:753] > Performed allocation for slave 20150210-230045-3142697795-53677-14658-S0 in > 476724ns > I0210 23:00:45.540105 14678 leveldb.cpp:342] Persisting action (16 bytes) > to leveldb took 447350ns > I0210 23:00:45.540129 14678 replica.cpp:678] Persisted action at 4 > I0210 23:00:45.540199 14676 master.cpp:3733] Sending 1 offers to framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.540689 14673 sched.cpp:605] Scheduler::resourceOffers took > 80763ns > I0210 23:00:45.541635 14679 replica.cpp:657] Replica received learned > notice for position 4 > I0210 23:00:45.542026 14679 leveldb.cpp:342] Persisting action (18 bytes) > to leveldb took 366263ns > I0210 23:00:45.542071 14679 leveldb.cpp:400] Deleting ~2 keys from leveldb > took 27827ns > I0210 23:00:45.542084 14679 replica.cpp:678] Persisted action at 4 > I0210 23:00:45.542100 14679 replica.cpp:663] Replica learned 3 action at > position 4 > I0210 23:00:45.542534 14686 master.cpp:2262] Processing ACCEPT call for > offers: [ 20150210-230045-3142697795-53677-14658-O0 ] on slave > 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) for framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.542579 14686 master.cpp:2111] Authorizing framework > principal 'test-principal' to launch task 1 as user 'jenkins' > W0210 23:00:45.544143 14686 validation.cpp:326] Executor default for task > 1 uses less CPUs (None) than the minimum required (0.01). Please update > your executor, as this will be mandatory in future releases. > W0210 23:00:45.544186 14686 validation.cpp:338] Executor default for task > 1 uses less memory (None) than the minimum required (32MB). Please update > your executor, as this will be mandatory in future releases. > I0210 23:00:45.544585 14686 master.hpp:819] Adding task 1 with resources > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20150210-230045-3142697795-53677-14658-S0 (pomona.apache.org) > I0210 23:00:45.544728 14686 master.cpp:2523] Launching task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 with > resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on > slave 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) > I0210 23:00:45.545042 14672 slave.cpp:1149] Got assigned task 1 for > framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.545714 14672 slave.cpp:1264] Launching task 1 for framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.547749 14672 slave.cpp:4021] Launching executor default of > framework 20150210-230045-3142697795-53677-14658-0000 in work directory > '/tmp/MasterTest_KillUnknownTask_zfbUpZ/slaves/20150210-230045-3142697795-53677-14658-S0/frameworks/20150210-230045-3142697795-53677-14658-0000/executors/default/runs/a6f161db-1139-40d5-a919-ae1429789ecb' > I0210 23:00:45.549674 14672 exec.cpp:145] Version: 0.22.0 > I0210 23:00:45.549881 14679 exec.cpp:195] Executor started at: > executor(78)@67.195.81.187:53677 with pid 14658 > I0210 23:00:45.550169 14672 slave.cpp:1411] Queuing task '1' for executor > default of framework '20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.550266 14672 slave.cpp:579] Successfully attached file > '/tmp/MasterTest_KillUnknownTask_zfbUpZ/slaves/20150210-230045-3142697795-53677-14658-S0/frameworks/20150210-230045-3142697795-53677-14658-0000/executors/default/runs/a6f161db-1139-40d5-a919-ae1429789ecb' > I0210 23:00:45.744364 14672 slave.cpp:2976] Monitoring executor 'default' > of framework '20150210-230045-3142697795-53677-14658-0000' in container > 'a6f161db-1139-40d5-a919-ae1429789ecb' > I0210 23:00:45.744545 14672 slave.cpp:1998] Got registration for executor > 'default' of framework 20150210-230045-3142697795-53677-14658-0000 from > executor(78)@67.195.81.187:53677 > I0210 23:00:45.745048 14682 exec.cpp:219] Executor registered on slave > 20150210-230045-3142697795-53677-14658-S0 > I0210 23:00:45.745038 14672 slave.cpp:2117] Flushing queued task 1 for > executor 'default' of framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.746606 14682 exec.cpp:231] Executor::registered took 22169ns > I0210 23:00:45.746753 14682 exec.cpp:306] Executor asked to run task '1' > I0210 23:00:45.746814 14682 exec.cpp:315] Executor::launchTask took 44204ns > I0210 23:00:45.748260 14682 exec.cpp:538] Executor sending status update > TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.748428 14682 slave.cpp:2351] Handling status update > TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 from executor(78)@ > 67.195.81.187:53677 > I0210 23:00:45.748693 14686 status_update_manager.cpp:316] Received status > update TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 > of framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.748723 14686 status_update_manager.cpp:493] Creating > StatusUpdate stream for task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.748891 14686 status_update_manager.cpp:370] Forwarding > update TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 > of framework 20150210-230045-3142697795-53677-14658-0000 to the slave > I0210 23:00:45.749066 14682 slave.cpp:2594] Forwarding the update > TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 to > master@67.195.81.187:53677 > I0210 23:00:45.749227 14682 master.cpp:3273] Status update TASK_RUNNING > (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 from slave > 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) > I0210 23:00:45.749253 14682 master.cpp:3314] Forwarding status update > TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.749364 14682 master.cpp:4596] Updating the latest state of > task 1 of framework 20150210-230045-3142697795-53677-14658-0000 to > TASK_RUNNING > I0210 23:00:45.749527 14676 sched.cpp:696] Scheduler::statusUpdate took > 87161ns > I0210 23:00:45.749966 14678 slave.cpp:2521] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.749995 14678 slave.cpp:2527] Sending acknowledgement for > status update TASK_RUNNING (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for > task 1 of framework 20150210-230045-3142697795-53677-14658-0000 to > executor(78)@67.195.81.187:53677 > I0210 23:00:45.750058 14676 master.cpp:2760] Forwarding status update > acknowledgement 2800af94-c3d5-41b2-8064-f4b8521add47 for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 to > slave 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) > I0210 23:00:45.750185 14681 exec.cpp:352] Executor received status update > acknowledgement 2800af94-c3d5-41b2-8064-f4b8521add47 for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.750392 14687 status_update_manager.cpp:388] Received status > update acknowledgement (UUID: 2800af94-c3d5-41b2-8064-f4b8521add47) for > task 1 of framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.750598 14687 slave.cpp:1938] Status update manager > successfully handled status update acknowledgement (UUID: > 2800af94-c3d5-41b2-8064-f4b8521add47) for task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.750645 14684 master.cpp:2596] Asked to kill task 2 of > framework 20150210-230045-3142697795-53677-14658-0000 > W0210 23:00:45.750686 14684 master.cpp:2634] Cannot kill task 2 of > framework 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > because it is unknown; performing reconciliation > I0210 23:00:45.750717 14684 master.cpp:3495] Performing explicit task > state reconciliation for 1 tasks of framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.753000 14684 master.cpp:3579] Sending explicit > reconciliation state TASK_LOST for task 2 of framework > 20150210-230045-3142697795-53677-14658-0000 (default) at > scheduler-3140ea46-2690-411a-b1ea-afe4b326585f@67.195.81.187:53677 > I0210 23:00:45.753226 14684 sched.cpp:696] Scheduler::statusUpdate took > 46506ns > I0210 23:00:45.754654 14658 sched.cpp:1470] Asked to stop the driver > I0210 23:00:45.754755 14684 sched.cpp:808] Stopping framework > '20150210-230045-3142697795-53677-14658-0000' > I0210 23:00:45.754760 14658 master.cpp:786] Master terminating > W0210 23:00:45.754920 14658 master.cpp:4641] Removing task 1 with > resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of > framework 20150210-230045-3142697795-53677-14658-0000 on slave > 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) in non-terminal state TASK_RUNNING > I0210 23:00:45.757603 14672 hierarchical_allocator_process.hpp:642] > Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] > (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; > ports(*):[31000-32000]) on slave 20150210-230045-3142697795-53677-14658-S0 > from framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.757853 14658 master.cpp:4684] Removing executor 'default' > with resources of framework 20150210-230045-3142697795-53677-14658-0000 on > slave 20150210-230045-3142697795-53677-14658-S0 at slave(223)@ > 67.195.81.187:53677 (pomona.apache.org) > I0210 23:00:45.758771 14683 slave.cpp:2759] master@67.195.81.187:53677 > exited > W0210 23:00:45.758801 14683 slave.cpp:2762] Master disconnected! Waiting > for a new master to be elected > I0210 23:00:45.763412 14686 slave.cpp:3034] Executor 'default' of > framework 20150210-230045-3142697795-53677-14658-0000 exited with status 0 > I0210 23:00:45.765964 14686 slave.cpp:2351] Handling status update > TASK_LOST (UUID: e732adc2-7750-41cc-a0f1-37de25328c6c) for task 1 of > framework 20150210-230045-3142697795-53677-14658-0000 from @0.0.0.0:0 > I0210 23:00:45.766047 14686 slave.cpp:4329] Terminating task 1 > I0210 23:00:45.766412 14686 slave.cpp:508] Slave terminating > I0210 23:00:45.766479 14686 slave.cpp:1628] Asked to shut down framework > 20150210-230045-3142697795-53677-14658-0000 by @0.0.0.0:0 > I0210 23:00:45.766499 14686 slave.cpp:1653] Shutting down framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.766551 14686 slave.cpp:3143] Cleaning up executor 'default' > of framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.766770 14686 slave.cpp:3222] Cleaning up framework > 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.766798 14673 gc.cpp:55] Scheduling > '/tmp/MasterTest_KillUnknownTask_zfbUpZ/slaves/20150210-230045-3142697795-53677-14658-S0/frameworks/20150210-230045-3142697795-53677-14658-0000/executors/default/runs/a6f161db-1139-40d5-a919-ae1429789ecb' > for gc 6.99999112698074days in the future > I0210 23:00:45.766909 14679 status_update_manager.cpp:278] Closing status > update streams for framework 20150210-230045-3142697795-53677-14658-0000 > I0210 23:00:45.947239 14679 status_update_manager.cpp:524] Cleaning up > status update stream for task 1 of framework > 20150210-230045-3142697795-53677-14658-0000 > [ OK ] MasterTest.KillUnknownTask (695 ms) > [----------] 32 tests from MasterTest (14428 ms total) > > [----------] 6 tests from FilesTest > [ RUN ] FilesTest.DetachTest > Using temporary directory '/tmp/FilesTest_DetachTest_Dw3ksG' > [ OK ] FilesTest.DetachTest (1 ms) > [ RUN ] FilesTest.ReadTest > Using temporary directory '/tmp/FilesTest_ReadTest_ZPb7L2' > I0210 23:00:45.961832 14675 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.964911 14681 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.967784 14684 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.971207 14679 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.974620 14676 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.977623 14673 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > [ OK ] FilesTest.ReadTest (19 ms) > [ RUN ] FilesTest.ResolveTest > Using temporary directory '/tmp/FilesTest_ResolveTest_9i0Y8o' > I0210 23:00:45.981842 14673 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.984969 14679 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:45.988106 14677 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.009490 14685 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.057253 14681 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.162173 14674 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.165534 14683 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.168002 14684 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.170244 14677 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > I0210 23:00:46.172547 14675 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/read.json' > [ OK ] FilesTest.ResolveTest (196 ms) > [ RUN ] FilesTest.DownloadTest > Using temporary directory '/tmp/FilesTest_DownloadTest_7oUK1L' > I0210 23:00:46.198158 14678 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/download.json' > I0210 23:00:46.198493 14683 process.cpp:1108] Sending file at > '/tmp/FilesTest_DownloadTest_7oUK1L/binary' with length 17 > I0210 23:00:46.363972 14680 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/download.json' > I0210 23:00:46.364243 14679 process.cpp:1108] Sending file at > '/tmp/FilesTest_DownloadTest_7oUK1L/black.gif' with length 42 > [ OK ] FilesTest.DownloadTest (194 ms) > [ RUN ] FilesTest.BrowseTest > Using temporary directory '/tmp/FilesTest_BrowseTest_t1u1p9' > I0210 23:00:46.425760 14680 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/browse.json' > I0210 23:00:46.469018 14675 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/browse.json' > I0210 23:00:46.472647 14679 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/browse.json' > I0210 23:00:46.476418 14682 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/browse.json' > I0210 23:00:46.563807 14673 process.cpp:2768] Handling HTTP event for > process 'files' with path: '/files/browse.json' > [ OK ] FilesTest.BrowseTest (196 ms) > [ RUN ] FilesTest.AttachTest > Using temporary directory '/tmp/FilesTest_AttachTest_97Cakx' > [ OK ] FilesTest.AttachTest (3 ms) > [----------] 6 tests from FilesTest (609 ms total) > > [----------] Global test environment tear-down > [==========] 488 tests from 77 test cases ran. (146660 ms total) > [ PASSED ] 487 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] MasterAuthorizationTest.DuplicateRegistration > > 1 FAILED TEST > YOU HAVE 7 DISABLED TESTS > > make[3]: *** [check-local] Error 1 > make[3]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make[2]: *** [check-am] Error 2 > make[2]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make[1]: *** [check] Error 2 > make[1]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src > '> > make: *** [check-recursive] Error 1 > Test finished > Exit status: 2 > Build step 'Execute shell' marked build as failure > Recording test results >
