See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4492/display/redirect?page=changes>
Changes: [bmahler] Optimized resources logging in master. ------------------------------------------ [...truncated 19.37 MB...] 3: I1122 21:46:18.075105 16018 replica.cpp:694] Replica received learned notice for position 3 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.076130 16018 leveldb.cpp:341] Persisting action (247 bytes) to leveldb took 1.010853ms 3: I1122 21:46:18.076146 16009 leveldb.cpp:341] Persisting action (247 bytes) to leveldb took 1.03546ms 3: I1122 21:46:18.076156 16018 replica.cpp:711] Persisted action APPEND at position 3 3: I1122 21:46:18.076180 16009 replica.cpp:711] Persisted action APPEND at position 3 3: I1122 21:46:18.076685 16015 registrar.cpp:552] Successfully updated the registry in 7.644928ms 3: I1122 21:46:18.076807 16021 log.cpp:579] Attempting to truncate the log to 3 3: I1122 21:46:18.076887 16013 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 4 3: I1122 21:46:18.077316 16004 replica.cpp:540] Replica received write request for position 4 from __req_res__(1531)@172.17.0.4:40853 3: I1122 21:46:18.077370 16022 replica.cpp:540] Replica received write request for position 4 from __req_res__(1532)@172.17.0.4:40853 3: I1122 21:46:18.077682 16020 registrar.cpp:347] Recovering registrar 3: I1122 21:46:18.078210 16017 registrar.cpp:391] Successfully fetched the registry (187B) in 491008ns 3: I1122 21:46:18.078358 16017 registrar.cpp:495] Applied 1 operations in 58391ns; attempting to update the registry 3: I1122 21:46:18.078793 16022 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.401296ms 3: I1122 21:46:18.078809 16004 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.467803ms 3: I1122 21:46:18.078820 16022 replica.cpp:711] Persisted action TRUNCATE at position 4 3: I1122 21:46:18.078845 16004 replica.cpp:711] Persisted action TRUNCATE at position 4 3: I1122 21:46:18.079205 16005 replica.cpp:694] Replica received learned notice for position 4 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.079231 16009 replica.cpp:694] Replica received learned notice for position 4 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.080413 16009 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.168123ms 3: I1122 21:46:18.080415 16005 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.197295ms 3: I1122 21:46:18.080471 16009 leveldb.cpp:399] Deleting ~2 keys from leveldb took 38211ns 3: I1122 21:46:18.080487 16009 replica.cpp:711] Persisted action TRUNCATE at position 4 3: I1122 21:46:18.080490 16005 leveldb.cpp:399] Deleting ~2 keys from leveldb took 41379ns 3: I1122 21:46:18.080519 16005 replica.cpp:711] Persisted action TRUNCATE at position 4 3: I1122 21:46:18.081023 16008 log.cpp:560] Attempting to append 226 bytes to the log 3: I1122 21:46:18.081091 16025 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 5 3: I1122 21:46:18.081636 16024 replica.cpp:540] Replica received write request for position 5 from __req_res__(1533)@172.17.0.4:40853 3: I1122 21:46:18.081717 16023 replica.cpp:540] Replica received write request for position 5 from __req_res__(1534)@172.17.0.4:40853 3: I1122 21:46:18.082801 16024 leveldb.cpp:341] Persisting action (245 bytes) to leveldb took 1.142167ms 3: I1122 21:46:18.082801 16023 leveldb.cpp:341] Persisting action (245 bytes) to leveldb took 1.063946ms 3: I1122 21:46:18.082830 16024 replica.cpp:711] Persisted action APPEND at position 5 3: I1122 21:46:18.082846 16023 replica.cpp:711] Persisted action APPEND at position 5 3: I1122 21:46:18.083158 16005 replica.cpp:694] Replica received learned notice for position 5 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.083165 16003 replica.cpp:694] Replica received learned notice for position 5 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.084195 16005 leveldb.cpp:341] Persisting action (247 bytes) to leveldb took 1.024668ms 3: I1122 21:46:18.084197 16003 leveldb.cpp:341] Persisting action (247 bytes) to leveldb took 1.019138ms 3: I1122 21:46:18.084223 16005 replica.cpp:711] Persisted action APPEND at position 5 3: I1122 21:46:18.084229 16003 replica.cpp:711] Persisted action APPEND at position 5 3: I1122 21:46:18.086081 16025 log.cpp:579] Attempting to truncate the log to 5 3: I1122 21:46:18.086046 16006 registrar.cpp:552] Successfully updated the registry in 7.41504ms 3: I1122 21:46:18.086316 16006 registrar.cpp:424] Successfully recovered registrar 3: I1122 21:46:18.086266 16021 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 6 3: I1122 21:46:18.087086 16004 replica.cpp:540] Replica received write request for position 6 from __req_res__(1535)@172.17.0.4:40853 3: I1122 21:46:18.087198 16017 registrar.cpp:495] Applied 1 operations in 80575ns; attempting to update the registry 3: I1122 21:46:18.087196 16010 replica.cpp:540] Replica received write request for position 6 from __req_res__(1536)@172.17.0.4:40853 3: I1122 21:46:18.088178 16010 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 939537ns 3: I1122 21:46:18.088178 16004 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.068266ms 3: I1122 21:46:18.088201 16010 replica.cpp:711] Persisted action TRUNCATE at position 6 3: I1122 21:46:18.088208 16004 replica.cpp:711] Persisted action TRUNCATE at position 6 3: I1122 21:46:18.088546 16016 replica.cpp:694] Replica received learned notice for position 6 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.088572 16015 replica.cpp:694] Replica received learned notice for position 6 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.089776 16015 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.187473ms 3: I1122 21:46:18.089779 16016 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.218374ms 3: I1122 21:46:18.089833 16015 leveldb.cpp:399] Deleting ~2 keys from leveldb took 36067ns 3: I1122 21:46:18.089849 16015 replica.cpp:711] Persisted action TRUNCATE at position 6 3: I1122 21:46:18.089856 16016 leveldb.cpp:399] Deleting ~2 keys from leveldb took 41329ns 3: I1122 21:46:18.089876 16016 replica.cpp:711] Persisted action TRUNCATE at position 6 3: I1122 21:46:18.090422 16007 log.cpp:560] Attempting to append 206 bytes to the log 3: I1122 21:46:18.090493 16021 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 7 3: I1122 21:46:18.091078 16002 replica.cpp:540] Replica received write request for position 7 from __req_res__(1537)@172.17.0.4:40853 3: I1122 21:46:18.091125 16003 replica.cpp:540] Replica received write request for position 7 from __req_res__(1538)@172.17.0.4:40853 3: I1122 21:46:18.092185 16002 leveldb.cpp:341] Persisting action (225 bytes) to leveldb took 1.077137ms 3: I1122 21:46:18.092193 16003 leveldb.cpp:341] Persisting action (225 bytes) to leveldb took 1.041532ms 3: I1122 21:46:18.092212 16002 replica.cpp:711] Persisted action APPEND at position 7 3: I1122 21:46:18.092216 16003 replica.cpp:711] Persisted action APPEND at position 7 3: I1122 21:46:18.092612 16025 replica.cpp:694] Replica received learned notice for position 7 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.092644 16019 replica.cpp:694] Replica received learned notice for position 7 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.093719 16019 leveldb.cpp:341] Persisting action (227 bytes) to leveldb took 1.063358ms 3: I1122 21:46:18.093719 16025 leveldb.cpp:341] Persisting action (227 bytes) to leveldb took 1.089501ms 3: I1122 21:46:18.093745 16019 replica.cpp:711] Persisted action APPEND at position 7 3: I1122 21:46:18.093767 16025 replica.cpp:711] Persisted action APPEND at position 7 3: I1122 21:46:18.094249 16013 registrar.cpp:552] Successfully updated the registry in 6.980096ms 3: I1122 21:46:18.094360 16021 log.cpp:579] Attempting to truncate the log to 7 3: I1122 21:46:18.094446 16018 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 8 3: I1122 21:46:18.094936 16024 replica.cpp:540] Replica received write request for position 8 from __req_res__(1539)@172.17.0.4:40853 3: I1122 21:46:18.094998 16019 replica.cpp:540] Replica received write request for position 8 from __req_res__(1540)@172.17.0.4:40853 3: I1122 21:46:18.095372 16016 registrar.cpp:347] Recovering registrar 3: I1122 21:46:18.095809 16005 registrar.cpp:391] Successfully fetched the registry (167B) in 406016ns 3: I1122 21:46:18.095906 16005 registrar.cpp:495] Applied 1 operations in 39318ns; attempting to update the registry 3: I1122 21:46:18.096045 16019 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.027114ms 3: I1122 21:46:18.096046 16024 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.089782ms 3: I1122 21:46:18.096074 16019 replica.cpp:711] Persisted action TRUNCATE at position 8 3: I1122 21:46:18.096081 16024 replica.cpp:711] Persisted action TRUNCATE at position 8 3: I1122 21:46:18.096364 16002 replica.cpp:694] Replica received learned notice for position 8 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.096401 16008 replica.cpp:694] Replica received learned notice for position 8 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.098054 16008 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.638927ms 3: I1122 21:46:18.098057 16002 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.679488ms 3: I1122 21:46:18.098115 16008 leveldb.cpp:399] Deleting ~2 keys from leveldb took 40980ns 3: I1122 21:46:18.098120 16002 leveldb.cpp:399] Deleting ~2 keys from leveldb took 38642ns 3: I1122 21:46:18.098132 16008 replica.cpp:711] Persisted action TRUNCATE at position 8 3: I1122 21:46:18.098135 16002 replica.cpp:711] Persisted action TRUNCATE at position 8 3: I1122 21:46:18.098626 16007 log.cpp:560] Attempting to append 206 bytes to the log 3: I1122 21:46:18.098690 16025 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 9 3: I1122 21:46:18.099140 16015 replica.cpp:540] Replica received write request for position 9 from __req_res__(1541)@172.17.0.4:40853 3: I1122 21:46:18.099249 16024 replica.cpp:540] Replica received write request for position 9 from __req_res__(1542)@172.17.0.4:40853 3: I1122 21:46:18.100253 16024 leveldb.cpp:341] Persisting action (225 bytes) to leveldb took 981156ns 3: I1122 21:46:18.100255 16015 leveldb.cpp:341] Persisting action (225 bytes) to leveldb took 1.093453ms 3: I1122 21:46:18.100275 16024 replica.cpp:711] Persisted action APPEND at position 9 3: I1122 21:46:18.100277 16015 replica.cpp:711] Persisted action APPEND at position 9 3: I1122 21:46:18.100641 16002 replica.cpp:694] Replica received learned notice for position 9 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.100651 16003 replica.cpp:694] Replica received learned notice for position 9 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.101656 16003 leveldb.cpp:341] Persisting action (227 bytes) to leveldb took 992118ns 3: I1122 21:46:18.101658 16002 leveldb.cpp:341] Persisting action (227 bytes) to leveldb took 1.001966ms 3: I1122 21:46:18.101681 16003 replica.cpp:711] Persisted action APPEND at position 9 3: I1122 21:46:18.101688 16002 replica.cpp:711] Persisted action APPEND at position 9 3: I1122 21:46:18.102166 16012 registrar.cpp:552] Successfully updated the registry in 6.214912ms 3: I1122 21:46:18.102257 16012 registrar.cpp:424] Successfully recovered registrar 3: I1122 21:46:18.102298 16023 log.cpp:579] Attempting to truncate the log to 9 3: I1122 21:46:18.102385 16025 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 10 3: I1122 21:46:18.102823 16020 registrar.cpp:495] Applied 1 operations in 61517ns; attempting to update the registry 3: I1122 21:46:18.102957 16018 replica.cpp:540] Replica received write request for position 10 from __req_res__(1543)@172.17.0.4:40853 3: I1122 21:46:18.103042 16002 replica.cpp:540] Replica received write request for position 10 from __req_res__(1544)@172.17.0.4:40853 3: I1122 21:46:18.104060 16002 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 990462ns 3: I1122 21:46:18.104080 16002 replica.cpp:711] Persisted action TRUNCATE at position 10 3: I1122 21:46:18.104080 16018 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.105966ms 3: I1122 21:46:18.104104 16018 replica.cpp:711] Persisted action TRUNCATE at position 10 3: I1122 21:46:18.104418 16023 replica.cpp:694] Replica received learned notice for position 10 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.104434 16013 replica.cpp:694] Replica received learned notice for position 10 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.106134 16013 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.685736ms 3: I1122 21:46:18.106151 16023 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.721412ms 3: I1122 21:46:18.106186 16013 leveldb.cpp:399] Deleting ~2 keys from leveldb took 34946ns 3: I1122 21:46:18.106202 16023 leveldb.cpp:399] Deleting ~2 keys from leveldb took 36175ns 3: I1122 21:46:18.106202 16013 replica.cpp:711] Persisted action TRUNCATE at position 10 3: I1122 21:46:18.106221 16023 replica.cpp:711] Persisted action TRUNCATE at position 10 3: I1122 21:46:18.107152 16015 log.cpp:560] Attempting to append 258 bytes to the log 3: I1122 21:46:18.107285 16004 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 11 3: I1122 21:46:18.107883 16006 replica.cpp:540] Replica received write request for position 11 from __req_res__(1545)@172.17.0.4:40853 3: I1122 21:46:18.107980 16020 replica.cpp:540] Replica received write request for position 11 from __req_res__(1546)@172.17.0.4:40853 3: I1122 21:46:18.109094 16020 leveldb.cpp:341] Persisting action (277 bytes) to leveldb took 1.08537ms 3: I1122 21:46:18.109107 16006 leveldb.cpp:341] Persisting action (277 bytes) to leveldb took 1.197073ms 3: I1122 21:46:18.109127 16020 replica.cpp:711] Persisted action APPEND at position 11 3: I1122 21:46:18.109136 16006 replica.cpp:711] Persisted action APPEND at position 11 3: I1122 21:46:18.109527 16013 replica.cpp:694] Replica received learned notice for position 11 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.109530 16016 replica.cpp:694] Replica received learned notice for position 11 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.110560 16016 leveldb.cpp:341] Persisting action (279 bytes) to leveldb took 1.009962ms 3: I1122 21:46:18.110577 16013 leveldb.cpp:341] Persisting action (279 bytes) to leveldb took 1.034513ms 3: I1122 21:46:18.110586 16016 replica.cpp:711] Persisted action APPEND at position 11 3: I1122 21:46:18.110606 16013 replica.cpp:711] Persisted action APPEND at position 11 3: I1122 21:46:18.111140 16025 registrar.cpp:552] Successfully updated the registry in 8.267264ms 3: I1122 21:46:18.111246 16025 log.cpp:579] Attempting to truncate the log to 11 3: I1122 21:46:18.111305 16008 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 12 3: I1122 21:46:18.111735 16017 replica.cpp:540] Replica received write request for position 12 from __req_res__(1547)@172.17.0.4:40853 3: I1122 21:46:18.111785 16009 replica.cpp:540] Replica received write request for position 12 from __req_res__(1548)@172.17.0.4:40853 3: I1122 21:46:18.112115 16014 registrar.cpp:347] Recovering registrar 3: I1122 21:46:18.112578 16025 registrar.cpp:391] Successfully fetched the registry (219B) in 434944ns 3: I1122 21:46:18.112730 16025 registrar.cpp:495] Applied 1 operations in 58099ns; attempting to update the registry 3: I1122 21:46:18.113535 16017 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.77626ms 3: I1122 21:46:18.113549 16009 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.742679ms 3: I1122 21:46:18.113574 16017 replica.cpp:711] Persisted action TRUNCATE at position 12 3: I1122 21:46:18.113585 16009 replica.cpp:711] Persisted action TRUNCATE at position 12 3: I1122 21:46:18.113905 16019 replica.cpp:694] Replica received learned notice for position 12 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.113935 16022 replica.cpp:694] Replica received learned notice for position 12 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.115151 16019 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.228964ms 3: I1122 21:46:18.115154 16022 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.207751ms 3: I1122 21:46:18.115221 16019 leveldb.cpp:399] Deleting ~2 keys from leveldb took 47725ns 3: I1122 21:46:18.115237 16019 replica.cpp:711] Persisted action TRUNCATE at position 12 3: I1122 21:46:18.115247 16022 leveldb.cpp:399] Deleting ~2 keys from leveldb took 55777ns 3: I1122 21:46:18.115272 16022 replica.cpp:711] Persisted action TRUNCATE at position 12 3: I1122 21:46:18.115921 16005 log.cpp:560] Attempting to append 258 bytes to the log 3: I1122 21:46:18.115984 16015 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 13 3: I1122 21:46:18.116425 16012 replica.cpp:540] Replica received write request for position 13 from __req_res__(1549)@172.17.0.4:40853 3: I1122 21:46:18.116499 16020 replica.cpp:540] Replica received write request for position 13 from __req_res__(1550)@172.17.0.4:40853 3: I1122 21:46:18.117446 16020 leveldb.cpp:341] Persisting action (277 bytes) to leveldb took 925451ns 3: I1122 21:46:18.117461 16012 leveldb.cpp:341] Persisting action (277 bytes) to leveldb took 1.010772ms 3: I1122 21:46:18.117472 16020 replica.cpp:711] Persisted action APPEND at position 13 3: I1122 21:46:18.117483 16012 replica.cpp:711] Persisted action APPEND at position 13 3: I1122 21:46:18.117815 16008 replica.cpp:694] Replica received learned notice for position 13 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.117825 16019 replica.cpp:694] Replica received learned notice for position 13 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.118857 16019 leveldb.cpp:341] Persisting action (279 bytes) to leveldb took 1.021918ms 3: I1122 21:46:18.118870 16008 leveldb.cpp:341] Persisting action (279 bytes) to leveldb took 1.041695ms 3: I1122 21:46:18.118887 16019 replica.cpp:711] Persisted action APPEND at position 13 3: I1122 21:46:18.118906 16008 replica.cpp:711] Persisted action APPEND at position 13 3: I1122 21:46:18.119494 16002 registrar.cpp:552] Successfully updated the registry in 6.719232ms 3: I1122 21:46:18.119587 16002 registrar.cpp:424] Successfully recovered registrar 3: I1122 21:46:18.119621 16014 log.cpp:579] Attempting to truncate the log to 13 3: I1122 21:46:18.119699 16015 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 14 3: I1122 21:46:18.120113 16021 replica.cpp:540] Replica received write request for position 14 from __req_res__(1551)@172.17.0.4:40853 3: I1122 21:46:18.120127 16020 registrar.cpp:495] Applied 1 operations in 51955ns; attempting to update the registry 3: I1122 21:46:18.120226 16013 replica.cpp:540] Replica received write request for position 14 from __req_res__(1552)@172.17.0.4:40853 3: I1122 21:46:18.121227 16013 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 980488ns 3: I1122 21:46:18.121229 16021 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.096409ms 3: I1122 21:46:18.121256 16013 replica.cpp:711] Persisted action TRUNCATE at position 14 3: I1122 21:46:18.121260 16021 replica.cpp:711] Persisted action TRUNCATE at position 14 3: I1122 21:46:18.121582 16009 replica.cpp:694] Replica received learned notice for position 14 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.121598 16015 replica.cpp:694] Replica received learned notice for position 14 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.122757 16015 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.150342ms 3: I1122 21:46:18.122758 16009 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.163936ms 3: I1122 21:46:18.122822 16009 leveldb.cpp:399] Deleting ~2 keys from leveldb took 38682ns 3: I1122 21:46:18.122828 16015 leveldb.cpp:399] Deleting ~2 keys from leveldb took 50287ns 3: I1122 21:46:18.122839 16009 replica.cpp:711] Persisted action TRUNCATE at position 14 3: I1122 21:46:18.122848 16015 replica.cpp:711] Persisted action TRUNCATE at position 14 3: I1122 21:46:18.123355 16016 log.cpp:560] Attempting to append 238 bytes to the log 3: I1122 21:46:18.123419 16022 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 15 3: I1122 21:46:18.123858 16004 replica.cpp:540] Replica received write request for position 15 from __req_res__(1553)@172.17.0.4:40853 3: I1122 21:46:18.123896 16013 replica.cpp:540] Replica received write request for position 15 from __req_res__(1554)@172.17.0.4:40853 3: I1122 21:46:18.125167 16013 leveldb.cpp:341] Persisting action (257 bytes) to leveldb took 1.24837ms 3: I1122 21:46:18.125169 16004 leveldb.cpp:341] Persisting action (257 bytes) to leveldb took 1.285666ms 3: I1122 21:46:18.125212 16013 replica.cpp:711] Persisted action APPEND at position 15 3: I1122 21:46:18.125226 16004 replica.cpp:711] Persisted action APPEND at position 15 3: I1122 21:46:18.125597 16010 replica.cpp:694] Replica received learned notice for position 15 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.125599 16006 replica.cpp:694] Replica received learned notice for position 15 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.126839 16010 leveldb.cpp:341] Persisting action (259 bytes) to leveldb took 1.225787ms 3: I1122 21:46:18.126876 16010 replica.cpp:711] Persisted action APPEND at position 15 3: I1122 21:46:18.126909 16006 leveldb.cpp:341] Persisting action (259 bytes) to leveldb took 1.291772ms 3: I1122 21:46:18.126947 16006 replica.cpp:711] Persisted action APPEND at position 15 3: I1122 21:46:18.127473 16018 registrar.cpp:552] Successfully updated the registry in 7296us 3: I1122 21:46:18.127609 16003 log.cpp:579] Attempting to truncate the log to 15 3: I1122 21:46:18.127674 16019 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 16 3: I1122 21:46:18.128103 16007 replica.cpp:540] Replica received write request for position 16 from __req_res__(1555)@172.17.0.4:40853 3: I1122 21:46:18.128175 16022 replica.cpp:540] Replica received write request for position 16 from __req_res__(1556)@172.17.0.4:40853 3: I1122 21:46:18.128398 16017 registrar.cpp:347] Recovering registrar 3: I1122 21:46:18.128829 16023 registrar.cpp:391] Successfully fetched the registry (199B) in 402944ns 3: I1122 21:46:18.128927 16023 registrar.cpp:495] Applied 1 operations in 41389ns; attempting to update the registry 3: I1122 21:46:18.129796 16022 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.602488ms 3: I1122 21:46:18.129813 16007 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.691936ms 3: I1122 21:46:18.129840 16022 replica.cpp:711] Persisted action TRUNCATE at position 16 3: I1122 21:46:18.129851 16007 replica.cpp:711] Persisted action TRUNCATE at position 16 3: I1122 21:46:18.130146 16013 replica.cpp:694] Replica received learned notice for position 16 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.130172 16006 replica.cpp:694] Replica received learned notice for position 16 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.131861 16006 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.68023ms 3: I1122 21:46:18.131880 16013 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 1.720556ms 3: I1122 21:46:18.131927 16013 leveldb.cpp:399] Deleting ~2 keys from leveldb took 33539ns 3: I1122 21:46:18.131927 16006 leveldb.cpp:399] Deleting ~2 keys from leveldb took 48717ns 3: I1122 21:46:18.131947 16013 replica.cpp:711] Persisted action TRUNCATE at position 16 3: I1122 21:46:18.131958 16006 replica.cpp:711] Persisted action TRUNCATE at position 16 3: I1122 21:46:18.132458 16005 log.cpp:560] Attempting to append 238 bytes to the log 3: I1122 21:46:18.132524 16020 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 17 3: I1122 21:46:18.133086 16024 replica.cpp:540] Replica received write request for position 17 from __req_res__(1557)@172.17.0.4:40853 3: I1122 21:46:18.133154 16007 replica.cpp:540] Replica received write request for position 17 from __req_res__(1558)@172.17.0.4:40853 3: I1122 21:46:18.134969 16007 leveldb.cpp:341] Persisting action (257 bytes) to leveldb took 1.792558ms 3: I1122 21:46:18.135012 16007 replica.cpp:711] Persisted action APPEND at position 17 3: I1122 21:46:18.135025 16024 leveldb.cpp:341] Persisting action (257 bytes) to leveldb took 1.915798ms 3: I1122 21:46:18.135053 16024 replica.cpp:711] Persisted action APPEND at position 17 3: I1122 21:46:18.135385 16013 replica.cpp:694] Replica received learned notice for position 17 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.135387 16006 replica.cpp:694] Replica received learned notice for position 17 from log-network(78)@172.17.0.4:40853 3: I1122 21:46:18.136514 16006 leveldb.cpp:341] Persisting action (259 bytes) to leveldb took 1.106764ms 3: I1122 21:46:18.136518 16013 leveldb.cpp:341] Persisting action (259 bytes) to leveldb took 1.117818ms 3: I1122 21:46:18.136548 16006 replica.cpp:711] Persisted action APPEND at position 17 3: I1122 21:46:18.136553 16013 replica.cpp:711] Persisted action APPEND at position 17 3: I1122 21:46:18.137213 16017 registrar.cpp:552] Successfully updated the registry in 8.241664ms 3: I1122 21:46:18.137308 16017 registrar.cpp:424] Successfully recovered registrar 3: I1122 21:46:18.137328 16020 log.cpp:579] Attempting to truncate the log to 17 3: I1122 21:46:18.137444 16021 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 18 3: I1122 21:46:18.138085 16019 replica.cpp:540] Replica received write request for position 18 from __req_res__(1559)@172.17.0.4:40853 3: I1122 21:46:18.138278 16015 replica.cpp:540] Replica received write request for position 18 from __req_res__(1560)@172.17.0.4:40853 + docker rmi mesos-1511385539-23952 Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information Build step 'Execute shell' marked build as failure
