ProHoster > Blog > uprava > 6 zabavnih sistemskih grešaka u radu Kubernetesa [i njihovo rješenje]
6 zabavnih sistemskih grešaka u radu Kubernetesa [i njihovo rješenje]
Tijekom godina korištenja Kubernetesa u proizvodnji nakupili smo mnogo zanimljivih priča o tome kako su greške u različitim komponentama sustava dovele do neugodnih i/ili neshvatljivih posljedica koje utječu na rad spremnika i podova. U ovom smo članku odabrali neke od najčešćih ili zanimljivih. Čak i ako nikad nemate sreće da se susrećete s takvim situacijama, čitanje ovakvih kratkih detektivskih priča – pogotovo iz “prve ruke” – uvijek je zanimljivo, zar ne?..
Priča 1. Supercronic i Docker vise
Na jednom od klastera povremeno smo dobivali zamrznuti Docker koji je ometao normalno funkcioniranje klastera. U isto vrijeme, u zapisima Dockera uočeno je sljedeće:
level=error msg="containerd: start init process" error="exit status 2: "runtime/cgo: pthread_create failed: No space left on device
SIGABRT: abort
PC=0x7f31b811a428 m=0
goroutine 0 [idle]:
goroutine 1 [running]:
runtime.systemstack_switch() /usr/local/go/src/runtime/asm_amd64.s:252 fp=0xc420026768 sp=0xc420026760
runtime.main() /usr/local/go/src/runtime/proc.go:127 +0x6c fp=0xc4200267c0 sp=0xc420026768
runtime.goexit() /usr/local/go/src/runtime/asm_amd64.s:2086 +0x1 fp=0xc4200267c8 sp=0xc4200267c0
goroutine 17 [syscall, locked to thread]:
runtime.goexit() /usr/local/go/src/runtime/asm_amd64.s:2086 +0x1
…
Ono što nas najviše zanima kod ove greške je poruka: pthread_create failed: No space left on device. Brzo učenje dokumentacija objasnio je da Docker ne može forkirati proces, zbog čega se povremeno zamrzava.
U praćenju, sljedeća slika odgovara onome što se događa:
Problem je sljedeći: kada se zadatak izvodi u supercronicu, proces koji je iznjedrio ne može ispravno završiti, pretvarajući se u zombi.
Primijetiti: Da budemo precizniji, procesi su stvoreni cron zadacima, ali supercronic nije init sustav i ne može "usvojiti" procese koje su proizvela njegova djeca. Kada se podignu signali SIGHUP ili SIGTERM, oni se ne prosljeđuju podređenim procesima, što rezultira time da se podređeni procesi ne prekidaju i ostaju u statusu zombija. Više o svemu tome možete pročitati npr. u takav članak.
Postoji nekoliko načina za rješavanje problema:
Kao privremeno rješenje - povećajte broj PID-ova u sustavu u jednom trenutku:
/proc/sys/kernel/pid_max (since Linux 2.5.34)
This file specifies the value at which PIDs wrap around (i.e., the value in this file is one greater than the maximum PID). PIDs greater than this value are not allo‐
cated; thus, the value in this file also acts as a system-wide limit on the total number of processes and threads. The default value for this file, 32768, results in the
same range of PIDs as on earlier kernels
Ili pokrenite zadatke u supercronicu ne izravno, već koristeći isti kace, koji može ispravno prekinuti procese i ne rađati zombije.
Priča 2. “Zombiji” prilikom brisanja cgrupe
Kubelet je počeo trošiti puno CPU-a:
Ovo se nikome neće svidjeti, pa smo se naoružali za izvedbu i počeo se baviti problemom. Rezultati istrage bili su sljedeći:
Kubelet troši više od trećine svog CPU vremena povlačeći memorijske podatke iz svih cgroup-ova:
Na mailing listi programera kernela možete pronaći rasprava o problemu. Ukratko, poanta se svodi na ovo: razne tmpfs datoteke i druge slične stvari nisu potpuno uklonjene iz sustava prilikom brisanja cgrupe tzv memcg zombi. Prije ili kasnije oni će biti izbrisani iz predmemorije stranice, ali na poslužitelju ima puno memorije i kernel ne vidi smisao u gubitku vremena na njihovo brisanje. Zato se i dalje gomilaju. Zašto se to uopće događa? Ovo je poslužitelj s cron poslovima koji stalno stvara nove poslove, a s njima i nove podove. Tako se stvaraju nove cgrupe za spremnike u njima, koje se ubrzo brišu.
Zašto cAdvisor u kubelet-u gubi toliko vremena? To je lako vidjeti s najjednostavnijom izvedbom time cat /sys/fs/cgroup/memory/memory.stat. Ako na zdravom stroju operacija traje 0,01 sekundu, onda na problematičnom cron02 traje 1,2 sekunde. Stvar je u tome što cAdvisor, koji vrlo sporo čita podatke iz sysfs-a, pokušava uzeti u obzir memoriju korištenu u zombie cgroups.
Da bismo nasilno uklonili zombije, pokušali smo očistiti predmemorije kao što je preporučeno u LKML-u: sync; echo 3 > /proc/sys/vm/drop_caches, - ali pokazalo se da je kernel kompliciraniji i srušio je auto.
Što uraditi? Problem se rješava (počiniti, a za opis vidi otpustiti poruku) ažuriranje jezgre Linuxa na verziju 4.16.
Povijest 3. Systemd i njegovo montiranje
Opet, kubelet troši previše resursa na nekim čvorovima, ali ovaj put troši previše memorije:
Ispostavilo se da postoji problem u systemd-u koji se koristi u Ubuntu 16.04, a javlja se prilikom upravljanja montiranjima koja su stvorena za povezivanje subPath iz ConfigMapa ili tajnih. Nakon što pod završi svoj posao servis systemd i njegovo montiranje servisa ostaju u sustavu. S vremenom ih se nakupi ogroman broj. Postoje čak i problemi na ovu temu:
#!/bin/bash
# we will work only on xenial
hostrelease="/etc/lsb-release-host"
test -f ${hostrelease} && grep xenial ${hostrelease} > /dev/null || exit 0
# sleeping max 30 minutes to dispense load on kube-nodes
sleep $((RANDOM % 1800))
stoppedCount=0
# counting actual subpath units in systemd
countBefore=$(systemctl list-units | grep subpath | grep "run-" | wc -l)
# let's go check each unit
for unit in $(systemctl list-units | grep subpath | grep "run-" | awk '{print $1}'); do
# finding description file for unit (to find out docker container, who born this unit)
DropFile=$(systemctl status ${unit} | grep Drop | awk -F': ' '{print $2}')
# reading uuid for docker container from description file
DockerContainerId=$(cat ${DropFile}/50-Description.conf | awk '{print $5}' | cut -d/ -f6)
# checking container status (running or not)
checkFlag=$(docker ps | grep -c ${DockerContainerId})
# if container not running, we will stop unit
if [[ ${checkFlag} -eq 0 ]]; then
echo "Stopping unit ${unit}"
# stoping unit in action
systemctl stop $unit
# just counter for logs
((stoppedCount++))
# logging current progress
echo "Stopped ${stoppedCount} systemd units out of ${countBefore}"
fi
done
... i radi svakih 5 minuta koristeći prethodno spomenuti supercronic. Njegov Dockerfile izgleda ovako:
Primijećeno je da: ako imamo mahunu postavljenu na čvor i njena slika se ispumpava jako dugo, onda će druga mahuna koja je "pogodila" isti čvor jednostavno ne počne povlačiti sliku nove mahune. Umjesto toga, čeka dok se ne povuče slika prethodne jedinice. Kao rezultat toga, pod koji je već zakazan i čija se slika mogla preuzeti za samo minutu završit će u statusu containerCreating.
Događaji će izgledati otprilike ovako:
Normal Pulling 8m kubelet, ip-10-241-44-128.ap-northeast-1.compute.internal pulling image "registry.example.com/infra/openvpn/openvpn:master"
Ispada da jedna slika iz sporog registra može blokirati implementaciju po čvoru.
Nažalost, nema mnogo izlaza iz situacije:
Pokušajte koristiti svoj Docker registar izravno u klasteru ili izravno s klasterom (na primjer, GitLab registar, Nexus itd.);
Tijekom rada raznih aplikacija također smo se susreli sa situacijom kada čvor potpuno prestaje biti dostupan: SSH ne odgovara, svi nadzorni demoni padaju, a zatim nema ničeg (ili gotovo ničeg) anomalnog u zapisima.
Reći ću vam u slikama na primjeru jednog čvora gdje je MongoDB funkcionirao.
Ovako izgleda vrh na nesreće:
I ovako - nakon nesreće:
U praćenju također postoji nagli skok, pri kojem čvor prestaje biti dostupan:
Dakle, iz snimaka zaslona jasno je da:
RAM na stroju je pri kraju;
Postoji nagli skok u potrošnji RAM-a, nakon čega je pristup cijelom stroju naglo onemogućen;
Veliki zadatak stiže na Mongo, što tjera DBMS proces da koristi više memorije i aktivno čita s diska.
Ispada da ako Linuxu ponestane slobodne memorije (postoji pritisak na memoriju) i nema swapa, tada na Kada OOM ubojica stigne, može doći do balansiranja između bacanja stranica u predmemoriju stranica i njihovog pisanja natrag na disk. To radi kswapd, koji hrabro oslobađa što više memorijskih stranica za kasniju distribuciju.
Nažalost, s velikim I/O opterećenjem u kombinaciji s malom količinom slobodne memorije, kswapd postaje usko grlo cijelog sustava, jer su vezani za to sve dodjele (greške stranice) memorijskih stranica u sustavu. To može potrajati jako dugo ako procesi više ne žele koristiti memoriju, nego su fiksirani na samom rubu OOM-killer ponora.
Prirodno pitanje je: zašto OOM ubojica dolazi tako kasno? U svojoj trenutnoj iteraciji, OOM killer je izuzetno glup: ubit će proces samo kada pokušaj dodjele memorijske stranice ne uspije, tj. ako pogreška stranice ne uspije. Ovo se ne događa dosta dugo, jer kswapd hrabro oslobađa memorijske stranice, vraćajući predmemoriju stranica (zapravo cijeli disk I/O u sustavu) natrag na disk. Detaljnije, s opisom koraka potrebnih za uklanjanje takvih problema u kernelu, možete pročitati здесь.
U nekim klasterima, u kojima djeluje stvarno mnogo mahuna, počeli smo primjećivati da većina njih “visi” jako dugo u stanju Pending, iako su sami Docker spremnici već pokrenuti na čvorovima i s njima se može raditi ručno.
Štoviše, u describe nema ništa loše:
Type Reason Age From Message
---- ------ ---- ---- -------
Normal Scheduled 1m default-scheduler Successfully assigned sphinx-0 to ss-dev-kub07
Normal SuccessfulAttachVolume 1m attachdetach-controller AttachVolume.Attach succeeded for volume "pvc-6aaad34f-ad10-11e8-a44c-52540035a73b"
Normal SuccessfulMountVolume 1m kubelet, ss-dev-kub07 MountVolume.SetUp succeeded for volume "sphinx-config"
Normal SuccessfulMountVolume 1m kubelet, ss-dev-kub07 MountVolume.SetUp succeeded for volume "default-token-fzcsf"
Normal SuccessfulMountVolume 49s (x2 over 51s) kubelet, ss-dev-kub07 MountVolume.SetUp succeeded for volume "pvc-6aaad34f-ad10-11e8-a44c-52540035a73b"
Normal Pulled 43s kubelet, ss-dev-kub07 Container image "registry.example.com/infra/sphinx-exporter/sphinx-indexer:v1" already present on machine
Normal Created 43s kubelet, ss-dev-kub07 Created container
Normal Started 43s kubelet, ss-dev-kub07 Started container
Normal Pulled 43s kubelet, ss-dev-kub07 Container image "registry.example.com/infra/sphinx/sphinx:v1" already present on machine
Normal Created 42s kubelet, ss-dev-kub07 Created container
Normal Started 42s kubelet, ss-dev-kub07 Started container
Nakon nekog kopanja, pretpostavili smo da kubelet jednostavno nema vremena poslati sve informacije o stanju mahuna i testovima živosti/spremnosti API poslužitelju.
I nakon proučavanja pomoći, pronašli smo sljedeće parametre:
--kube-api-qps - QPS to use while talking with kubernetes apiserver (default 5)
--kube-api-burst - Burst to use while talking with kubernetes apiserver (default 10)
--event-qps - If > 0, limit event creations per second to this value. If 0, unlimited. (default 5)
--event-burst - Maximum size of a bursty event records, temporarily allows event records to burst to this number, while still not exceeding event-qps. Only used if --event-qps > 0 (default 10)
--registry-qps - If > 0, limit registry pull QPS to this value.
--registry-burst - Maximum size of bursty pulls, temporarily allows pulls to burst to this number, while still not exceeding registry-qps. Only used if --registry-qps > 0 (default 10)
Kao što se vidi, zadane vrijednosti su prilično male, au 90% pokrivaju sve potrebe... Međutim, u našem slučaju to nije bilo dovoljno. Stoga postavljamo sljedeće vrijednosti:
... i ponovno pokrenuli kubelets, nakon čega smo vidjeli sljedeću sliku u grafovima poziva prema API poslužitelju:
... i da, sve je počelo letjeti!
PS
Za njihovu pomoć u prikupljanju grešaka i pripremi ovog članka, izražavam duboku zahvalnost brojnim inženjerima naše tvrtke, a posebno mom kolegi iz našeg tima za istraživanje i razvoj Andreju Klimentjevu (zuzze).