You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/06/24 08:41:16 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1926
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1926/changes>
Changes:
[yujie.jay] Modified two slave recovery tests to deal with the cases where 'update'
------------------------------------------
[...truncated 16003 lines...]
I0624 06:38:06.811617 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18122ns
I0624 06:38:07.812748 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 38090ns
I0624 06:38:08.813824 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17024ns
I0624 06:38:09.814924 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14994ns
I0624 06:38:10.816050 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27679ns
I0624 06:38:10.985262 3924 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:11.816177 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22292ns
I0624 06:38:12.817267 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19769ns
I0624 06:38:13.818382 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27794ns
I0624 06:38:14.819502 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 30751ns
I0624 06:38:15.451189 3925 slave.cpp:2966] Current usage 3.32%. Max allowed age: 6.067693162001540days
I0624 06:38:15.668488 3927 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:38:15.819636 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21089ns
I0624 06:38:15.985858 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:16.819823 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19628ns
I0624 06:38:17.820932 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23713ns
I0624 06:38:18.822017 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14508ns
I0624 06:38:19.823140 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 29711ns
I0624 06:38:20.824231 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17118ns
I0624 06:38:20.986455 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:21.824411 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 52343ns
I0624 06:38:22.825469 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 32417ns
I0624 06:38:23.826602 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 35476ns
I0624 06:38:24.827661 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18186ns
I0624 06:38:25.828799 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 38101ns
I0624 06:38:25.986984 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:26.829933 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17861ns
I0624 06:38:27.831037 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23079ns
I0624 06:38:28.832136 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20093ns
I0624 06:38:29.833220 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18413ns
I0624 06:38:30.669193 3926 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:38:30.833340 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17840ns
I0624 06:38:30.987555 3926 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:31.833538 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 34177ns
I0624 06:38:32.834669 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20831ns
I0624 06:38:33.835774 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21533ns
I0624 06:38:34.836859 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20051ns
I0624 06:38:35.837960 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15728ns
I0624 06:38:35.988169 3925 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:36.838112 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 32909ns
I0624 06:38:37.839187 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 24185ns
I0624 06:38:38.840308 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 37235ns
I0624 06:38:39.841372 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18036ns
I0624 06:38:40.842463 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15317ns
I0624 06:38:40.988688 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:41.842588 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18783ns
I0624 06:38:42.842679 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17235ns
I0624 06:38:43.842805 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 36919ns
I0624 06:38:44.843935 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19025ns
I0624 06:38:45.669898 3930 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:38:45.844071 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22625ns
I0624 06:38:45.989265 3926 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:46.844239 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 28055ns
I0624 06:38:47.844324 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14952ns
I0624 06:38:48.844439 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23279ns
I0624 06:38:49.845581 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15543ns
I0624 06:38:50.846675 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16496ns
I0624 06:38:50.989881 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:51.846799 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16605ns
I0624 06:38:52.846891 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19965ns
I0624 06:38:53.847000 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 24497ns
I0624 06:38:54.848091 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18692ns
I0624 06:38:55.849196 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23282ns
I0624 06:38:55.990406 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:38:56.849313 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15777ns
I0624 06:38:57.849431 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 25922ns
I0624 06:38:58.850575 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19124ns
I0624 06:38:59.851676 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16176ns
I0624 06:39:00.670629 3929 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:39:00.851829 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26906ns
I0624 06:39:00.991008 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:01.852061 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 40430ns
I0624 06:39:02.853126 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18536ns
I0624 06:39:03.854223 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15851ns
I0624 06:39:04.855327 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16064ns
I0624 06:39:05.856451 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21729ns
I0624 06:39:05.991642 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:06.856576 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19163ns
I0624 06:39:07.856673 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15038ns
I0624 06:39:08.857789 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23951ns
I0624 06:39:09.858927 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 30149ns
I0624 06:39:10.860035 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 41060ns
I0624 06:39:10.992209 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:11.860153 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26150ns
I0624 06:39:12.861296 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15510ns
I0624 06:39:13.862402 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20870ns
I0624 06:39:14.863503 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18885ns
I0624 06:39:15.452231 3924 slave.cpp:2966] Current usage 3.32%. Max allowed age: 6.067693162001540days
I0624 06:39:15.671510 3931 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:39:15.863736 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 36922ns
I0624 06:39:15.992904 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:16.864820 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21971ns
I0624 06:39:17.865914 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15369ns
I0624 06:39:18.867028 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18980ns
I0624 06:39:19.868126 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15942ns
I0624 06:39:20.869225 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15797ns
I0624 06:39:20.993420 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:21.869369 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16334ns
I0624 06:39:22.870481 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 28274ns
I0624 06:39:23.871640 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 40444ns
I0624 06:39:24.872704 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 28229ns
I0624 06:39:25.873805 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22022ns
I0624 06:39:25.993985 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:26.873924 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16772ns
I0624 06:39:27.874908 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27782ns
I0624 06:39:28.876003 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26228ns
I0624 06:39:29.877099 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18275ns
I0624 06:39:30.671991 3929 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:39:30.878221 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16431ns
I0624 06:39:30.994397 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:31.878350 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15870ns
I0624 06:39:32.879461 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 25421ns
I0624 06:39:33.880563 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17552ns
I0624 06:39:34.881662 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15401ns
I0624 06:39:35.882799 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 32900ns
I0624 06:39:35.994946 3924 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:36.882908 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21953ns
I0624 06:39:37.884006 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22763ns
I0624 06:39:38.885108 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16413ns
I0624 06:39:39.886219 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20081ns
I0624 06:39:40.887351 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 38648ns
I0624 06:39:40.995493 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:41.888500 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16392ns
I0624 06:39:42.889605 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19220ns
I0624 06:39:43.890705 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15407ns
I0624 06:39:44.891824 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22801ns
I0624 06:39:45.672734 3928 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:39:45.891939 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17205ns
I0624 06:39:45.996103 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:46.892125 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20966ns
I0624 06:39:47.893230 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17715ns
I0624 06:39:48.894323 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16082ns
I0624 06:39:49.895428 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17834ns
I0624 06:39:50.896544 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18365ns
I0624 06:39:50.996702 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:51.896695 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 34796ns
I0624 06:39:52.897769 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16694ns
I0624 06:39:53.898885 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21725ns
I0624 06:39:54.899981 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16289ns
I0624 06:39:55.901095 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26543ns
I0624 06:39:55.997241 3926 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:39:56.901218 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26750ns
I0624 06:39:57.901321 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19745ns
I0624 06:39:58.902426 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 24489ns
I0624 06:39:59.903525 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18995ns
I0624 06:40:00.673444 3924 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:40:00.903657 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 24459ns
I0624 06:40:00.997802 3924 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:01.903805 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 34075ns
I0624 06:40:02.904889 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18396ns
I0624 06:40:03.905987 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16004ns
I0624 06:40:04.907095 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19497ns
I0624 06:40:05.908228 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 33185ns
I0624 06:40:05.998348 3930 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:06.908349 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 25397ns
I0624 06:40:07.909440 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26093ns
I0624 06:40:08.910568 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17738ns
I0624 06:40:09.911674 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15042ns
I0624 06:40:10.912773 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15296ns
I0624 06:40:10.998924 3926 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:11.912905 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18587ns
I0624 06:40:12.913007 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14573ns
I0624 06:40:13.913105 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14258ns
I0624 06:40:14.914245 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 35819ns
I0624 06:40:15.452898 3924 slave.cpp:2966] Current usage 3.32%. Max allowed age: 6.067693162001540days
I0624 06:40:15.674211 3931 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:40:15.914427 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18759ns
I0624 06:40:15.999605 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:16.914623 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 32087ns
I0624 06:40:17.915762 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19148ns
I0624 06:40:18.916857 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14681ns
I0624 06:40:19.917959 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15954ns
I0624 06:40:20.919064 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15041ns
I0624 06:40:21.000211 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:21.919312 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 37915ns
I0624 06:40:22.920263 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27770ns
I0624 06:40:23.921380 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 35495ns
I0624 06:40:24.922454 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19376ns
I0624 06:40:25.923554 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16196ns
I0624 06:40:26.000696 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:26.923710 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 40807ns
I0624 06:40:27.924836 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17415ns
I0624 06:40:28.925940 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 18278ns
I0624 06:40:29.927083 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 32698ns
I0624 06:40:30.674960 3931 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:40:30.927183 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20us
I0624 06:40:31.001312 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:31.927361 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17136ns
I0624 06:40:32.928519 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 39019ns
I0624 06:40:33.929610 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 31013ns
I0624 06:40:34.930699 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 23387ns
I0624 06:40:35.931795 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 21647ns
I0624 06:40:36.001937 3929 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:36.931918 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17016ns
I0624 06:40:37.932030 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27929ns
I0624 06:40:38.933123 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16242ns
I0624 06:40:39.934237 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27901ns
I0624 06:40:40.935364 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 41228ns
I0624 06:40:41.002456 3930 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:41.936517 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20318ns
I0624 06:40:42.937623 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 28550ns
I0624 06:40:43.938724 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16964ns
I0624 06:40:44.939824 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19349ns
I0624 06:40:45.675698 3924 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:40:45.939946 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17111ns
I0624 06:40:46.003074 3928 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:46.940079 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20839ns
I0624 06:40:47.941190 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 22862ns
I0624 06:40:48.942278 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16041ns
I0624 06:40:49.943416 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 40555ns
I0624 06:40:50.944507 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 28205ns
I0624 06:40:51.003612 3930 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:51.945204 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26711ns
I0624 06:40:52.946359 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 29474ns
I0624 06:40:53.947438 3931 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 17939ns
I0624 06:40:54.948547 3929 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 24548ns
I0624 06:40:55.949657 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 25535ns
I0624 06:40:56.003702 3925 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:40:56.950768 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19973ns
I0624 06:40:57.951869 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 29518ns
I0624 06:40:58.952944 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14094ns
I0624 06:40:59.954028 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 13887ns
I0624 06:41:00.675813 3927 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:41:00.955149 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 20681ns
I0624 06:41:01.004254 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:41:01.955261 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15224ns
I0624 06:41:02.956388 3924 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 42269ns
I0624 06:41:03.957522 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 43513ns
I0624 06:41:04.958564 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19244ns
I0624 06:41:05.959683 3925 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 34754ns
I0624 06:41:06.004787 3931 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:41:06.959792 3930 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 16730ns
I0624 06:41:07.960911 3928 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 30521ns
I0624 06:41:08.962000 3926 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 19958ns
I0624 06:41:09.963174 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 42142ns
I0624 06:41:10.964241 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 30341ns
I0624 06:41:11.005332 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
I0624 06:41:11.964341 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14765ns
I0624 06:41:12.965436 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14459ns
I0624 06:41:13.966532 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 15851ns
I0624 06:41:14.967623 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 14103ns
I0624 06:41:15.453184 3927 slave.cpp:2966] Current usage 3.32%. Max allowed age: 6.067693162001540days
I0624 06:41:15.676470 3927 slave.cpp:2308] Received ping from slave-observer(13)@67.195.138.61:35656
I0624 06:41:15.967777 3927 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 26843ns
I0624 06:41:16.005874 3927 master.cpp:122] No whitelist given. Advertising offers for all slaves
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted
make[1]: *** [check-recursive] Terminated
make: *** [distcheck] Terminated
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1928
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1928/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1927
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1927/changes>
Changes:
[vinodkone] Temporarily disabled health check tests due to MESOS-1533.
[vinodkone] Properly disable health check tests.
------------------------------------------
[...truncated 58788 lines...]
Jun 24, 2014 9:05:49 AM TestLog main
INFO: Initializing log /tmp/mesos-9wIwpC/log3 with <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src/mesos-log>
2014-06-24 09:05:49,947:24747(0x2b4934401700):ZOO_INFO@check_events@1750: session establishment complete on server [:::39278], sessionId=0x146cd1fbc3d0002, negotiated timeout=6000
I0624 09:05:49.947530 24805 group.cpp:313] Group process (group(3)@67.195.138.61:34934) connected to ZooKeeper
I0624 09:05:49.947582 24805 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0624 09:05:49.947592 24805 group.cpp:385] Trying to create path '/log' in ZooKeeper
2014-06-24 09:05:49,973:24747(0x2b4934803700):ZOO_INFO@check_events@1750: session establishment complete on server [:::39278], sessionId=0x146cd1fbc3d0003, negotiated timeout=6000
I0624 09:05:49.973595 24811 group.cpp:313] Group process (group(4)@67.195.138.61:34934) connected to ZooKeeper
I0624 09:05:49.973690 24811 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0)
I0624 09:05:49.973711 24811 group.cpp:385] Trying to create path '/log' in ZooKeeper
I0624 09:05:49.975646 24809 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:49.975780 24809 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:50.015862 24808 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934 }
I0624 09:05:50.016111 24805 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:50.016276 24807 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:50.016947 24812 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:50.016998 24809 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:50.017180 24807 group.cpp:658] Trying to get '/log/0000000001' in ZooKeeper
I0624 09:05:50.017637 24809 group.cpp:658] Trying to get '/log/0000000001' in ZooKeeper
I0624 09:05:50.017954 24808 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934, log-replica(2)@67.195.138.61:34934 }
I0624 09:05:50.018581 24812 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934, log-replica(2)@67.195.138.61:34934 }
I0624 09:05:50.406378 24754 leveldb.cpp:176] Opened db in 182.06366ms
I0624 09:05:50.491647 24754 leveldb.cpp:183] Compacted db in 85.235231ms
I0624 09:05:50.491669 24754 leveldb.cpp:198] Created db iterator in 5040ns
I0624 09:05:50.491683 24754 leveldb.cpp:204] Seeked to beginning of db in 6410ns
I0624 09:05:50.491703 24754 leveldb.cpp:273] Iterated through 1 keys in the db in 12794ns
I0624 09:05:50.491716 24754 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
Jun 24, 2014 9:05:50 AM TestLog main
INFO: Initializing writer
I0624 09:05:50.492529 24807 log.cpp:238] Attempting to join replica to ZooKeeper group
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@716: Client environment:host.name=juno
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@716: Client environment:host.name=juno
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-51-generic
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@725: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-51-generic
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@725: Client environment:os.version=#77-Ubuntu SMP Wed Jul 24 20:18:19 UTC 2013
I0624 09:05:50.492740 24806 recover.cpp:425] Starting replica recovery
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@log_env@753: Client environment:user.dir=/tmp/ExamplesTest_JavaLog_so9AKV
2014-06-24 09:05:50,492:24747(0x2b491fa3a700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=0:0:0:0:0:0:0:0:39278 sessionTimeout=3000 watcher=0x2b491d36c130 sessionId=0 sessionPasswd=<null> context=0x2b4938004bd0 flags=0
I0624 09:05:50.492982 24806 recover.cpp:451] Replica is in VOTING status
I0624 09:05:50.493034 24806 recover.cpp:440] Recover process terminated
2014-06-24 09:05:50,492:24747(0x2b491fe3c700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-06-24 09:05:50,493:24747(0x2b491fe3c700):ZOO_INFO@log_env@753: Client environment:user.dir=/tmp/ExamplesTest_JavaLog_so9AKV
2014-06-24 09:05:50,493:24747(0x2b491fe3c700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=0:0:0:0:0:0:0:0:39278 sessionTimeout=3000 watcher=0x2b491d36c130 sessionId=0 sessionPasswd=<null> context=0x2b4954000e50 flags=0
2014-06-24 09:05:50,493:24747(0x2b4934c05700):ZOO_INFO@check_events@1703: initiated connection to server [:::39278]
2014-06-24 09:05:50,493:24747(0x2b4934e06700):ZOO_INFO@check_events@1703: initiated connection to server [:::39278]
I0624 09:05:50.494004 24812 log.cpp:656] Attempting to start the writer
I0624 09:05:50.495296 24805 replica.cpp:474] Replica received implicit promise request with proposal 1
I0624 09:05:50.495456 24808 replica.cpp:474] Replica received implicit promise request with proposal 1
2014-06-24 09:05:50,529:24747(0x2b4934c05700):ZOO_INFO@check_events@1750: session establishment complete on server [:::39278], sessionId=0x146cd1fbc3d0004, negotiated timeout=6000
I0624 09:05:50.530313 24809 group.cpp:313] Group process (group(6)@67.195.138.61:34934) connected to ZooKeeper
I0624 09:05:50.530329 24809 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0)
I0624 09:05:50.530339 24809 group.cpp:385] Trying to create path '/log' in ZooKeeper
2014-06-24 09:05:50,583:24747(0x2b4934e06700):ZOO_INFO@check_events@1750: session establishment complete on server [:::39278], sessionId=0x146cd1fbc3d0005, negotiated timeout=6000
I0624 09:05:50.583714 24807 group.cpp:313] Group process (group(5)@67.195.138.61:34934) connected to ZooKeeper
I0624 09:05:50.583740 24807 group.cpp:787] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0624 09:05:50.583748 24807 group.cpp:385] Trying to create path '/log' in ZooKeeper
I0624 09:05:51.222132 24808 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 726.657088ms
I0624 09:05:51.222138 24805 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 726.81343ms
I0624 09:05:51.222182 24808 replica.cpp:342] Persisted promised to 1
I0624 09:05:51.222193 24805 replica.cpp:342] Persisted promised to 1
I0624 09:05:51.222522 24811 coordinator.cpp:230] Coordinator attemping to fill missing position
I0624 09:05:51.223084 24811 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I0624 09:05:51.223157 24806 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2
I0624 09:05:51.540459 24738 exec.cpp:85] Committing suicide by killing the process group
I0624 09:05:51.540472 24718 exec.cpp:85] Committing suicide by killing the process group
I0624 09:05:51.541368 24731 exec.cpp:85] Committing suicide by killing the process group
I0624 09:05:51.815115 24811 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 591.949788ms
I0624 09:05:51.815124 24806 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 591.917189ms
I0624 09:05:51.815157 24811 replica.cpp:676] Persisted action at 0
I0624 09:05:51.815168 24806 replica.cpp:676] Persisted action at 0
I0624 09:05:51.815438 24806 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:51.815507 24810 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:51.815527 24805 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:51.815749 24805 network.hpp:423] ZooKeeper group memberships changed
I0624 09:05:51.815750 24806 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:51.815908 24805 group.cpp:658] Trying to get '/log/0000000000' in ZooKeeper
I0624 09:05:51.816387 24811 replica.cpp:508] Replica received write request for position 0
I0624 09:05:51.816433 24811 leveldb.cpp:438] Reading position from leveldb took 25610ns
I0624 09:05:51.816457 24807 replica.cpp:508] Replica received write request for position 0
I0624 09:05:51.816485 24807 leveldb.cpp:438] Reading position from leveldb took 16958ns
I0624 09:05:51.857372 24811 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 40.910761ms
I0624 09:05:51.857399 24811 replica.cpp:676] Persisted action at 0
I0624 09:05:51.857645 24805 group.cpp:658] Trying to get '/log/0000000001' in ZooKeeper
I0624 09:05:51.857660 24810 group.cpp:658] Trying to get '/log/0000000001' in ZooKeeper
I0624 09:05:51.857880 24806 group.cpp:658] Trying to get '/log/0000000001' in ZooKeeper
I0624 09:05:51.858542 24810 group.cpp:658] Trying to get '/log/0000000002' in ZooKeeper
I0624 09:05:51.858783 24805 group.cpp:658] Trying to get '/log/0000000002' in ZooKeeper
I0624 09:05:51.858976 24806 group.cpp:658] Trying to get '/log/0000000002' in ZooKeeper
I0624 09:05:51.859340 24810 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934, log-replica(2)@67.195.138.61:34934, log-replica(3)@67.195.138.61:34934 }
I0624 09:05:51.860767 24811 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934, log-replica(2)@67.195.138.61:34934, log-replica(3)@67.195.138.61:34934 }
I0624 09:05:51.860796 24805 network.hpp:461] ZooKeeper group PIDs: { log-replica(1)@67.195.138.61:34934, log-replica(2)@67.195.138.61:34934, log-replica(3)@67.195.138.61:34934 }
I0624 09:05:51.864297 24807 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 47.788962ms
I0624 09:05:51.864321 24807 replica.cpp:676] Persisted action at 0
I0624 09:05:51.864596 24805 replica.cpp:655] Replica received learned notice for position 0
I0624 09:05:51.864641 24812 replica.cpp:655] Replica received learned notice for position 0
I0624 09:05:51.864716 24806 replica.cpp:655] Replica received learned notice for position 0
I0624 09:05:51.899142 24812 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 34.484362ms
I0624 09:05:51.899165 24812 replica.cpp:676] Persisted action at 0
I0624 09:05:51.899173 24812 replica.cpp:661] Replica learned NOP action at position 0
I0624 09:05:51.899382 24810 log.cpp:672] Writer started with ending position 0
I0624 09:05:51.899895 24810 log.cpp:680] Attempting to append 1024 bytes to the log
I0624 09:05:51.899956 24811 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I0624 09:05:51.900261 24811 replica.cpp:508] Replica received write request for position 1
I0624 09:05:51.928196 24806 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 63.456065ms
I0624 09:05:51.928218 24806 replica.cpp:676] Persisted action at 0
I0624 09:05:51.928227 24806 replica.cpp:661] Replica learned NOP action at position 0
I0624 09:05:51.928246 24806 replica.cpp:508] Replica received write request for position 1
I0624 09:05:51.955904 24805 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 91.29012ms
I0624 09:05:51.955922 24805 replica.cpp:676] Persisted action at 0
I0624 09:05:51.955931 24805 replica.cpp:661] Replica learned NOP action at position 0
I0624 09:05:51.955946 24805 replica.cpp:508] Replica received write request for position 1
I0624 09:05:51.961565 24806 leveldb.cpp:343] Persisting action (1043 bytes) to leveldb took 33.307162ms
I0624 09:05:51.961583 24806 replica.cpp:676] Persisted action at 1
I0624 09:05:51.980393 24811 leveldb.cpp:343] Persisting action (1043 bytes) to leveldb took 80.10862ms
I0624 09:05:51.980423 24811 replica.cpp:676] Persisted action at 1
I0624 09:05:51.980666 24810 replica.cpp:655] Replica received learned notice for position 1
I0624 09:05:51.980682 24806 replica.cpp:655] Replica received learned notice for position 1
I0624 09:05:52.004608 24805 leveldb.cpp:343] Persisting action (1043 bytes) to leveldb took 48.651207ms
I0624 09:05:52.004628 24805 replica.cpp:676] Persisted action at 1
I0624 09:05:52.004650 24805 replica.cpp:655] Replica received learned notice for position 1
I0624 09:05:52.023998 24806 leveldb.cpp:343] Persisting action (1045 bytes) to leveldb took 43.29839ms
I0624 09:05:52.024019 24806 replica.cpp:676] Persisted action at 1
I0624 09:05:52.024026 24806 replica.cpp:661] Replica learned APPEND action at position 1
I0624 09:05:52.045387 24810 leveldb.cpp:343] Persisting action (1045 bytes) to leveldb took 64.704487ms
I0624 09:05:52.045408 24810 replica.cpp:676] Persisted action at 1
I0624 09:05:52.045418 24810 replica.cpp:661] Replica learned APPEND action at position 1
Jun 24, 2014 9:05:52 AM TestLog main
INFO: Time: 1403600752046 Appended 1024 bytes in 146472488 ns
I0624 09:05:52.048229 24811 log.cpp:680] Attempting to append 10240 bytes to the log
I0624 09:05:52.048388 24808 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 2
I0624 09:05:52.048686 24806 replica.cpp:508] Replica received write request for position 2
I0624 09:05:52.048738 24807 replica.cpp:508] Replica received write request for position 2
I0624 09:05:52.075855 24805 leveldb.cpp:343] Persisting action (1045 bytes) to leveldb took 71.195459ms
I0624 09:05:52.075871 24805 replica.cpp:676] Persisted action at 1
I0624 09:05:52.075878 24805 replica.cpp:661] Replica learned APPEND action at position 1
I0624 09:05:52.075897 24805 replica.cpp:508] Replica received write request for position 2
I0624 09:05:52.122778 24807 leveldb.cpp:343] Persisting action (10259 bytes) to leveldb took 73.996315ms
I0624 09:05:52.122797 24807 replica.cpp:676] Persisted action at 2
I0624 09:05:52.122828 24806 leveldb.cpp:343] Persisting action (10259 bytes) to leveldb took 74.108504ms
I0624 09:05:52.122844 24806 replica.cpp:676] Persisted action at 2
I0624 09:05:52.123080 24811 replica.cpp:655] Replica received learned notice for position 2
I0624 09:05:52.123172 24808 replica.cpp:655] Replica received learned notice for position 2
I0624 09:05:52.203517 24805 leveldb.cpp:343] Persisting action (10259 bytes) to leveldb took 127.593792ms
I0624 09:05:52.203536 24805 replica.cpp:676] Persisted action at 2
I0624 09:05:52.203560 24805 replica.cpp:655] Replica received learned notice for position 2
I0624 09:05:52.280449 24811 leveldb.cpp:343] Persisting action (10261 bytes) to leveldb took 157.346457ms
I0624 09:05:52.280470 24811 replica.cpp:676] Persisted action at 2
I0624 09:05:52.280478 24811 replica.cpp:661] Replica learned APPEND action at position 2
I0624 09:05:52.280491 24808 leveldb.cpp:343] Persisting action (10261 bytes) to leveldb took 157.293017ms
I0624 09:05:52.280508 24808 replica.cpp:676] Persisted action at 2
I0624 09:05:52.280515 24808 replica.cpp:661] Replica learned APPEND action at position 2
Jun 24, 2014 9:05:52 AM TestLog main
INFO: Time: 1403600752280 Appended 10240 bytes in 232516092 ns
I0624 09:05:52.289288 24811 log.cpp:680] Attempting to append 102400 bytes to the log
I0624 09:05:52.289413 24807 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3
I0624 09:05:52.290015 24807 replica.cpp:508] Replica received write request for position 3
I0624 09:05:52.290891 24810 replica.cpp:508] Replica received write request for position 3
2014-06-24 09:05:52,364:15806(0x2af09d348700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:48893] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0624 09:05:52.365350 24805 leveldb.cpp:343] Persisting action (10261 bytes) to leveldb took 161.774714ms
I0624 09:05:52.365370 24805 replica.cpp:676] Persisted action at 2
I0624 09:05:52.365378 24805 replica.cpp:661] Replica learned APPEND action at position 2
I0624 09:05:52.365480 24805 replica.cpp:508] Replica received write request for position 3
I0624 09:05:52.437223 24810 leveldb.cpp:343] Persisting action (102422 bytes) to leveldb took 146.245268ms
I0624 09:05:52.437243 24810 replica.cpp:676] Persisted action at 3
I0624 09:05:52.464759 24807 leveldb.cpp:343] Persisting action (102422 bytes) to leveldb took 174.654907ms
I0624 09:05:52.464797 24807 replica.cpp:676] Persisted action at 3
I0624 09:05:52.465265 24809 replica.cpp:655] Replica received learned notice for position 3
I0624 09:05:52.465383 24808 replica.cpp:655] Replica received learned notice for position 3
I0624 09:05:52.466240 24805 leveldb.cpp:343] Persisting action (102422 bytes) to leveldb took 100.675339ms
I0624 09:05:52.466282 24805 replica.cpp:676] Persisted action at 3
I0624 09:05:52.466434 24805 replica.cpp:655] Replica received learned notice for position 3
I0624 09:05:52.620769 24809 leveldb.cpp:343] Persisting action (102424 bytes) to leveldb took 155.48587ms
I0624 09:05:52.620798 24809 replica.cpp:676] Persisted action at 3
I0624 09:05:52.620808 24809 replica.cpp:661] Replica learned APPEND action at position 3
Jun 24, 2014 9:05:52 AM TestLog main
INFO: Time: 1403600752621 Appended 102400 bytes in 332065942 ns
2014-06-24 09:05:52,622:24747(0x2b4934401700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.622741 24806 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
2014-06-24 09:05:52,623:24747(0x2b4934803700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.623298 24811 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
2014-06-24 09:05:52,623:24747(0x2b4934e06700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.623702 24809 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
2014-06-24 09:05:52,623:24747(0x2b4960201700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.624207 24812 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
2014-06-24 09:05:52,624:24747(0x2b4960603700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.624819 24811 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
2014-06-24 09:05:52,625:24747(0x2b4934c05700):ZOO_ERROR@handle_socket_error_msg@1721: Socket [:::39278] zk retcode=-4, errno=112(Host is down): failed while receiving a server response
I0624 09:05:52.625299 24809 group.cpp:418] Lost connection to ZooKeeper, attempting to reconnect ...
I0624 09:05:52.642729 24805 leveldb.cpp:343] Persisting action (102424 bytes) to leveldb took 176.282318ms
I0624 09:05:52.642761 24805 replica.cpp:676] Persisted action at 3
I0624 09:05:52.642771 24805 replica.cpp:661] Replica learned APPEND action at position 3
I0624 09:05:52.646962 24808 leveldb.cpp:343] Persisting action (102424 bytes) to leveldb took 181.550566ms
I0624 09:05:52.646987 24808 replica.cpp:676] Persisted action at 3
I0624 09:05:52.646996 24808 replica.cpp:661] Replica learned APPEND action at position 3
[ OK ] ExamplesTest.JavaLog (6092 ms)
[----------] 6 tests from ExamplesTest (51267 ms total)
[----------] 2 tests from PathsTest
[ RUN ] PathsTest.format
[ OK ] PathsTest.format (27 ms)
[ RUN ] PathsTest.CreateExecutorDirectory
[ OK ] PathsTest.CreateExecutorDirectory (1 ms)
[----------] 2 tests from PathsTest (28 ms total)
[----------] 1 test from LauncherTest
[ RUN ] LauncherTest.Launch
Using temporary directory '/tmp/LauncherTest_Launch_uicfh6'
I0624 09:05:52.717365 15806 launcher.cpp:202] Setting launcher environment MESOS_LAUNCHER_command=cp /tmp/LauncherTest_Launch_uicfh6/temp1 /tmp/LauncherTest_Launch_uicfh6/temp2
[ OK ] LauncherTest.Launch (822 ms)
[----------] 1 test from LauncherTest (822 ms total)
[----------] 2 tests from MonitorTest
[ RUN ] MonitorTest.Collection
[ OK ] MonitorTest.Collection (121 ms)
[ RUN ] MonitorTest.Statistics
I0624 09:05:53.638362 16125 process.cpp:3335] Handling HTTP event for process 'monitor' with path: '/monitor/statistics.json'
I0624 09:05:53.639225 16132 process.cpp:1037] Socket closed while receiving
I0624 09:05:53.688931 16131 process.cpp:3335] Handling HTTP event for process 'monitor' with path: '/monitor/statistics.json'
I0624 09:05:53.689327 16132 process.cpp:1037] Socket closed while receiving
[ OK ] MonitorTest.Statistics (102 ms)
[----------] 2 tests from MonitorTest (223 ms total)
[----------] Global test environment tear-down
[==========] 377 tests from 62 test cases ran. (559750 ms total)
[ PASSED ] 376 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ExamplesTest.PythonFramework
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'>
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'>
make[2]: *** [check] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'>
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build'>
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure