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
