See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2164/changes>
Changes: [benjamin.hindman] Update the Mesos Style Guide with C++11 and naming notes. ------------------------------------------ [...truncated 46788 lines...] I1013 06:55:13.781483 11857 registrar.cpp:313] Recovering registrar I1013 06:55:13.781533 11850 recover.cpp:542] Updating replica status to VOTING I1013 06:55:13.782021 11852 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 412351ns I1013 06:55:13.782034 11852 replica.cpp:320] Persisted replica status to VOTING I1013 06:55:13.782129 11853 recover.cpp:556] Successfully joined the Paxos group I1013 06:55:13.782202 11853 recover.cpp:440] Recover process terminated I1013 06:55:13.782878 11856 log.cpp:656] Attempting to start the writer I1013 06:55:13.783583 11843 replica.cpp:474] Replica received implicit promise request with proposal 1 I1013 06:55:13.783877 11843 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 276997ns I1013 06:55:13.783890 11843 replica.cpp:342] Persisted promised to 1 I1013 06:55:13.784231 11845 coordinator.cpp:230] Coordinator attemping to fill missing position I1013 06:55:13.785022 11850 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I1013 06:55:13.785440 11850 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 391105ns I1013 06:55:13.785460 11850 replica.cpp:676] Persisted action at 0 I1013 06:55:13.786133 11858 replica.cpp:508] Replica received write request for position 0 I1013 06:55:13.786164 11858 leveldb.cpp:438] Reading position from leveldb took 14194ns I1013 06:55:13.786476 11858 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 296966ns I1013 06:55:13.786494 11858 replica.cpp:676] Persisted action at 0 I1013 06:55:13.786794 11857 replica.cpp:655] Replica received learned notice for position 0 I1013 06:55:13.787106 11857 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 294687ns I1013 06:55:13.787119 11857 replica.cpp:676] Persisted action at 0 I1013 06:55:13.787127 11857 replica.cpp:661] Replica learned NOP action at position 0 I1013 06:55:13.787399 11845 log.cpp:672] Writer started with ending position 0 I1013 06:55:13.787829 11851 leveldb.cpp:438] Reading position from leveldb took 18489ns I1013 06:55:13.788929 11847 registrar.cpp:346] Successfully fetched the registry (0B) in 7.420928ms I1013 06:55:13.788966 11847 registrar.cpp:445] Applied 1 operations in 2480ns; attempting to update the 'registry' I1013 06:55:13.790426 11847 log.cpp:680] Attempting to append 139 bytes to the log I1013 06:55:13.790506 11854 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I1013 06:55:13.791158 11855 replica.cpp:508] Replica received write request for position 1 I1013 06:55:13.791594 11855 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 412813ns I1013 06:55:13.791615 11855 replica.cpp:676] Persisted action at 1 I1013 06:55:13.792069 11848 replica.cpp:655] Replica received learned notice for position 1 I1013 06:55:13.792471 11848 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 381244ns I1013 06:55:13.792490 11848 replica.cpp:676] Persisted action at 1 I1013 06:55:13.792497 11848 replica.cpp:661] Replica learned APPEND action at position 1 I1013 06:55:13.792872 11847 registrar.cpp:490] Successfully updated the 'registry' in 3.88096ms I1013 06:55:13.792935 11847 registrar.cpp:376] Successfully recovered registrar I1013 06:55:13.792955 11855 log.cpp:699] Attempting to truncate the log to 1 I1013 06:55:13.793037 11851 master.cpp:1100] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register I1013 06:55:13.793048 11846 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I1013 06:55:13.793566 11844 replica.cpp:508] Replica received write request for position 2 I1013 06:55:13.793968 11844 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 381193ns I1013 06:55:13.793984 11844 replica.cpp:676] Persisted action at 2 I1013 06:55:13.794348 11843 replica.cpp:655] Replica received learned notice for position 2 I1013 06:55:13.794708 11843 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 337397ns I1013 06:55:13.794738 11843 leveldb.cpp:401] Deleting ~1 keys from leveldb took 14117ns I1013 06:55:13.794749 11843 replica.cpp:676] Persisted action at 2 I1013 06:55:13.794756 11843 replica.cpp:661] Replica learned TRUNCATE action at position 2 I1013 06:55:13.806016 11851 slave.cpp:169] Slave started on 159)@67.195.81.187:35596 I1013 06:55:13.806049 11851 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/credential' I1013 06:55:13.806145 11851 slave.cpp:276] Slave using credential for: test-principal I1013 06:55:13.806242 11851 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1013 06:55:13.806354 11851 slave.cpp:318] Slave hostname: pomona.apache.org I1013 06:55:13.806366 11851 slave.cpp:319] Slave checkpoint: false I1013 06:55:13.806711 11851 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/meta' I1013 06:55:13.806882 11852 status_update_manager.cpp:193] Recovering status update manager I1013 06:55:13.807006 11852 slave.cpp:3329] Finished recovery I1013 06:55:13.807307 11854 slave.cpp:600] New master detected at [email protected]:35596 I1013 06:55:13.807364 11854 slave.cpp:674] Authenticating with master [email protected]:35596 I1013 06:55:13.807386 11852 status_update_manager.cpp:167] New master detected at [email protected]:35596 I1013 06:55:13.807500 11854 slave.cpp:647] Detecting new master I1013 06:55:13.807515 11843 authenticatee.hpp:133] Creating new client SASL connection I1013 06:55:13.807605 11851 master.cpp:3787] Authenticating slave(159)@67.195.81.187:35596 I1013 06:55:13.807672 11847 authenticator.hpp:161] Creating new server SASL connection I1013 06:55:13.807740 11846 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1013 06:55:13.807757 11846 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1013 06:55:13.807811 11856 authenticator.hpp:267] Received SASL authentication start I1013 06:55:13.807848 11856 authenticator.hpp:389] Authentication requires more steps I1013 06:55:13.807883 11856 authenticatee.hpp:270] Received SASL authentication step I1013 06:55:13.807920 11856 authenticator.hpp:295] Received SASL authentication step I1013 06:55:13.807932 11856 auxprop.cpp:81] 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 I1013 06:55:13.947485 11856 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1013 06:55:13.947520 11856 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1013 06:55:13.947537 11856 auxprop.cpp:81] 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 I1013 06:55:13.947543 11856 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1013 06:55:13.947547 11856 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1013 06:55:13.947562 11856 authenticator.hpp:381] Authentication success I1013 06:55:13.947631 11856 authenticatee.hpp:310] Authentication success I1013 06:55:13.947677 11849 master.cpp:3827] Successfully authenticated principal 'test-principal' at slave(159)@67.195.81.187:35596 I1013 06:55:13.947707 11856 slave.cpp:731] Successfully authenticated with master [email protected]:35596 I1013 06:55:13.947753 11856 slave.cpp:1048] Will retry registration in 19.585195ms if necessary I1013 06:55:13.947811 11849 master.cpp:2968] Registering slave at slave(159)@67.195.81.187:35596 (pomona.apache.org) with id 20141013-065513-3142697795-35596-11829-0 I1013 06:55:13.947939 11855 registrar.cpp:445] Applied 1 operations in 14203ns; attempting to update the 'registry' I1013 06:55:13.949022 11846 log.cpp:680] Attempting to append 315 bytes to the log I1013 06:55:13.949098 11845 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1013 06:55:13.949514 11848 replica.cpp:508] Replica received write request for position 3 I1013 06:55:13.949723 11848 leveldb.cpp:343] Persisting action (334 bytes) to leveldb took 190536ns I1013 06:55:13.949735 11848 replica.cpp:676] Persisted action at 3 I1013 06:55:13.949945 11858 replica.cpp:655] Replica received learned notice for position 3 I1013 06:55:13.950373 11858 leveldb.cpp:343] Persisting action (336 bytes) to leveldb took 411768ns I1013 06:55:13.950387 11858 replica.cpp:676] Persisted action at 3 I1013 06:55:13.950393 11858 replica.cpp:661] Replica learned APPEND action at position 3 I1013 06:55:13.950708 11851 registrar.cpp:490] Successfully updated the 'registry' in 2.745856ms I1013 06:55:13.950790 11858 log.cpp:699] Attempting to truncate the log to 3 I1013 06:55:13.950845 11852 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1013 06:55:13.950880 11844 slave.cpp:2403] Received ping from slave-observer(132)@67.195.81.187:35596 I1013 06:55:13.950896 11848 master.cpp:3022] Registered slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1013 06:55:13.950947 11858 hierarchical_allocator_process.hpp:442] Added slave 20141013-065513-3142697795-35596-11829-0 (pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1013 06:55:13.950980 11844 slave.cpp:765] Registered with master [email protected]:35596; given slave ID 20141013-065513-3142697795-35596-11829-0 I1013 06:55:13.950994 11858 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141013-065513-3142697795-35596-11829-0 in 10146ns I1013 06:55:13.951243 11849 replica.cpp:508] Replica received write request for position 4 I1013 06:55:13.951553 11849 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 293489ns I1013 06:55:13.951567 11849 replica.cpp:676] Persisted action at 4 I1013 06:55:13.951747 11843 replica.cpp:655] Replica received learned notice for position 4 I1013 06:55:13.952057 11843 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 290184ns I1013 06:55:13.952085 11843 leveldb.cpp:401] Deleting ~2 keys from leveldb took 14819ns I1013 06:55:13.952093 11843 replica.cpp:676] Persisted action at 4 I1013 06:55:13.952100 11843 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1013 06:55:13.954061 11829 sched.cpp:137] Version: 0.21.0 I1013 06:55:13.954418 11846 sched.cpp:233] New master detected at [email protected]:35596 I1013 06:55:13.954437 11846 sched.cpp:283] Authenticating with master [email protected]:35596 I1013 06:55:13.954541 11843 authenticatee.hpp:133] Creating new client SASL connection I1013 06:55:13.954663 11845 master.cpp:3787] Authenticating [email protected]:35596 I1013 06:55:13.954788 11856 authenticator.hpp:161] Creating new server SASL connection I1013 06:55:13.954877 11851 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1013 06:55:13.954900 11851 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1013 06:55:13.954964 11850 authenticator.hpp:267] Received SASL authentication start I1013 06:55:13.955014 11850 authenticator.hpp:389] Authentication requires more steps I1013 06:55:13.955061 11850 authenticatee.hpp:270] Received SASL authentication step I1013 06:55:13.955109 11850 authenticator.hpp:295] Received SASL authentication step I1013 06:55:13.955127 11850 auxprop.cpp:81] 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 I1013 06:55:13.955133 11850 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1013 06:55:13.955144 11850 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1013 06:55:13.955154 11850 auxprop.cpp:81] 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 I1013 06:55:13.955160 11850 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1013 06:55:13.955170 11850 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1013 06:55:13.955190 11850 authenticator.hpp:381] Authentication success I1013 06:55:13.955231 11851 authenticatee.hpp:310] Authentication success I1013 06:55:13.955246 11850 master.cpp:3827] Successfully authenticated principal 'test-principal' at [email protected]:35596 I1013 06:55:13.955350 11851 sched.cpp:357] Successfully authenticated with master [email protected]:35596 I1013 06:55:13.955368 11851 sched.cpp:476] Sending registration request to [email protected]:35596 I1013 06:55:13.955427 11849 master.cpp:1362] Received registration request for framework 'default' at [email protected]:35596 I1013 06:55:13.955453 11849 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role '*' I1013 06:55:13.955591 11851 master.cpp:1426] Registering framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:13.955687 11848 hierarchical_allocator_process.hpp:329] Added framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:13.955756 11848 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141013-065513-3142697795-35596-11829-0 to framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:13.955766 11847 sched.cpp:407] Framework registered with 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:13.955817 11847 sched.cpp:421] Scheduler::registered took 22270ns I1013 06:55:13.955890 11848 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 182130ns I1013 06:55:13.956010 11852 master.cpp:3729] Sending 1 offers to framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:14.118634 11852 sched.cpp:544] Scheduler::resourceOffers took 137932ns I1013 06:55:14.118854 11846 master.cpp:2315] Processing reply for offers: [ 20141013-065513-3142697795-35596-11829-0 ] on slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) for framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 W1013 06:55:14.118887 11846 master.cpp:1969] Executor default for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1013 06:55:14.118901 11846 master.cpp:1980] Executor default for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1013 06:55:14.118911 11846 master.cpp:2397] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' I1013 06:55:14.119192 11848 master.hpp:869] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141013-065513-3142697795-35596-11829-0 (pomona.apache.org) I1013 06:55:14.119220 11848 master.cpp:2463] Launching task 0 of framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 with resources cpus(*):2; mem(*):1024 on slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:14.119330 11845 slave.cpp:1079] Got assigned task 0 for framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.119459 11850 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141013-065513-3142697795-35596-11829-0 from framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.119482 11850 hierarchical_allocator_process.hpp:599] Framework 20141013-065513-3142697795-35596-11829-0000 filtered slave 20141013-065513-3142697795-35596-11829-0 for 5secs I1013 06:55:14.119513 11845 slave.cpp:1189] Launching task 0 for framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.120740 11845 slave.cpp:3733] Launching executor default of framework 20141013-065513-3142697795-35596-11829-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/slaves/20141013-065513-3142697795-35596-11829-0/frameworks/20141013-065513-3142697795-35596-11829-0000/executors/default/runs/a1a50655-6d4b-4698-a551-32ebacc9ef52' I1013 06:55:14.121753 11845 exec.cpp:132] Version: 0.21.0 I1013 06:55:14.121809 11848 exec.cpp:182] Executor started at: executor(42)@67.195.81.187:35596 with pid 11829 I1013 06:55:14.121841 11845 slave.cpp:1303] Queuing task '0' for executor default of framework '20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.121872 11845 slave.cpp:556] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/slaves/20141013-065513-3142697795-35596-11829-0/frameworks/20141013-065513-3142697795-35596-11829-0000/executors/default/runs/a1a50655-6d4b-4698-a551-32ebacc9ef52' I1013 06:55:14.121899 11845 slave.cpp:2669] Monitoring executor 'default' of framework '20141013-065513-3142697795-35596-11829-0000' in container 'a1a50655-6d4b-4698-a551-32ebacc9ef52' I1013 06:55:14.121961 11845 slave.cpp:1814] Got registration for executor 'default' of framework 20141013-065513-3142697795-35596-11829-0000 from executor(42)@67.195.81.187:35596 I1013 06:55:14.122040 11845 slave.cpp:1933] Flushing queued task 0 for executor 'default' of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.122079 11852 exec.cpp:206] Executor registered on slave 20141013-065513-3142697795-35596-11829-0 I1013 06:55:14.122962 11852 exec.cpp:218] Executor::registered took 14233ns I1013 06:55:14.123011 11852 exec.cpp:293] Executor asked to run task '0' I1013 06:55:14.123036 11852 exec.cpp:302] Executor::launchTask took 17252ns I1013 06:55:14.123905 11852 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.123993 11852 slave.cpp:2167] Handling status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 from executor(42)@67.195.81.187:35596 I1013 06:55:14.124097 11857 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124119 11857 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124183 11857 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 to [email protected]:35596 I1013 06:55:14.124290 11848 master.cpp:3352] Forwarding status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124336 11848 master.cpp:3324] Status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 from slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:14.124341 11849 slave.cpp:2324] Status update manager successfully handled status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124359 11849 slave.cpp:2330] Sending acknowledgement for status update TASK_RUNNING (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 to executor(42)@67.195.81.187:35596 I1013 06:55:14.124408 11857 sched.cpp:635] Scheduler::statusUpdate took 25671ns I1013 06:55:14.124419 11852 exec.cpp:339] Executor received status update acknowledgement 1c217343-bff2-40b5-8442-18fba92fb8b2 for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124588 11854 master.cpp:2813] Forwarding status update acknowledgement 1c217343-bff2-40b5-8442-18fba92fb8b2 for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 to slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:14.124837 11857 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.124936 11851 slave.cpp:2727] Executor 'default' of framework 20141013-065513-3142697795-35596-11829-0000 exited with status 0 I1013 06:55:14.126200 11851 slave.cpp:2167] Handling status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 from @0.0.0.0:0 I1013 06:55:14.126230 11851 slave.cpp:4041] Terminating task 0 I1013 06:55:14.126373 11851 slave.cpp:1754] Status update manager successfully handled status update acknowledgement (UUID: 1c217343-bff2-40b5-8442-18fba92fb8b2) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.126458 11851 status_update_manager.cpp:320] Received status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:14.126469 11846 master.cpp:3404] Executor default of framework 20141013-065513-3142697795-35596-11829-0000 on slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) exited with status 0 I1013 06:55:14.126480 11851 status_update_manager.cpp:373] Forwarding status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 to [email protected]:35596 I1013 06:55:14.126497 11846 master.cpp:4571] Removing executor 'default' with resources of framework 20141013-065513-3142697795-35596-11829-0000 on slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:16.009373 11851 slave.cpp:2324] Status update manager successfully handled status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009500 11846 master.cpp:3352] Forwarding status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009544 11846 master.cpp:3324] Status update TASK_LOST (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 from slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:16.009639 11858 sched.cpp:635] Scheduler::statusUpdate took 33664ns I1013 06:55:16.009670 11857 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141013-065513-3142697795-35596-11829-0 from framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009717 11846 master.cpp:4542] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141013-065513-3142697795-35596-11829-0000 on slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:16.009754 11846 master.cpp:2813] Forwarding status update acknowledgement e9d15410-2d17-41f1-8bfb-fb7330cae2fc for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 to slave 20141013-065513-3142697795-35596-11829-0 at slave(159)@67.195.81.187:35596 (pomona.apache.org) I1013 06:55:16.009853 11846 status_update_manager.cpp:398] Received status update acknowledgement (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009881 11846 status_update_manager.cpp:530] Cleaning up status update stream for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009969 11849 slave.cpp:1754] Status update manager successfully handled status update acknowledgement (UUID: e9d15410-2d17-41f1-8bfb-fb7330cae2fc) for task 0 of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.009989 11849 slave.cpp:4080] Completing task 0 I1013 06:55:16.009996 11849 slave.cpp:2863] Cleaning up executor 'default' of framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.010115 11845 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/slaves/20141013-065513-3142697795-35596-11829-0/frameworks/20141013-065513-3142697795-35596-11829-0000/executors/default/runs/a1a50655-6d4b-4698-a551-32ebacc9ef52' for gc 1.00000310060825weeks in the future I1013 06:55:16.010125 11849 slave.cpp:2938] Cleaning up framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.010176 11845 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/slaves/20141013-065513-3142697795-35596-11829-0/frameworks/20141013-065513-3142697795-35596-11829-0000/executors/default' for gc 1.00000310060825weeks in the future I1013 06:55:16.010220 11845 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_DiskUsage_iHuEQl/slaves/20141013-065513-3142697795-35596-11829-0/frameworks/20141013-065513-3142697795-35596-11829-0000' for gc 1.00000310060825weeks in the future I1013 06:55:16.010275 11846 status_update_manager.cpp:282] Closing status update streams for framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.027873 11856 slave.cpp:3184] Current usage 90.00%. Max allowed age: 0ns I1013 06:55:16.027982 11856 hierarchical_allocator_process.hpp:734] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20141013-065513-3142697795-35596-11829-0 to framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.028072 11856 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 142148ns I1013 06:55:16.028149 11856 master.cpp:3729] Sending 1 offers to framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:16.028250 11856 sched.cpp:544] Scheduler::resourceOffers took 11862ns tests/gc_tests.cpp:675: Failure Value of: os::exists(executorDir) Actual: true Expected: false I1013 06:55:16.048317 11857 master.cpp:768] Framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 disconnected I1013 06:55:16.048336 11857 master.cpp:1731] Disconnecting framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:16.048347 11857 master.cpp:1747] Deactivating framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:16.048431 11853 hierarchical_allocator_process.hpp:405] Deactivated framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.048457 11857 master.cpp:790] Giving framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 0ns to failover I1013 06:55:16.048547 11846 master.cpp:3599] Framework failover timeout, removing framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:16.048539 11853 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141013-065513-3142697795-35596-11829-0 from framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.048566 11846 master.cpp:4128] Removing framework 20141013-065513-3142697795-35596-11829-0000 (default) at [email protected]:35596 I1013 06:55:16.048655 11855 slave.cpp:1487] Asked to shut down framework 20141013-065513-3142697795-35596-11829-0000 by [email protected]:35596 W1013 06:55:16.048677 11855 slave.cpp:1502] Cannot shut down unknown framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.048719 11856 hierarchical_allocator_process.hpp:360] Removed framework 20141013-065513-3142697795-35596-11829-0000 I1013 06:55:16.049263 11829 master.cpp:677] Master terminating I1013 06:55:16.049412 11849 slave.cpp:2488] [email protected]:35596 exited W1013 06:55:16.049428 11849 slave.cpp:2491] Master disconnected! Waiting for a new master to be elected F1013 06:55:16.049428 11829 logging.cpp:57] RAW: Pure virtual method called @ 0x2b60391efddd google::LogMessage::Fail() @ 0x2b60391f74d6 google::RawLog__() @ 0x508886 mesos::internal::tests::Cluster::Slaves::shutdown() @ 0x7b7d84 mesos::internal::tests::MesosTest::ShutdownSlaves() @ 0x9b91d3 testing::internal::HandleExceptionsInMethodIfSupported<>() @ 0x9b0370 testing::Test::Run() @ 0x9b044e testing::TestInfo::Run() @ 0x9b0555 testing::TestCase::Run() @ 0x9b07f8 testing::internal::UnitTestImpl::RunAllTests() @ 0x9b0a97 testing::UnitTest::Run() @ 0x493493 main @ 0x2b603b11fec5 (unknown) @ 0x4a0b68 (unknown) make[3]: *** [check-local] Aborted 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
