[ https://issues.apache.org/jira/browse/MESOS-1533?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14041468#comment-14041468 ]
Benjamin Mahler commented on MESOS-1533: ---------------------------------------- HealthCheckTest.ConsecutiveFailures is also flaky: https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2198/consoleText > HealthCheckTest.HealthyTask is flaky: runs forever. > --------------------------------------------------- > > Key: MESOS-1533 > URL: https://issues.apache.org/jira/browse/MESOS-1533 > Project: Mesos > Issue Type: Bug > Components: test > Reporter: Benjamin Mahler > Assignee: Timothy Chen > > Hey [~tnachen], looks like the health check test is flaky, can you take a > look? > You can run tests with repetition to make sure they are not flaky: > {noformat} > ./bin/mesos-tests.sh --gtest_filter="HealthCheckTest.HealthyTask" > --gtest_break_on_failure --gtest_repeat=-1 --verbose > {noformat} > {noformat} > [----------] 4 tests from HealthCheckTest > [ RUN ] HealthCheckTest.HealthyTask > Using temporary directory '/tmp/HealthCheckTest_HealthyTask_MOxO1V' > I0623 13:29:05.958271 11026 leveldb.cpp:176] Opened db in 27.890327ms > I0623 13:29:05.971760 11026 leveldb.cpp:183] Compacted db in 13.143547ms > I0623 13:29:05.972281 11026 leveldb.cpp:198] Created db iterator in 7492ns > I0623 13:29:05.972537 11026 leveldb.cpp:204] Seeked to beginning of db in > 2043ns > I0623 13:29:05.972823 11026 leveldb.cpp:273] Iterated through 0 keys in the > db in 817ns > I0623 13:29:05.973098 11026 replica.cpp:741] Replica recovered with log > positions 0 -> 0 with 1 holes and 0 unlearned > I0623 13:29:05.973688 11050 recover.cpp:425] Starting replica recovery > I0623 13:29:05.973769 11050 recover.cpp:451] Replica is in EMPTY status > I0623 13:29:05.974089 11050 replica.cpp:638] Replica in EMPTY status received > a broadcasted recover request > I0623 13:29:05.974153 11050 recover.cpp:188] Received a recover response from > a replica in EMPTY status > I0623 13:29:05.974290 11050 recover.cpp:542] Updating replica status to > STARTING > I0623 13:29:05.978360 11047 master.cpp:288] Master > 20140623-132905-16842879-46830-11026 (quantal) started on 127.0.1.1:46830 > I0623 13:29:05.978400 11047 master.cpp:325] Master only allowing > authenticated frameworks to register > I0623 13:29:05.978409 11047 master.cpp:330] Master only allowing > authenticated slaves to register > I0623 13:29:05.978415 11047 credentials.hpp:35] Loading credentials for > authentication from '/tmp/HealthCheckTest_HealthyTask_MOxO1V/credentials' > I0623 13:29:05.978492 11047 master.cpp:356] Authorization enabled > I0623 13:29:05.978965 11047 hierarchical_allocator_process.hpp:301] > Initializing hierarchical allocator process with master : > master@127.0.1.1:46830 > I0623 13:29:05.979009 11047 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:05.979218 11047 master.cpp:1122] The newly elected leader is > master@127.0.1.1:46830 with id 20140623-132905-16842879-46830-11026 > I0623 13:29:05.979240 11047 master.cpp:1135] Elected as the leading master! > I0623 13:29:05.979249 11047 master.cpp:953] Recovering from registrar > I0623 13:29:05.979318 11047 registrar.cpp:313] Recovering registrar > I0623 13:29:05.987046 11050 leveldb.cpp:306] Persisting metadata (8 bytes) to > leveldb took 12.683367ms > I0623 13:29:05.987083 11050 replica.cpp:320] Persisted replica status to > STARTING > I0623 13:29:05.987197 11050 recover.cpp:451] Replica is in STARTING status > I0623 13:29:05.987592 11050 replica.cpp:638] Replica in STARTING status > received a broadcasted recover request > I0623 13:29:05.987668 11050 recover.cpp:188] Received a recover response from > a replica in STARTING status > I0623 13:29:05.987797 11050 recover.cpp:542] Updating replica status to VOTING > I0623 13:29:05.998905 11050 leveldb.cpp:306] Persisting metadata (8 bytes) to > leveldb took 11.033964ms > I0623 13:29:05.998955 11050 replica.cpp:320] Persisted replica status to > VOTING > I0623 13:29:05.999232 11048 recover.cpp:556] Successfully joined the Paxos > group > I0623 13:29:05.999614 11050 recover.cpp:440] Recover process terminated > I0623 13:29:05.999821 11050 log.cpp:656] Attempting to start the writer > I0623 13:29:06.000411 11050 replica.cpp:474] Replica received implicit > promise request with proposal 1 > I0623 13:29:06.011952 11050 leveldb.cpp:306] Persisting metadata (8 bytes) to > leveldb took 11.138872ms > I0623 13:29:06.012461 11050 replica.cpp:342] Persisted promised to 1 > I0623 13:29:06.016896 11045 coordinator.cpp:230] Coordinator attemping to > fill missing position > I0623 13:29:06.017344 11045 replica.cpp:375] Replica received explicit > promise request for position 0 with proposal 2 > I0623 13:29:06.027925 11045 leveldb.cpp:343] Persisting action (8 bytes) to > leveldb took 10.554554ms > I0623 13:29:06.027961 11045 replica.cpp:676] Persisted action at 0 > I0623 13:29:06.028323 11045 replica.cpp:508] Replica received write request > for position 0 > I0623 13:29:06.028357 11045 leveldb.cpp:438] Reading position from leveldb > took 16343ns > I0623 13:29:06.038075 11045 leveldb.cpp:343] Persisting action (14 bytes) to > leveldb took 9.692828ms > I0623 13:29:06.038537 11045 replica.cpp:676] Persisted action at 0 > I0623 13:29:06.044852 11048 replica.cpp:655] Replica received learned notice > for position 0 > I0623 13:29:06.054749 11048 leveldb.cpp:343] Persisting action (16 bytes) to > leveldb took 9.86323ms > I0623 13:29:06.054788 11048 replica.cpp:676] Persisted action at 0 > I0623 13:29:06.054800 11048 replica.cpp:661] Replica learned NOP action at > position 0 > I0623 13:29:06.055058 11048 log.cpp:672] Writer started with ending position 0 > I0623 13:29:06.055423 11048 leveldb.cpp:438] Reading position from leveldb > took 18568ns > I0623 13:29:06.056553 11048 registrar.cpp:346] Successfully fetched the > registry (0B) > I0623 13:29:06.056581 11048 registrar.cpp:422] Attempting to update the > 'registry' > I0623 13:29:06.057991 11045 log.cpp:680] Attempting to append 120 bytes to > the log > I0623 13:29:06.058054 11045 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 1 > I0623 13:29:06.058347 11045 replica.cpp:508] Replica received write request > for position 1 > I0623 13:29:06.068114 11045 leveldb.cpp:343] Persisting action (137 bytes) to > leveldb took 9.741464ms > I0623 13:29:06.068155 11045 replica.cpp:676] Persisted action at 1 > I0623 13:29:06.068431 11045 replica.cpp:655] Replica received learned notice > for position 1 > I0623 13:29:06.079959 11045 leveldb.cpp:343] Persisting action (139 bytes) to > leveldb took 11.501635ms > I0623 13:29:06.080003 11045 replica.cpp:676] Persisted action at 1 > I0623 13:29:06.080014 11045 replica.cpp:661] Replica learned APPEND action at > position 1 > I0623 13:29:06.080891 11045 registrar.cpp:479] Successfully updated 'registry' > I0623 13:29:06.081310 11045 registrar.cpp:372] Successfully recovered > registrar > I0623 13:29:06.081625 11045 log.cpp:699] Attempting to truncate the log to 1 > I0623 13:29:06.082010 11045 master.cpp:980] Recovered 0 slaves from the > Registry (84B) ; allowing 10mins for slaves to re-register > I0623 13:29:06.082386 11045 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 2 > I0623 13:29:06.083796 11026 mesos_containerizer.cpp:125] Using isolation: > posix/cpu,posix/mem > I0623 13:29:06.086760 11045 slave.cpp:168] Slave started on > 162)@127.0.1.1:46830 > I0623 13:29:06.087103 11045 credentials.hpp:35] Loading credentials for > authentication from '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/credential' > I0623 13:29:06.087458 11045 slave.cpp:268] Slave using credential for: > test-principal > I0623 13:29:06.087806 11045 slave.cpp:281] Slave resources: cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] > I0623 13:29:06.088110 11045 slave.cpp:309] Slave hostname: quantal > I0623 13:29:06.088420 11045 slave.cpp:310] Slave checkpoint: false > I0623 13:29:06.089365 11049 state.cpp:33] Recovering state from > '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/meta' > I0623 13:29:06.089573 11049 status_update_manager.cpp:193] Recovering status > update manager > I0623 13:29:06.089628 11049 replica.cpp:508] Replica received write request > for position 2 > I0623 13:29:06.089810 11052 mesos_containerizer.cpp:283] Recovering > containerizer > I0623 13:29:06.090075 11052 slave.cpp:3110] Finished recovery > I0623 13:29:06.090356 11052 slave.cpp:584] New master detected at > master@127.0.1.1:46830 > I0623 13:29:06.090400 11052 slave.cpp:660] Authenticating with master > master@127.0.1.1:46830 > I0623 13:29:06.090451 11052 slave.cpp:633] Detecting new master > I0623 13:29:06.090489 11052 status_update_manager.cpp:167] New master > detected at master@127.0.1.1:46830 > I0623 13:29:06.090528 11052 authenticatee.hpp:128] Creating new client SASL > connection > I0623 13:29:06.090682 11052 master.cpp:3499] Authenticating > slave(162)@127.0.1.1:46830 > I0623 13:29:06.090781 11052 authenticator.hpp:156] Creating new server SASL > connection > I0623 13:29:06.090888 11052 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0623 13:29:06.090909 11052 authenticatee.hpp:245] Attempting to authenticate > with mechanism 'CRAM-MD5' > I0623 13:29:06.090934 11052 authenticator.hpp:262] Received SASL > authentication start > I0623 13:29:06.090967 11052 authenticator.hpp:384] Authentication requires > more steps > I0623 13:29:06.090994 11052 authenticatee.hpp:265] Received SASL > authentication step > I0623 13:29:06.091032 11052 authenticator.hpp:290] Received SASL > authentication step > I0623 13:29:06.091048 11052 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'quantal' server FQDN: 'quantal' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: false > I0623 13:29:06.091055 11052 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0623 13:29:06.091066 11052 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0623 13:29:06.091075 11052 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'quantal' server FQDN: 'quantal' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: true > I0623 13:29:06.091081 11052 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0623 13:29:06.091086 11052 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0623 13:29:06.091096 11052 authenticator.hpp:376] Authentication success > I0623 13:29:06.091125 11052 authenticatee.hpp:305] Authentication success > I0623 13:29:06.091150 11052 master.cpp:3539] Successfully authenticated > principal 'test-principal' at slave(162)@127.0.1.1:46830 > I0623 13:29:06.091261 11052 slave.cpp:717] Successfully authenticated with > master master@127.0.1.1:46830 > I0623 13:29:06.091310 11052 slave.cpp:955] Will retry registration in > 4.490367ms if necessary > I0623 13:29:06.091405 11046 master.cpp:2781] Registering slave at > slave(162)@127.0.1.1:46830 (quantal) with id > 20140623-132905-16842879-46830-11026-0 > I0623 13:29:06.091518 11046 registrar.cpp:422] Attempting to update the > 'registry' > I0623 13:29:06.095747 11026 sched.cpp:139] Version: 0.20.0 > I0623 13:29:06.096346 11047 sched.cpp:235] New master detected at > master@127.0.1.1:46830 > I0623 13:29:06.096371 11047 sched.cpp:285] Authenticating with master > master@127.0.1.1:46830 > I0623 13:29:06.096462 11047 authenticatee.hpp:128] Creating new client SASL > connection > I0623 13:29:06.096894 11048 slave.cpp:955] Will retry registration in > 31.550502ms if necessary > I0623 13:29:06.097097 11047 master.cpp:3499] Authenticating > scheduler-5fa39402-e675-4214-8e7c-f279bdf54802@127.0.1.1:46830 > I0623 13:29:06.097558 11046 authenticator.hpp:156] Creating new server SASL > connection > I0623 13:29:06.097673 11046 authenticatee.hpp:219] Received SASL > authentication mechanisms: CRAM-MD5 > I0623 13:29:06.097692 11046 authenticatee.hpp:245] Attempting to authenticate > with mechanism 'CRAM-MD5' > I0623 13:29:06.097717 11046 authenticator.hpp:262] Received SASL > authentication start > I0623 13:29:06.097748 11046 authenticator.hpp:384] Authentication requires > more steps > I0623 13:29:06.097774 11046 authenticatee.hpp:265] Received SASL > authentication step > I0623 13:29:06.097807 11046 authenticator.hpp:290] Received SASL > authentication step > I0623 13:29:06.097823 11046 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'quantal' server FQDN: 'quantal' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: false > I0623 13:29:06.097831 11046 auxprop.cpp:153] Looking up auxiliary property > '*userPassword' > I0623 13:29:06.097841 11046 auxprop.cpp:153] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I0623 13:29:06.097849 11046 auxprop.cpp:81] Request to lookup properties for > user: 'test-principal' realm: 'quantal' server FQDN: 'quantal' > SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false > SASL_AUXPROP_AUTHZID: true > I0623 13:29:06.097856 11046 auxprop.cpp:103] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I0623 13:29:06.097861 11046 auxprop.cpp:103] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I0623 13:29:06.097870 11046 authenticator.hpp:376] Authentication success > I0623 13:29:06.097892 11046 authenticatee.hpp:305] Authentication success > I0623 13:29:06.097942 11046 sched.cpp:359] Successfully authenticated with > master master@127.0.1.1:46830 > I0623 13:29:06.097956 11046 sched.cpp:478] Sending registration request to > master@127.0.1.1:46830 > I0623 13:29:06.098467 11047 master.cpp:2769] Ignoring register slave message > from slave(162)@127.0.1.1:46830 (quantal) as admission is already in progress > I0623 13:29:06.098753 11047 master.cpp:1233] Queuing up registration request > from scheduler-5fa39402-e675-4214-8e7c-f279bdf54802@127.0.1.1:46830 because > authentication is still in progress > I0623 13:29:06.099154 11047 master.cpp:3539] Successfully authenticated > principal 'test-principal' at > scheduler-5fa39402-e675-4214-8e7c-f279bdf54802@127.0.1.1:46830 > I0623 13:29:06.103518 11049 leveldb.cpp:343] Persisting action (16 bytes) to > leveldb took 13.860392ms > I0623 13:29:06.103555 11049 replica.cpp:676] Persisted action at 2 > I0623 13:29:06.103824 11049 replica.cpp:655] Replica received learned notice > for position 2 > I0623 13:29:06.104375 11047 master.cpp:1241] Received registration request > from scheduler-5fa39402-e675-4214-8e7c-f279bdf54802@127.0.1.1:46830 > I0623 13:29:06.104627 11047 master.cpp:1201] Authorizing framework principal > 'test-principal' to receive offers for role '*' > I0623 13:29:06.104964 11047 master.cpp:1300] Registering framework > 20140623-132905-16842879-46830-11026-0000 at > scheduler-5fa39402-e675-4214-8e7c-f279bdf54802@127.0.1.1:46830 > I0623 13:29:06.105402 11047 sched.cpp:409] Framework registered with > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.105834 11047 sched.cpp:423] Scheduler::registered took 15480ns > I0623 13:29:06.105599 11048 hierarchical_allocator_process.hpp:331] Added > framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.106120 11048 hierarchical_allocator_process.hpp:724] No > resources available to allocate! > I0623 13:29:06.106132 11048 hierarchical_allocator_process.hpp:686] Performed > allocation for 0 slaves in 16487ns > I0623 13:29:06.116317 11049 leveldb.cpp:343] Persisting action (18 bytes) to > leveldb took 12.466174ms > I0623 13:29:06.116390 11049 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 37305ns > I0623 13:29:06.116406 11049 replica.cpp:676] Persisted action at 2 > I0623 13:29:06.116417 11049 replica.cpp:661] Replica learned TRUNCATE action > at position 2 > I0623 13:29:06.116758 11049 log.cpp:680] Attempting to append 295 bytes to > the log > I0623 13:29:06.116817 11049 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 3 > I0623 13:29:06.117074 11049 replica.cpp:508] Replica received write request > for position 3 > I0623 13:29:06.129529 11047 slave.cpp:955] Will retry registration in > 3.448659ms if necessary > I0623 13:29:06.129727 11048 master.cpp:2769] Ignoring register slave message > from slave(162)@127.0.1.1:46830 (quantal) as admission is already in progress > I0623 13:29:06.130036 11049 leveldb.cpp:343] Persisting action (314 bytes) to > leveldb took 12.939545ms > I0623 13:29:06.130332 11049 replica.cpp:676] Persisted action at 3 > I0623 13:29:06.130928 11045 replica.cpp:655] Replica received learned notice > for position 3 > I0623 13:29:06.133996 11051 slave.cpp:955] Will retry registration in > 153.139616ms if necessary > I0623 13:29:06.134066 11051 master.cpp:2769] Ignoring register slave message > from slave(162)@127.0.1.1:46830 (quantal) as admission is already in progress > I0623 13:29:06.139631 11045 leveldb.cpp:343] Persisting action (316 bytes) to > leveldb took 8.674286ms > I0623 13:29:06.139662 11045 replica.cpp:676] Persisted action at 3 > I0623 13:29:06.139670 11045 replica.cpp:661] Replica learned APPEND action at > position 3 > I0623 13:29:06.140383 11045 registrar.cpp:479] Successfully updated 'registry' > I0623 13:29:06.140482 11045 log.cpp:699] Attempting to truncate the log to 3 > I0623 13:29:06.140555 11045 master.cpp:2821] Registered slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:06.140571 11045 master.cpp:3967] Adding slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 > (quantal) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] > I0623 13:29:06.140727 11045 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 4 > I0623 13:29:06.140812 11045 slave.cpp:751] Registered with master > master@127.0.1.1:46830; given slave ID 20140623-132905-16842879-46830-11026-0 > I0623 13:29:06.140887 11045 hierarchical_allocator_process.hpp:444] Added > slave 20140623-132905-16842879-46830-11026-0 (quantal) with cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) > I0623 13:29:06.140946 11045 hierarchical_allocator_process.hpp:750] Offering > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 to framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.141038 11045 hierarchical_allocator_process.hpp:706] Performed > allocation for slave 20140623-132905-16842879-46830-11026-0 in 120284ns > I0623 13:29:06.141144 11045 master.hpp:794] Adding offer > 20140623-132905-16842879-46830-11026-0 with resources cpus(*):2; mem(*):1024; > disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 (quantal) > I0623 13:29:06.141186 11045 master.cpp:3446] Sending 1 offers to framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.141341 11045 sched.cpp:546] Scheduler::resourceOffers took > 21190ns > I0623 13:29:06.141527 11045 replica.cpp:508] Replica received write request > for position 4 > I0623 13:29:06.142268 11051 master.hpp:804] Removing offer > 20140623-132905-16842879-46830-11026-0 with resources cpus(*):2; mem(*):1024; > disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 (quantal) > I0623 13:29:06.142333 11051 master.cpp:2125] Processing reply for offers: [ > 20140623-132905-16842879-46830-11026-0 ] on slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 > (quantal) for framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.142364 11051 master.cpp:2211] Authorizing framework principal > 'test-principal' to launch task 1 as user 'jenkins' > I0623 13:29:06.142791 11051 master.hpp:766] Adding task 1 with resources > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 (quantal) > I0623 13:29:06.142828 11051 master.cpp:2277] Launching task 1 of framework > 20140623-132905-16842879-46830-11026-0000 with resources cpus(*):2; > mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:06.142963 11051 slave.cpp:986] Got assigned task 1 for framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.143211 11051 slave.cpp:1096] Launching task 1 for framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.144738 11046 mesos_containerizer.cpp:548] Starting container > '1045d906-8cec-476d-a2a1-947e61983a5b' for executor '1' of framework > '20140623-132905-16842879-46830-11026-0000' > I0623 13:29:06.147037 11046 launcher.cpp:117] Forked child with pid '12559' > for container '1045d906-8cec-476d-a2a1-947e61983a5b' > I0623 13:29:06.148129 11046 mesos_containerizer.cpp:658] Fetching URIs for > container '1045d906-8cec-476d-a2a1-947e61983a5b' using command > '/var/jenkins/workspace/mesos-ubuntu-12.10-gcc/src/mesos-fetcher' > I0623 13:29:06.150723 11051 slave.cpp:1206] Queuing task '1' for executor 1 > of framework '20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.151619 11051 slave.cpp:545] Successfully attached file > '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/slaves/20140623-132905-16842879-46830-11026-0/frameworks/20140623-132905-16842879-46830-11026-0000/executors/1/runs/1045d906-8cec-476d-a2a1-947e61983a5b' > I0623 13:29:06.158108 11045 leveldb.cpp:343] Persisting action (16 bytes) to > leveldb took 16.477988ms > I0623 13:29:06.158176 11045 replica.cpp:676] Persisted action at 4 > I0623 13:29:06.158668 11045 replica.cpp:655] Replica received learned notice > for position 4 > I0623 13:29:06.168743 11045 leveldb.cpp:343] Persisting action (18 bytes) to > leveldb took 10.019524ms > I0623 13:29:06.168838 11045 leveldb.cpp:401] Deleting ~2 keys from leveldb > took 43708ns > I0623 13:29:06.168860 11045 replica.cpp:676] Persisted action at 4 > I0623 13:29:06.168874 11045 replica.cpp:661] Replica learned TRUNCATE action > at position 4 > I0623 13:29:06.903492 11045 slave.cpp:2452] Monitoring executor '1' of > framework '20140623-132905-16842879-46830-11026-0000' in container > '1045d906-8cec-476d-a2a1-947e61983a5b' > I0623 13:29:06.953822 11049 slave.cpp:1717] Got registration for executor '1' > of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.956344 11053 process.cpp:1098] Socket closed while receiving > I0623 13:29:06.958034 11049 slave.cpp:1836] Flushing queued task 1 for > executor '1' of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.958570 11053 process.cpp:1098] Socket closed while receiving > I0623 13:29:06.965090 11051 slave.cpp:2071] Handling status update > TASK_RUNNING (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 of > framework 20140623-132905-16842879-46830-11026-0000 from > executor(1)@127.0.1.1:60351 > I0623 13:29:06.965258 11051 status_update_manager.cpp:320] Received status > update TASK_RUNNING (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 > of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.965276 11051 status_update_manager.cpp:499] Creating > StatusUpdate stream for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.965415 11051 status_update_manager.cpp:373] Forwarding status > update TASK_RUNNING (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 > of framework 20140623-132905-16842879-46830-11026-0000 to > master@127.0.1.1:46830 > I0623 13:29:06.965626 11051 master.cpp:3107] Status update TASK_RUNNING > (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 from slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:06.965668 11051 slave.cpp:2229] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.965679 11051 slave.cpp:2235] Sending acknowledgement for > status update TASK_RUNNING (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for > task 1 of framework 20140623-132905-16842879-46830-11026-0000 to > executor(1)@127.0.1.1:60351 > I0623 13:29:06.965945 11051 sched.cpp:637] Scheduler::statusUpdate took > 45619ns > I0623 13:29:06.966094 11051 master.cpp:2631] Forwarding status update > acknowledgement 0f48b057-84bb-4622-8abc-fa28a67d1faa for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 to slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:06.966984 11053 process.cpp:1098] Socket closed while receiving > I0623 13:29:06.967305 11050 status_update_manager.cpp:398] Received status > update acknowledgement (UUID: 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task > 1 of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.967434 11050 slave.cpp:1657] Status update manager > successfully handled status update acknowledgement (UUID: > 0f48b057-84bb-4622-8abc-fa28a67d1faa) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:06.979565 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 77364ns > I0623 13:29:07.980064 11052 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 84425ns > I0623 13:29:08.980820 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 41035ns > I0623 13:29:09.984819 11049 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 44099ns > I0623 13:29:10.979605 11049 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:10.986023 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 34630ns > I0623 13:29:11.986449 11046 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 42464ns > I0623 13:29:12.987354 11051 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43441ns > I0623 13:29:13.988312 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43495ns > I0623 13:29:14.989270 11052 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 42945ns > I0623 13:29:15.979938 11045 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:15.989708 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 50353ns > tests/health_check_tests.cpp:159: Failure > Failed to wait 10secs for statusHealth > I0623 13:29:16.990399 11051 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43532ns > I0623 13:29:17.991320 11049 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 51967ns > I0623 13:29:18.992204 11051 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 60190ns > I0623 13:29:19.993016 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54574ns > I0623 13:29:20.981199 11047 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:20.993456 11047 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 47786ns > I0623 13:29:21.994848 11049 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55818ns > I0623 13:29:22.995617 11051 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52831ns > I0623 13:29:23.996482 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54658ns > I0623 13:29:24.997186 11047 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52690ns > I0623 13:29:25.982275 11052 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:25.997391 11052 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 49323ns > I0623 13:29:26.973814 11045 slave.cpp:2071] Handling status update > TASK_FINISHED (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 of > framework 20140623-132905-16842879-46830-11026-0000 from > executor(1)@127.0.1.1:60351 > I0623 13:29:26.973901 11045 slave.cpp:3752] Terminating task 1 > I0623 13:29:26.974519 11045 status_update_manager.cpp:320] Received status > update TASK_FINISHED (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 > of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.974581 11045 status_update_manager.cpp:373] Forwarding status > update TASK_FINISHED (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 > of framework 20140623-132905-16842879-46830-11026-0000 to > master@127.0.1.1:46830 > I0623 13:29:26.974784 11045 master.cpp:3107] Status update TASK_FINISHED > (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 from slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:26.974872 11045 master.hpp:784] Removing task 1 with resources > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 (quantal) > I0623 13:29:26.975059 11045 slave.cpp:2229] Status update manager > successfully handled status update TASK_FINISHED (UUID: > 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.975075 11045 slave.cpp:2235] Sending acknowledgement for > status update TASK_FINISHED (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for > task 1 of framework 20140623-132905-16842879-46830-11026-0000 to > executor(1)@127.0.1.1:60351 > I0623 13:29:26.975688 11051 sched.cpp:637] Scheduler::statusUpdate took > 41150ns > I0623 13:29:26.975805 11051 hierarchical_allocator_process.hpp:635] Recovered > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total > allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on > slave 20140623-132905-16842879-46830-11026-0 from framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.976025 11050 master.cpp:2631] Forwarding status update > acknowledgement 3c926ce6-fbf9-4aef-b8e5-db6c51f92110 for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 to slave > 20140623-132905-16842879-46830-11026-0 at slave(162)@127.0.1.1:46830 (quantal) > I0623 13:29:26.976826 11053 process.cpp:1098] Socket closed while receiving > I0623 13:29:26.981264 11046 status_update_manager.cpp:398] Received status > update acknowledgement (UUID: 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task > 1 of framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.981313 11046 status_update_manager.cpp:530] Cleaning up status > update stream for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.981657 11045 slave.cpp:1657] Status update manager > successfully handled status update acknowledgement (UUID: > 3c926ce6-fbf9-4aef-b8e5-db6c51f92110) for task 1 of framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.982151 11045 slave.cpp:3794] Completing task 1 > I0623 13:29:26.998436 11050 hierarchical_allocator_process.hpp:750] Offering > cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 to framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.998576 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 206661ns > I0623 13:29:26.998669 11050 master.hpp:794] Adding offer > 20140623-132905-16842879-46830-11026-1 with resources cpus(*):2; mem(*):1024; > disk(*):1024; ports(*):[31000-32000] on slave > 20140623-132905-16842879-46830-11026-0 (quantal) > I0623 13:29:26.998721 11050 master.cpp:3446] Sending 1 offers to framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:26.998901 11050 sched.cpp:546] Scheduler::resourceOffers took > 37165ns > I0623 13:29:27.974091 11053 process.cpp:1037] Socket closed while receiving > I0623 13:29:27.999688 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53454ns > I0623 13:29:28.915324 11046 mesos_containerizer.cpp:1131] Executor for > container '1045d906-8cec-476d-a2a1-947e61983a5b' has exited > I0623 13:29:28.915392 11046 mesos_containerizer.cpp:1015] Destroying > container '1045d906-8cec-476d-a2a1-947e61983a5b' > I0623 13:29:28.927748 11045 slave.cpp:2510] Executor '1' of framework > 20140623-132905-16842879-46830-11026-0000 exited with status 0 > I0623 13:29:28.927831 11045 slave.cpp:2644] Cleaning up executor '1' of > framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:28.928016 11045 slave.cpp:2719] Cleaning up framework > 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:28.928153 11045 gc.cpp:56] Scheduling > '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/slaves/20140623-132905-16842879-46830-11026-0/frameworks/20140623-132905-16842879-46830-11026-0000/executors/1/runs/1045d906-8cec-476d-a2a1-947e61983a5b' > for gc 6.99998926017185days in the future > I0623 13:29:28.928225 11045 gc.cpp:56] Scheduling > '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/slaves/20140623-132905-16842879-46830-11026-0/frameworks/20140623-132905-16842879-46830-11026-0000/executors/1' > for gc 6.99998925930667days in the future > I0623 13:29:28.928268 11045 gc.cpp:56] Scheduling > '/tmp/HealthCheckTest_HealthyTask_8Tzxpu/slaves/20140623-132905-16842879-46830-11026-0/frameworks/20140623-132905-16842879-46830-11026-0000' > for gc 6.99998925861037days in the future > I0623 13:29:28.928308 11045 status_update_manager.cpp:282] Closing status > update streams for framework 20140623-132905-16842879-46830-11026-0000 > I0623 13:29:29.000550 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 50434ns > I0623 13:29:30.001346 11045 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53577ns > I0623 13:29:30.982919 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:31.002223 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 51750ns > I0623 13:29:32.002712 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 73773ns > I0623 13:29:33.003180 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53940ns > I0623 13:29:34.003808 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53558ns > I0623 13:29:35.004268 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53898ns > I0623 13:29:35.983779 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:36.005245 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 51824ns > I0623 13:29:37.005506 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53274ns > I0623 13:29:38.005902 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43625ns > I0623 13:29:39.006609 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 58328ns > I0623 13:29:40.007002 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53416ns > I0623 13:29:40.984532 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:41.008054 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53937ns > I0623 13:29:42.008424 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 58434ns > I0623 13:29:43.008923 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55446ns > I0623 13:29:44.009263 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 76356ns > I0623 13:29:45.009577 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54392ns > I0623 13:29:45.985028 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:46.010452 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54094ns > I0623 13:29:47.010826 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55739ns > I0623 13:29:48.011225 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 56030ns > I0623 13:29:49.011993 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53974ns > I0623 13:29:50.012663 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 83039ns > I0623 13:29:50.986024 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:51.013417 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55998ns > I0623 13:29:52.014410 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 66513ns > I0623 13:29:53.014827 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54592ns > I0623 13:29:54.015296 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52885ns > I0623 13:29:55.015703 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53916ns > I0623 13:29:55.987176 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:29:56.016623 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52742ns > I0623 13:29:57.016999 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 62540ns > I0623 13:29:58.017364 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43176ns > I0623 13:29:59.017732 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53975ns > I0623 13:30:00.018108 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53350ns > I0623 13:30:00.987377 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:01.018800 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52418ns > I0623 13:30:02.019189 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53547ns > I0623 13:30:03.019587 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54179ns > I0623 13:30:04.019919 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52882ns > I0623 13:30:05.020361 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52947ns > I0623 13:30:05.988178 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:06.021682 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 56257ns > I0623 13:30:06.090155 11050 slave.cpp:2965] Current usage 74.04%. Max allowed > age: 1.117428832185220days > I0623 13:30:06.150583 11050 slave.cpp:2881] Framework > 20140623-132905-16842879-46830-11026-0000 seems to have exited. Ignoring > registration timeout for executor '1' > I0623 13:30:07.022019 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53318ns > I0623 13:30:08.022624 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54292ns > I0623 13:30:09.023293 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53542ns > I0623 13:30:10.023941 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52393ns > I0623 13:30:10.989413 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:11.024899 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52966ns > I0623 13:30:12.025527 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53140ns > I0623 13:30:13.026146 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53721ns > I0623 13:30:14.026729 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53274ns > I0623 13:30:15.027339 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54069ns > I0623 13:30:15.989742 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:16.028256 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52569ns > I0623 13:30:17.028873 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53962ns > I0623 13:30:18.029326 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52644ns > I0623 13:30:19.029984 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54637ns > I0623 13:30:20.030349 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 44872ns > I0623 13:30:20.990906 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:21.031368 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54554ns > I0623 13:30:22.032305 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52795ns > I0623 13:30:23.032912 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54090ns > I0623 13:30:24.033435 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 65308ns > I0623 13:30:25.034030 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54331ns > I0623 13:30:25.991462 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:26.034934 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 56338ns > I0623 13:30:27.035320 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54295ns > I0623 13:30:28.035639 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43351ns > I0623 13:30:29.036260 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53786ns > I0623 13:30:30.036741 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53673ns > I0623 13:30:30.992256 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:31.037739 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 51929ns > I0623 13:30:32.038426 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 68657ns > I0623 13:30:33.038898 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53297ns > I0623 13:30:34.039538 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55770ns > I0623 13:30:35.039994 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53309ns > I0623 13:30:35.993522 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:36.041009 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52201ns > I0623 13:30:37.041970 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53620ns > I0623 13:30:38.042342 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52384ns > I0623 13:30:39.042762 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53347ns > I0623 13:30:40.043160 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52846ns > I0623 13:30:40.994627 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:41.044037 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55044ns > I0623 13:30:42.044471 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 69351ns > I0623 13:30:43.044844 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 52761ns > I0623 13:30:44.045295 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 58272ns > I0623 13:30:45.045703 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53121ns > I0623 13:30:45.995093 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:46.046560 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53568ns > I0623 13:30:47.047122 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53533ns > I0623 13:30:48.047538 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54903ns > I0623 13:30:49.048111 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54062ns > I0623 13:30:50.048686 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55433ns > I0623 13:30:50.996052 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:51.049459 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53307ns > I0623 13:30:52.049793 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53253ns > I0623 13:30:53.050050 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53589ns > I0623 13:30:54.050307 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54237ns > I0623 13:30:55.051612 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55806ns > I0623 13:30:55.996837 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:30:56.052299 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53773ns > I0623 13:30:57.053175 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43987ns > I0623 13:30:58.054240 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43877ns > I0623 13:30:59.054507 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 42241ns > I0623 13:31:00.054829 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 43142ns > I0623 13:31:00.998021 11050 master.cpp:122] No whitelist given. Advertising > offers for all slaves > I0623 13:31:01.055660 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53868ns > I0623 13:31:02.056437 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 55486ns > I0623 13:31:03.056727 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 53723ns > I0623 13:31:04.056962 11050 hierarchical_allocator_process.hpp:686] Performed > allocation for 1 slaves in 54088ns > {noformat} -- This message was sent by Atlassian JIRA (v6.2#6252)