Benjamin Mahler created MESOS-1533: -------------------------------------- Summary: 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)