See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2456/changes>
Changes: [vinodkone] Fixed mesos git repo url in docs to avoid git clone failure. ------------------------------------------ [...truncated 78638 lines...] I0211 18:43:04.823547 15980 leveldb.cpp:400] Deleting ~1 keys from leveldb took 32484ns I0211 18:43:04.823583 15980 replica.cpp:678] Persisted action at 2 I0211 18:43:04.823623 15980 replica.cpp:663] Replica learned 3 action at position 2 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@716: Client environment:host.name=pomona.apache.org 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@723: Client environment:os.name=Linux 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@724: Client environment:os.arch=3.13.0-36-lowlatency 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@725: Client environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@733: Client environment:user.name=jenkins I0211 18:43:04.830530 15986 slave.cpp:174] Slave started on 226)@67.195.81.187:36720 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins I0211 18:43:04.830631 15986 credentials.hpp:84] Loading credential for authentication from '/tmp/RegistrarZooKeeperTest_TaskRunning_I3RXGd/credential' 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@log_env@753: Client environment:user.dir=/tmp/RegistrarZooKeeperTest_TaskRunning_HyaAMn 2015-02-11 18:43:04,830:15965(0x2ab54480b700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:60788 sessionTimeout=10000 watcher=0x2ab53f7d3554 sessionId=0 sessionPasswd=<null> context=0x2ab588015780 flags=0 I0211 18:43:04.830772 15986 slave.cpp:281] Slave using credential for: test-principal I0211 18:43:04.830951 15986 slave.cpp:299] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] 2015-02-11 18:43:04,830:15965(0x2ab8c750c700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:60788] I0211 18:43:04.831035 15986 slave.cpp:328] Slave hostname: pomona.apache.org I0211 18:43:04.831050 15986 slave.cpp:329] Slave checkpoint: false W0211 18:43:04.831058 15986 slave.cpp:331] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I0211 18:43:04.831739 15992 state.cpp:34] Recovering state from '/tmp/RegistrarZooKeeperTest_TaskRunning_I3RXGd/meta' 2015-02-11 18:43:04,831:15965(0x2ab8c750c700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:60788], sessionId=0x14b79f3a1bd0004, negotiated timeout=10000 I0211 18:43:04.832020 15991 status_update_manager.cpp:196] Recovering status update manager I0211 18:43:04.832653 15992 group.cpp:313] Group process (group(58)@67.195.81.187:36720) connected to ZooKeeper I0211 18:43:04.832681 15992 group.cpp:790] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0) I0211 18:43:04.832682 15994 slave.cpp:3643] Finished recovery I0211 18:43:04.832691 15992 group.cpp:385] Trying to create path '/znode' in ZooKeeper I0211 18:43:04.837162 15992 group.cpp:717] Found non-sequence node 'log_replicas' at '/znode' in ZooKeeper I0211 18:43:04.837323 15992 detector.cpp:138] Detected a new leader: (id='1') I0211 18:43:04.837568 15990 group.cpp:659] Trying to get '/znode/info_0000000001' in ZooKeeper I0211 18:43:04.838148 15965 sched.cpp:151] Version: 0.22.0 I0211 18:43:04.842516 15987 detector.cpp:451] A new leading master ([email protected]:36720) is detected I0211 18:43:04.842572 15980 sched.cpp:248] New master detected at [email protected]:36720 I0211 18:43:04.842671 15980 sched.cpp:304] Authenticating with master [email protected]:36720 I0211 18:43:04.842705 15980 sched.cpp:311] Using default CRAM-MD5 authenticatee I0211 18:43:04.842742 15985 status_update_manager.cpp:170] Pausing sending status updates I0211 18:43:04.842746 15989 slave.cpp:624] New master detected at [email protected]:36720 I0211 18:43:04.842864 15989 slave.cpp:687] Authenticating with master [email protected]:36720 I0211 18:43:04.842892 15993 authenticatee.hpp:138] Creating new client SASL connection I0211 18:43:04.842898 15989 slave.cpp:692] Using default CRAM-MD5 authenticatee I0211 18:43:04.843061 15993 authenticatee.hpp:138] Creating new client SASL connection I0211 18:43:04.843091 15987 master.cpp:3810] Authenticating [email protected]:36720 I0211 18:43:04.843121 15987 master.cpp:3821] Using default CRAM-MD5 authenticator I0211 18:43:04.843353 15988 authenticator.hpp:169] Creating new server SASL connection I0211 18:43:04.843475 15982 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0211 18:43:04.843504 15982 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0211 18:43:04.843643 15982 authenticator.hpp:275] Received SASL authentication start I0211 18:43:04.843691 15982 authenticator.hpp:397] Authentication requires more steps I0211 18:43:04.843816 15981 authenticatee.hpp:275] Received SASL authentication step I0211 18:43:04.844019 15993 authenticator.hpp:303] Received SASL authentication step I0211 18:43:04.844033 15987 master.cpp:3810] Authenticating slave(226)@67.195.81.187:36720 I0211 18:43:04.844054 15987 master.cpp:3821] Using default CRAM-MD5 authenticator I0211 18:43:04.844058 15993 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 I0211 18:43:04.844076 15993 auxprop.cpp:170] Looking up auxiliary property '*userPassword' I0211 18:43:04.844135 15993 auxprop.cpp:170] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0211 18:43:04.844182 15993 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 I0211 18:43:04.844208 15993 auxprop.cpp:120] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0211 18:43:04.844226 15993 auxprop.cpp:120] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0211 18:43:04.844256 15993 authenticator.hpp:389] Authentication success I0211 18:43:04.844326 15981 authenticator.hpp:169] Creating new server SASL connection I0211 18:43:04.844347 15989 slave.cpp:660] Detecting new master I0211 18:43:04.844400 15980 authenticatee.hpp:315] Authentication success I0211 18:43:04.844483 15993 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I0211 18:43:04.844499 15987 master.cpp:3868] Successfully authenticated principal 'test-principal' at [email protected]:36720 I0211 18:43:04.844511 15993 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I0211 18:43:04.844638 15985 authenticator.hpp:275] Received SASL authentication start I0211 18:43:04.844694 15985 authenticator.hpp:397] Authentication requires more steps I0211 18:43:04.845332 15981 sched.cpp:392] Successfully authenticated with master [email protected]:36720 I0211 18:43:04.845352 15981 sched.cpp:515] Sending registration request to [email protected]:36720 I0211 18:43:04.845393 15993 authenticatee.hpp:275] Received SASL authentication step I0211 18:43:04.845396 15981 sched.cpp:548] Will retry registration in 1.424635064secs if necessary I0211 18:43:04.845470 15985 master.cpp:1571] Received registration request for framework 'default' at [email protected]:36720 I0211 18:43:04.845515 15979 authenticator.hpp:303] Received SASL authentication step I0211 18:43:04.845540 15985 master.cpp:1432] Authorizing framework principal 'test-principal' to receive offers for role '*' I0211 18:43:04.845546 15979 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 I0211 18:43:04.845573 15979 auxprop.cpp:170] Looking up auxiliary property '*userPassword' I0211 18:43:04.845607 15979 auxprop.cpp:170] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0211 18:43:04.845625 15979 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 I0211 18:43:04.845638 15979 auxprop.cpp:120] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0211 18:43:04.845648 15979 auxprop.cpp:120] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0211 18:43:04.845666 15979 authenticator.hpp:389] Authentication success I0211 18:43:04.845742 15990 authenticatee.hpp:315] Authentication success I0211 18:43:04.845814 15991 master.cpp:3868] Successfully authenticated principal 'test-principal' at slave(226)@67.195.81.187:36720 I0211 18:43:04.848587 15986 slave.cpp:758] Successfully authenticated with master [email protected]:36720 I0211 18:43:04.848667 15991 master.cpp:1635] Registering framework 20150211-184304-3142697795-36720-15965-0000 (default) at [email protected]:36720 I0211 18:43:04.848709 15986 slave.cpp:1092] Will retry registration in 1.971482ms if necessary I0211 18:43:04.848937 15986 hierarchical_allocator_process.hpp:318] Added framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.848974 15986 hierarchical_allocator_process.hpp:828] No resources available to allocate! I0211 18:43:04.848991 15986 hierarchical_allocator_process.hpp:735] Performed allocation for 0 slaves in 28462ns I0211 18:43:04.849036 15994 sched.cpp:442] Framework registered with 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.849073 15994 sched.cpp:456] Scheduler::registered took 15730ns I0211 18:43:04.849133 15991 master.cpp:2935] Registering slave at slave(226)@67.195.81.187:36720 (pomona.apache.org) with id 20150211-184304-3142697795-36720-15965-S0 I0211 18:43:04.849494 15988 registrar.cpp:444] Applied 1 operations in 47159ns; attempting to update the 'registry' I0211 18:43:04.851191 15981 slave.cpp:1092] Will retry registration in 34.065885ms if necessary I0211 18:43:04.851296 15991 master.cpp:2923] Ignoring register slave message from slave(226)@67.195.81.187:36720 (pomona.apache.org) as admission is already in progress I0211 18:43:04.852504 15994 log.cpp:683] Attempting to append 316 bytes to the log I0211 18:43:04.852668 15991 coordinator.cpp:339] Coordinator attempting to write 2 action at position 3 I0211 18:43:04.853734 15985 replica.cpp:510] Replica received write request for position 3 I0211 18:43:04.854369 15985 leveldb.cpp:342] Persisting action (335 bytes) to leveldb took 596235ns I0211 18:43:04.854405 15985 replica.cpp:678] Persisted action at 3 I0211 18:43:04.855046 15985 replica.cpp:657] Replica received learned notice for position 3 I0211 18:43:04.855614 15985 leveldb.cpp:342] Persisting action (337 bytes) to leveldb took 538564ns I0211 18:43:04.855649 15985 replica.cpp:678] Persisted action at 3 I0211 18:43:04.855674 15985 replica.cpp:663] Replica learned 2 action at position 3 I0211 18:43:04.856739 15990 registrar.cpp:489] Successfully updated the 'registry' in 7.186944ms I0211 18:43:04.856983 15983 log.cpp:702] Attempting to truncate the log to 3 I0211 18:43:04.857115 15993 coordinator.cpp:339] Coordinator attempting to write 3 action at position 4 I0211 18:43:04.857565 15991 slave.cpp:2698] Received ping from slave-observer(206)@67.195.81.187:36720 I0211 18:43:04.857731 15986 master.cpp:2992] Registered slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0211 18:43:04.857882 15994 hierarchical_allocator_process.hpp:450] Added slave 20150211-184304-3142697795-36720-15965-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) I0211 18:43:04.858124 15991 slave.cpp:792] Registered with master [email protected]:36720; given slave ID 20150211-184304-3142697795-36720-15965-S0 I0211 18:43:04.858145 15981 replica.cpp:510] Replica received write request for position 4 I0211 18:43:04.858201 15984 status_update_manager.cpp:177] Resuming sending status updates I0211 18:43:04.858340 15994 hierarchical_allocator_process.hpp:753] Performed allocation for slave 20150211-184304-3142697795-36720-15965-S0 in 393635ns I0211 18:43:04.858614 15981 leveldb.cpp:342] Persisting action (16 bytes) to leveldb took 437954ns I0211 18:43:04.858635 15980 master.cpp:3752] Sending 1 offers to framework 20150211-184304-3142697795-36720-15965-0000 (default) at [email protected]:36720 I0211 18:43:04.858644 15981 replica.cpp:678] Persisted action at 4 I0211 18:43:04.859096 15993 sched.cpp:605] Scheduler::resourceOffers took 110972ns I0211 18:43:04.859653 15984 replica.cpp:657] Replica received learned notice for position 4 I0211 18:43:04.860183 15984 leveldb.cpp:342] Persisting action (18 bytes) to leveldb took 499258ns I0211 18:43:04.860241 15984 leveldb.cpp:400] Deleting ~2 keys from leveldb took 28767ns I0211 18:43:04.860262 15984 replica.cpp:678] Persisted action at 4 I0211 18:43:04.860314 15984 replica.cpp:663] Replica learned 3 action at position 4 I0211 18:43:04.863456 15986 master.cpp:2265] Processing ACCEPT call for offers: [ 20150211-184304-3142697795-36720-15965-O0 ] on slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) for framework 20150211-184304-3142697795-36720-15965-0000 (default) at [email protected]:36720 I0211 18:43:04.863507 15986 master.cpp:2109] Authorizing framework principal 'test-principal' to launch task 8786d78d-c6b9-41dd-b9c6-31562cee0ade as user 'jenkins' W0211 18:43:04.864908 15986 validation.cpp:326] Executor default for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0211 18:43:04.864953 15986 validation.cpp:338] Executor default for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0211 18:43:04.865350 15986 master.hpp:821] Adding task 8786d78d-c6b9-41dd-b9c6-31562cee0ade with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20150211-184304-3142697795-36720-15965-S0 (pomona.apache.org) I0211 18:43:04.865500 15986 master.cpp:2542] Launching task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 (default) at [email protected]:36720 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) I0211 18:43:04.865890 15979 slave.cpp:1147] Got assigned task 8786d78d-c6b9-41dd-b9c6-31562cee0ade for framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.866569 15979 slave.cpp:1262] Launching task 8786d78d-c6b9-41dd-b9c6-31562cee0ade for framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.869441 15979 slave.cpp:4045] Launching executor default of framework 20150211-184304-3142697795-36720-15965-0000 in work directory '/tmp/RegistrarZooKeeperTest_TaskRunning_I3RXGd/slaves/20150211-184304-3142697795-36720-15965-S0/frameworks/20150211-184304-3142697795-36720-15965-0000/executors/default/runs/f37af926-1fbb-49af-9763-3454765fc3f3' I0211 18:43:04.872493 15979 exec.cpp:145] Version: 0.22.0 I0211 18:43:04.872728 15981 exec.cpp:195] Executor started at: executor(79)@67.195.81.187:36720 with pid 15965 I0211 18:43:04.872884 15979 slave.cpp:1409] Queuing task '8786d78d-c6b9-41dd-b9c6-31562cee0ade' for executor default of framework '20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.873018 15979 slave.cpp:577] Successfully attached file '/tmp/RegistrarZooKeeperTest_TaskRunning_I3RXGd/slaves/20150211-184304-3142697795-36720-15965-S0/frameworks/20150211-184304-3142697795-36720-15965-0000/executors/default/runs/f37af926-1fbb-49af-9763-3454765fc3f3' I0211 18:43:04.873142 15979 slave.cpp:3000] Monitoring executor 'default' of framework '20150211-184304-3142697795-36720-15965-0000' in container 'f37af926-1fbb-49af-9763-3454765fc3f3' I0211 18:43:04.873342 15979 slave.cpp:2022] Got registration for executor 'default' of framework 20150211-184304-3142697795-36720-15965-0000 from executor(79)@67.195.81.187:36720 I0211 18:43:04.873843 15979 slave.cpp:2141] Flushing queued task 8786d78d-c6b9-41dd-b9c6-31562cee0ade for executor 'default' of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.873940 15989 exec.cpp:219] Executor registered on slave 20150211-184304-3142697795-36720-15965-S0 I0211 18:43:04.876353 15989 exec.cpp:231] Executor::registered took 29424ns I0211 18:43:04.876526 15989 exec.cpp:306] Executor asked to run task '8786d78d-c6b9-41dd-b9c6-31562cee0ade' I0211 18:43:04.876590 15989 exec.cpp:315] Executor::launchTask took 44704ns I0211 18:43:04.878227 15989 exec.cpp:538] Executor sending status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.878413 15989 slave.cpp:2375] Handling status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 from executor(79)@67.195.81.187:36720 I0211 18:43:04.878667 15990 status_update_manager.cpp:316] Received status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.878715 15990 status_update_manager.cpp:493] Creating StatusUpdate stream for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.878921 15990 status_update_manager.cpp:370] Forwarding update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 to the slave I0211 18:43:04.879155 15988 slave.cpp:2618] Forwarding the update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 to [email protected]:36720 I0211 18:43:04.879379 15988 slave.cpp:2545] Status update manager successfully handled status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.879413 15988 slave.cpp:2551] Sending acknowledgement for status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 to executor(79)@67.195.81.187:36720 I0211 18:43:04.879537 15989 master.cpp:3292] Status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 from slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) I0211 18:43:04.879564 15982 exec.cpp:352] Executor received status update acknowledgement 841c5247-a154-437a-8e78-2c7d5d68728e for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.879575 15989 master.cpp:3333] Forwarding status update TASK_RUNNING (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.879695 15989 master.cpp:4615] Updating the latest state of task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 to TASK_RUNNING I0211 18:43:04.879813 15980 sched.cpp:696] Scheduler::statusUpdate took 59001ns I0211 18:43:04.880230 15965 sched.cpp:1470] Asked to stop the driver I0211 18:43:04.880399 15981 sched.cpp:808] Stopping framework '20150211-184304-3142697795-36720-15965-0000' I0211 18:43:04.880360 15987 master.cpp:2779] Forwarding status update acknowledgement 841c5247-a154-437a-8e78-2c7d5d68728e for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 (default) at [email protected]:36720 to slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) I0211 18:43:04.880517 15987 master.cpp:784] Master terminating I0211 18:43:04.880619 15982 status_update_manager.cpp:388] Received status update acknowledgement (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 W0211 18:43:04.880620 15987 master.cpp:4668] Removing task 8786d78d-c6b9-41dd-b9c6-31562cee0ade with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 20150211-184304-3142697795-36720-15965-0000 on slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) in non-terminal state TASK_RUNNING I0211 18:43:04.880805 15990 slave.cpp:1962] Status update manager successfully handled status update acknowledgement (UUID: 841c5247-a154-437a-8e78-2c7d5d68728e) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.881053 15982 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 20150211-184304-3142697795-36720-15965-S0 from framework 20150211-184304-3142697795-36720-15965-0000 I0211 18:43:04.881141 15987 master.cpp:4711] Removing executor 'default' with resources of framework 20150211-184304-3142697795-36720-15965-0000 on slave 20150211-184304-3142697795-36720-15965-S0 at slave(226)@67.195.81.187:36720 (pomona.apache.org) I0211 18:43:04.881649 15984 slave.cpp:2783] [email protected]:36720 exited W0211 18:43:04.881685 15984 slave.cpp:2786] Master disconnected! Waiting for a new master to be elected I0211 18:43:04.882604 15965 process.cpp:2117] Dropped / Lost event for PID: [email protected]:36720 I0211 18:43:04.882916 15965 process.cpp:2117] Dropped / Lost event for PID: leader-detector(21)@67.195.81.187:36720 2015-02-11 18:43:04,883:15965(0x2ab53c7da0c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x14b79f3a1bd0003 to [127.0.0.1:60788] I0211 18:43:04.883559 15985 contender.cpp:186] Now cancelling the membership: 1 I0211 18:43:04.883725 15982 group.cpp:619] Trying to remove '/znode/info_0000000001' in ZooKeeper I0211 18:43:04.883801 15965 process.cpp:2117] Dropped / Lost event for PID: [email protected]:36720 I0211 18:43:04.885982 15982 process.cpp:2117] Dropped / Lost event for PID: leader-contender(22)@67.195.81.187:36720 I0211 18:43:04.886054 15982 process.cpp:2117] Dropped / Lost event for PID: leader-contender(22)@67.195.81.187:36720 2015-02-11 18:43:04,886:15965(0x2ab53c7da0c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x14b79f3a1bd0002 to [127.0.0.1:60788] I0211 18:43:04.886719 15988 group.cpp:717] Found non-sequence node 'log_replicas' at '/znode' in ZooKeeper I0211 18:43:04.886983 15984 detector.cpp:126] The current leader (id=1) is lost I0211 18:43:04.887020 15984 detector.cpp:138] Detected a new leader: None I0211 18:43:04.887647 15991 slave.cpp:617] Lost leading master I0211 18:43:04.887680 15991 slave.cpp:660] Detecting new master I0211 18:43:04.887693 15990 status_update_manager.cpp:170] Pausing sending status updates 2015-02-11 18:43:04,888:15965(0x2ab545e16700):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x14b79f3a1bd0001 to [127.0.0.1:60788] I0211 18:43:04.888864 15984 process.cpp:2117] Dropped / Lost event for PID: __executor__(5)@67.195.81.187:36720 I0211 18:43:04.889137 20928 process.cpp:2117] Dropped / Lost event for PID: group(54)@67.195.81.187:36720 2015-02-11 18:43:04,889:15965(0x2ab545612700):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x14b79f3a1bd0000 to [127.0.0.1:60788] I0211 18:43:04.890590 15988 slave.cpp:3058] Executor 'default' of framework 20150211-184304-3142697795-36720-15965-0000 exited with status 0 I0211 18:43:04.893568 15988 slave.cpp:2375] Handling status update TASK_LOST (UUID: 836e695c-75ea-4244-b5a7-76a1a19e4030) for task 8786d78d-c6b9-41dd-b9c6-31562cee0ade of framework 20150211-184304-3142697795-36720-15965-0000 from @0.0.0.0:0 I0211 18:43:04.893666 15988 slave.cpp:4353] Terminating task 8786d78d-c6b9-41dd-b9c6-31562cee0ade I0211 18:43:04.894047 15988 slave.cpp:506] Slave terminating I0211 18:43:04.894130 15988 slave.cpp:1626] Asked to shut down framework 20150211-184304-3142697795-36720-15965-0000 by @0.0.0.0:0 W0211 18:43:04.894151 15988 slave.cpp:1634] Ignoring shutdown framework message for 20150211-184304-3142697795-36720-15965-0000 because the slave has not yet registered with the master I0211 18:43:04.895645 15965 process.cpp:2117] Dropped / Lost event for PID: slave(226)@67.195.81.187:36720 I0211 18:43:04.896131 15965 process.cpp:2117] Dropped / Lost event for PID: leader-detector(22)@67.195.81.187:36720 2015-02-11 18:43:04,896:15965(0x2ab545c15700):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x14b79f3a1bd0004 to [127.0.0.1:60788] I0211 18:43:04.898221 15965 process.cpp:2117] Dropped / Lost event for PID: [email protected]:36720 [ OK ] RegistrarZooKeeperTest.TaskRunning (133 ms) I0211 18:43:04.899407 15965 zookeeper_test_server.cpp:117] Shutting down ZooKeeperTestServer on port 60788 [----------] 1 test from RegistrarZooKeeperTest (133 ms total) [----------] 10 tests from PathsTest [ RUN ] PathsTest.CreateExecutorDirectory [ OK ] PathsTest.CreateExecutorDirectory (3 ms) [ RUN ] PathsTest.Archive [ OK ] PathsTest.Archive (3 ms) [ RUN ] PathsTest.LibProcessPid [ OK ] PathsTest.LibProcessPid (2 ms) [ RUN ] PathsTest.Executor [ OK ] PathsTest.Executor (1 ms) [ RUN ] PathsTest.Slave [ OK ] PathsTest.Slave (2 ms) [ RUN ] PathsTest.PersistentVolume [ OK ] PathsTest.PersistentVolume (2 ms) [ RUN ] PathsTest.Meta [ OK ] PathsTest.Meta (1 ms) [ RUN ] PathsTest.Task [ OK ] PathsTest.Task (2 ms) [ RUN ] PathsTest.Framework [ OK ] PathsTest.Framework (2 ms) [ RUN ] PathsTest.BootId [ OK ] PathsTest.BootId (1 ms) [----------] 10 tests from PathsTest (19 ms total) [----------] Global test environment tear-down [==========] 530 tests from 84 test cases ran. (247392 ms total) [ PASSED ] 529 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ExamplesTest.NoExecutorFramework 1 FAILED TEST YOU HAVE 8 DISABLED TESTS *** Aborted at 1423680185 (unix time) try "date -d @1423680185" if you are using GNU date *** 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
