John— it seems like a flaky test that revealed itself before recent commits.
On Wed, Feb 11, 2015 at 1:25 AM, John Pampuch <[email protected]> wrote: > So did you break the build? > > -John > > > > On Tue, Feb 10, 2015 at 3:35 PM, Niklas Nielsen <[email protected]> > wrote: > > > Already reported: https://issues.apache.org/jira/browse/MESOS-2239 > > > > On 10 February 2015 at 15:01, Apache Jenkins Server < > > [email protected]> 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 > > > [email protected]:53677 > > > I0210 23:00:45.351428 14677 sched.cpp:248] New master detected at > > > [email protected]:53677 > > > I0210 23:00:45.351480 14676 slave.cpp:689] Authenticating with master > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 [email protected]: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 > > > [email protected]: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 [email protected]:53677 > > > I0210 23:00:45.530869 14683 sched.cpp:515] Sending registration request > > to > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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 > > > [email protected]: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] [email protected]: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 > > > > > >
