See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2538/changes>
Changes: [vinodkone] Added slave metrics for total and used resources. [toenshoff] Fixed invalid characters in external-containerizer.md ------------------------------------------ [...truncated 64936 lines...] I1108 02:53:19.338738 25885 slave.cpp:1556] Shutting down framework 20141108-025319-3125920579-41701-25865-0000 I1108 02:53:19.338789 25885 slave.cpp:3005] Cleaning up executor 'default' of framework 20141108-025319-3125920579-41701-25865-0000 I1108 02:53:19.339010 25885 slave.cpp:3082] Cleaning up framework 20141108-025319-3125920579-41701-25865-0000 I1108 02:53:19.339022 25886 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_ic7Pmh/slaves/20141108-025319-3125920579-41701-25865-S0/frameworks/20141108-025319-3125920579-41701-25865-0000/executors/default/runs/277c214d-20f1-4603-84a7-dc0d4c8003f8' for gc 6.99999607806815days in the future I1108 02:53:19.339112 25889 status_update_manager.cpp:279] Closing status update streams for framework 20141108-025319-3125920579-41701-25865-0000 I1108 02:53:19.339190 25889 status_update_manager.cpp:525] Cleaning up status update stream for task 1 of framework 20141108-025319-3125920579-41701-25865-0000 I1108 02:53:19.339259 25886 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_ic7Pmh/slaves/20141108-025319-3125920579-41701-25865-S0/frameworks/20141108-025319-3125920579-41701-25865-0000/executors/default' for gc 6.99999607682667days in the future I1108 02:53:19.339406 25886 gc.cpp:56] Scheduling '/tmp/SchedulerTest_TaskRunning_ic7Pmh/slaves/20141108-025319-3125920579-41701-25865-S0/frameworks/20141108-025319-3125920579-41701-25865-0000' for gc 6.99999607552days in the future [ OK ] SchedulerTest.TaskRunning (95 ms) [----------] 1 test from SchedulerTest (95 ms total) [----------] 3 tests from GarbageCollectorTest [ RUN ] GarbageCollectorTest.Unschedule Using temporary directory '/tmp/GarbageCollectorTest_Unschedule_N4q1Wq' I1108 02:53:19.342380 25886 gc.cpp:84] Unscheduling 'bogus' from gc I1108 02:53:19.342840 25888 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1108 02:53:19.342973 25888 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1108 02:53:19.343062 25888 gc.cpp:56] Scheduling 'file3' for gc 10secs in the future I1108 02:53:19.343144 25888 gc.cpp:84] Unscheduling 'file2' from gc I1108 02:53:19.353092 25891 gc.cpp:84] Unscheduling 'file3' from gc I1108 02:53:19.363229 25883 gc.cpp:84] Unscheduling 'file1' from gc I1108 02:53:19.374130 25891 gc.cpp:155] Ignoring gc event at 0ns as the paths were already removed, or were unscheduled [ OK ] GarbageCollectorTest.Unschedule (72 ms) [ RUN ] GarbageCollectorTest.Schedule Using temporary directory '/tmp/GarbageCollectorTest_Schedule_4L5TIA' I1108 02:53:19.414825 25881 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1108 02:53:19.414985 25881 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1108 02:53:19.415099 25881 gc.cpp:56] Scheduling 'file3' for gc 15secs in the future I1108 02:53:19.455865 25884 gc.cpp:134] Deleting file1 I1108 02:53:19.455934 25884 gc.cpp:143] Deleted 'file1' I1108 02:53:19.455965 25884 gc.cpp:134] Deleting file2 I1108 02:53:19.455997 25884 gc.cpp:143] Deleted 'file2' I1108 02:53:19.486083 25888 gc.cpp:134] Deleting file3 I1108 02:53:19.486142 25888 gc.cpp:143] Deleted 'file3' [ OK ] GarbageCollectorTest.Schedule (92 ms) [ RUN ] GarbageCollectorTest.Prune Using temporary directory '/tmp/GarbageCollectorTest_Prune_3GCKJK' I1108 02:53:19.506672 25879 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1108 02:53:19.506801 25879 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1108 02:53:19.506908 25879 gc.cpp:56] Scheduling 'file3' for gc 15secs in the future I1108 02:53:19.506994 25879 gc.cpp:56] Scheduling 'file4' for gc 15secs in the future I1108 02:53:19.507074 25879 gc.cpp:84] Unscheduling 'file3' from gc I1108 02:53:19.527171 25883 gc.cpp:167] Pruning directories with remaining removal time 10secs I1108 02:53:19.527248 25883 gc.cpp:134] Deleting file1 I1108 02:53:19.527297 25883 gc.cpp:143] Deleted 'file1' I1108 02:53:19.527323 25883 gc.cpp:134] Deleting file2 I1108 02:53:19.527354 25883 gc.cpp:143] Deleted 'file2' I1108 02:53:19.547471 25885 gc.cpp:167] Pruning directories with remaining removal time 15secs I1108 02:53:19.547575 25885 gc.cpp:134] Deleting file4 I1108 02:53:19.547633 25885 gc.cpp:143] Deleted 'file4' [ OK ] GarbageCollectorTest.Prune (51 ms) [----------] 3 tests from GarbageCollectorTest (215 ms total) [----------] 4 tests from ReplicaTest [ RUN ] ReplicaTest.Restore Using temporary directory '/tmp/ReplicaTest_Restore_EONZSU' I1108 02:53:19.561199 25865 leveldb.cpp:176] Opened db in 3.022731ms I1108 02:53:19.562335 25865 leveldb.cpp:183] Compacted db in 1.101381ms I1108 02:53:19.562388 25865 leveldb.cpp:198] Created db iterator in 16575ns I1108 02:53:19.562407 25865 leveldb.cpp:204] Seeked to beginning of db in 1715ns I1108 02:53:19.562418 25865 leveldb.cpp:273] Iterated through 0 keys in the db in 350ns I1108 02:53:19.562450 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.563913 25893 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 678983ns I1108 02:53:19.563946 25893 replica.cpp:320] Persisted replica status to VOTING I1108 02:53:19.567039 25865 leveldb.cpp:176] Opened db in 2.586217ms I1108 02:53:19.570047 25865 leveldb.cpp:183] Compacted db in 2.981007ms I1108 02:53:19.570101 25865 leveldb.cpp:198] Created db iterator in 17495ns I1108 02:53:19.570138 25865 leveldb.cpp:204] Seeked to beginning of db in 18131ns I1108 02:53:19.570178 25865 leveldb.cpp:273] Iterated through 1 keys in the db in 23652ns I1108 02:53:19.570210 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.570590 25883 replica.cpp:474] Replica received implicit promise request with proposal 1 I1108 02:53:19.571100 25883 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 474548ns I1108 02:53:19.571128 25883 replica.cpp:342] Persisted promised to 1 I1108 02:53:19.572089 25881 replica.cpp:508] Replica received write request for position 1 I1108 02:53:19.572466 25881 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 341006ns I1108 02:53:19.572497 25881 replica.cpp:676] Persisted action at 1 I1108 02:53:19.573211 25890 leveldb.cpp:438] Reading position from leveldb took 44471ns I1108 02:53:19.576428 25865 leveldb.cpp:176] Opened db in 2.748406ms I1108 02:53:19.578843 25865 leveldb.cpp:183] Compacted db in 2.389796ms I1108 02:53:19.578884 25865 leveldb.cpp:198] Created db iterator in 14030ns I1108 02:53:19.578913 25865 leveldb.cpp:204] Seeked to beginning of db in 14269ns I1108 02:53:19.578974 25865 leveldb.cpp:273] Iterated through 2 keys in the db in 48373ns I1108 02:53:19.579025 25865 replica.cpp:741] Replica recovered with log positions 0 -> 1 with 1 holes and 1 unlearned I1108 02:53:19.579277 25891 leveldb.cpp:438] Reading position from leveldb took 64762ns [ OK ] ReplicaTest.Restore (23 ms) [ RUN ] ReplicaTest.NonVoting Using temporary directory '/tmp/ReplicaTest_NonVoting_HBBW54' I1108 02:53:19.583242 25865 leveldb.cpp:176] Opened db in 2.32999ms I1108 02:53:19.584153 25865 leveldb.cpp:183] Compacted db in 884498ns I1108 02:53:19.584195 25865 leveldb.cpp:198] Created db iterator in 13305ns I1108 02:53:19.584214 25865 leveldb.cpp:204] Seeked to beginning of db in 1613ns I1108 02:53:19.584224 25865 leveldb.cpp:273] Iterated through 0 keys in the db in 200ns I1108 02:53:19.584254 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.584689 25886 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I1108 02:53:19.594954 25891 replica.cpp:503] Replica ignoring write request as it is in EMPTY status [ OK ] ReplicaTest.NonVoting (25 ms) [ RUN ] ReplicaTest.Append Using temporary directory '/tmp/ReplicaTest_Append_MCNSmf' I1108 02:53:19.607792 25865 leveldb.cpp:176] Opened db in 2.336045ms I1108 02:53:19.608794 25865 leveldb.cpp:183] Compacted db in 969194ns I1108 02:53:19.608839 25865 leveldb.cpp:198] Created db iterator in 13904ns I1108 02:53:19.608858 25865 leveldb.cpp:204] Seeked to beginning of db in 1595ns I1108 02:53:19.608868 25865 leveldb.cpp:273] Iterated through 0 keys in the db in 218ns I1108 02:53:19.608897 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.610112 25887 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 687241ns I1108 02:53:19.610142 25887 replica.cpp:320] Persisted replica status to VOTING I1108 02:53:19.612984 25865 leveldb.cpp:176] Opened db in 2.199748ms I1108 02:53:19.615424 25865 leveldb.cpp:183] Compacted db in 2.405398ms I1108 02:53:19.615478 25865 leveldb.cpp:198] Created db iterator in 17612ns I1108 02:53:19.615543 25865 leveldb.cpp:204] Seeked to beginning of db in 44681ns I1108 02:53:19.615583 25865 leveldb.cpp:273] Iterated through 1 keys in the db in 21434ns I1108 02:53:19.615618 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.615994 25894 replica.cpp:474] Replica received implicit promise request with proposal 1 I1108 02:53:19.616709 25894 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 683561ns I1108 02:53:19.616739 25894 replica.cpp:342] Persisted promised to 1 I1108 02:53:19.617465 25884 replica.cpp:508] Replica received write request for position 1 I1108 02:53:19.617905 25884 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 404951ns I1108 02:53:19.617936 25884 replica.cpp:676] Persisted action at 1 I1108 02:53:19.618535 25894 leveldb.cpp:438] Reading position from leveldb took 34622ns [ OK ] ReplicaTest.Append (14 ms) [ RUN ] ReplicaTest.Promise Using temporary directory '/tmp/ReplicaTest_Promise_HGsbGp' I1108 02:53:19.622789 25865 leveldb.cpp:176] Opened db in 2.749878ms I1108 02:53:19.623788 25865 leveldb.cpp:183] Compacted db in 966944ns I1108 02:53:19.623831 25865 leveldb.cpp:198] Created db iterator in 13339ns I1108 02:53:19.623849 25865 leveldb.cpp:204] Seeked to beginning of db in 1639ns I1108 02:53:19.623859 25865 leveldb.cpp:273] Iterated through 0 keys in the db in 200ns I1108 02:53:19.623889 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.625113 25886 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 745294ns I1108 02:53:19.625144 25886 replica.cpp:320] Persisted replica status to VOTING I1108 02:53:19.627836 25865 leveldb.cpp:176] Opened db in 2.13511ms I1108 02:53:19.630780 25865 leveldb.cpp:183] Compacted db in 2.910206ms I1108 02:53:19.630833 25865 leveldb.cpp:198] Created db iterator in 17732ns I1108 02:53:19.630870 25865 leveldb.cpp:204] Seeked to beginning of db in 17494ns I1108 02:53:19.630905 25865 leveldb.cpp:273] Iterated through 1 keys in the db in 20797ns I1108 02:53:19.630939 25865 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I1108 02:53:19.631296 25894 replica.cpp:474] Replica received implicit promise request with proposal 2 I1108 02:53:19.632073 25894 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 747684ns I1108 02:53:19.632099 25894 replica.cpp:342] Persisted promised to 2 I1108 02:53:19.632877 25889 replica.cpp:474] Replica received implicit promise request with proposal 1 I1108 02:53:19.632904 25889 replica.cpp:479] Replica denying promise request with proposal 1 I1108 02:53:19.633469 25890 replica.cpp:474] Replica received implicit promise request with proposal 3 I1108 02:53:19.634016 25890 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 513702ns I1108 02:53:19.634045 25890 replica.cpp:342] Persisted promised to 3 [ OK ] ReplicaTest.Promise (16 ms) [----------] 4 tests from ReplicaTest (78 ms total) [----------] 6 tests from FilesTest [ RUN ] FilesTest.BrowseTest Using temporary directory '/tmp/FilesTest_BrowseTest_049W1z' I1108 02:53:19.637013 25879 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/browse.json' I1108 02:53:19.640193 25888 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/browse.json' I1108 02:53:19.643056 25885 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/browse.json' I1108 02:53:19.645869 25884 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/browse.json' Shutting down Sending SIGTERM to process tree at pid 30347 I1108 02:53:19.647814 25883 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/browse.json' [ OK ] FilesTest.BrowseTest (14 ms) [ RUN ] FilesTest.ReadTest Using temporary directory '/tmp/FilesTest_ReadTest_X2u5pK' I1108 02:53:19.650080 25891 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.651950 25887 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Shutting down Sending SIGTERM to process tree at pid 30348 I1108 02:53:19.653877 25885 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Shutting down Sending SIGTERM to process tree at pid 30349 I1108 02:53:19.656651 25884 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.659047 25889 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Shutting down Sending SIGTERM to process tree at pid 30367 I1108 02:53:19.661238 25884 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' [ OK ] FilesTest.ReadTest (13 ms) [ RUN ] FilesTest.ResolveTest Using temporary directory '/tmp/FilesTest_ResolveTest_IeAmQU' I1108 02:53:19.665184 25883 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.667208 25881 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.668906 25888 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.670418 25892 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.671985 25891 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Killing the following process trees: [ ] I1108 02:53:19.673606 25887 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' I1108 02:53:19.675093 25887 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Shutting down Sending SIGTERM to process tree at pid 30368 I1108 02:53:19.676839 25894 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Killing the following process trees: [ ] I1108 02:53:19.678535 25891 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Killing the following process trees: [ ] I1108 02:53:19.680181 25881 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/read.json' Killing the following process trees: [ ] [ OK ] FilesTest.ResolveTest (19 ms) [ RUN ] FilesTest.DownloadTest Using temporary directory '/tmp/FilesTest_DownloadTest_JQwKj5' I1108 02:53:19.683179 25893 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/download.json' I1108 02:53:19.683640 25888 process.cpp:1822] Sending file at '/tmp/FilesTest_DownloadTest_JQwKj5/binary' with length 17 I1108 02:53:19.685248 25889 process.cpp:3358] Handling HTTP event for process 'files' with path: '/files/download.json' I1108 02:53:19.685708 25882 process.cpp:1822] Sending file at '/tmp/FilesTest_DownloadTest_JQwKj5/black.gif' with length 42 [ OK ] FilesTest.DownloadTest (6 ms) [ RUN ] FilesTest.DetachTest Using temporary directory '/tmp/FilesTest_DetachTest_0uuXNf' [ OK ] FilesTest.DetachTest (0 ms) [ RUN ] FilesTest.AttachTest Using temporary directory '/tmp/FilesTest_AttachTest_WXiiiq' [ OK ] FilesTest.AttachTest (3 ms) [----------] 6 tests from FilesTest (55 ms total) [----------] 1 test from PerfTest [ RUN ] PerfTest.Parse W1108 02:53:19.691162 25865 perf.cpp:374] Unsupported perf counter, ignoring: <not supported>,cycles [ OK ] PerfTest.Parse (1 ms) [----------] 1 test from PerfTest (1 ms total) [----------] Global test environment tear-down Killing the following process trees: [ ] [==========] 414 tests from 64 test cases ran. (259267 ms total) [ PASSED ] 413 tests. [ FAILED ] 1 test, listed below: [ FAILED ] MesosContainerizerExecuteTest.IoRedirection 1 FAILED TEST YOU HAVE 5 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
