{"level":"warn","ts":"2026-05-12T16:58:57.115248Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:58:57.115345Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.236:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.236:2380","--initial-cluster=instance=https://199.19.213.236:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.236:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.236:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T16:58:57.115499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:58:57.115514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-05-12T16:58:57.115536Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T16:58:57.116163Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"]} {"level":"info","ts":"2026-05-12T16:58:57.116234Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.236:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T16:58:57.338457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"222.009255ms"} {"level":"info","ts":"2026-05-12T16:58:57.633128Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f20dfa43a2e4607","cluster-id":"85a3671ea9bffea4"} {"level":"info","ts":"2026-05-12T16:58:57.633289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:58:57.633332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 0"} {"level":"info","ts":"2026-05-12T16:58:57.633386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f20dfa43a2e4607 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:58:57.633401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 1"} {"level":"info","ts":"2026-05-12T16:58:57.633495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"warn","ts":"2026-05-12T16:58:57.644204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:58:57.646069Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:58:57.647269Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T16:58:57.649683Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f20dfa43a2e4607","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:58:57.65385Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:58:57.653851Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f20dfa43a2e4607","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:58:57.653946Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:58:57.653973Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T16:58:57.655187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"info","ts":"2026-05-12T16:58:57.655735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","added-peer-id":"5f20dfa43a2e4607","added-peer-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-05-12T16:58:57.659537Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T16:58:57.659671Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-05-12T16:58:57.659746Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-05-12T16:58:57.65979Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f20dfa43a2e4607","initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:58:57.659838Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:58:57.840626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:58:57.840806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:58:57.841042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgPreVoteResp from 5f20dfa43a2e4607 at term 1"} {"level":"info","ts":"2026-05-12T16:58:57.84135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:58:57.841374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgVoteResp from 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-05-12T16:58:57.841392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became leader at term 2"} {"level":"info","ts":"2026-05-12T16:58:57.84176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f20dfa43a2e4607 elected leader 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-05-12T16:58:57.843828Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:58:57.844998Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f20dfa43a2e4607","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.236:2379]}","request-path":"/0/members/5f20dfa43a2e4607/attributes","cluster-id":"85a3671ea9bffea4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:58:57.845039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:58:57.845098Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:58:57.845588Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:58:57.845608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:58:57.847815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.236:2379"} {"level":"info","ts":"2026-05-12T16:58:57.848679Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:58:57.848745Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:58:57.848768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:58:57.848939Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T16:59:35.153181Z","caller":"traceutil/trace.go:171","msg":"trace[1340662209] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"126.237503ms","start":"2026-05-12T16:59:35.02692Z","end":"2026-05-12T16:59:35.153157Z","steps":["trace[1340662209] 'process raft request' (duration: 125.527188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:59:35.615498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.840047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:59:35.615649Z","caller":"traceutil/trace.go:171","msg":"trace[91193258] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"308.368969ms","start":"2026-05-12T16:59:35.307264Z","end":"2026-05-12T16:59:35.615633Z","steps":["trace[91193258] 'read index received' (duration: 147.584296ms)","trace[91193258] 'applied index is now lower than readState.Index' (duration: 160.783403ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:59:35.615757Z","caller":"traceutil/trace.go:171","msg":"trace[591472857] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"410.025259ms","start":"2026-05-12T16:59:35.205699Z","end":"2026-05-12T16:59:35.615724Z","steps":["trace[591472857] 'process raft request' (duration: 249.213635ms)","trace[591472857] 'compare' (duration: 158.238226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:59:35.615915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.640761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cfhqp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-12T16:59:35.615986Z","caller":"traceutil/trace.go:171","msg":"trace[1514146361] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cfhqp; range_end:; response_count:1; response_revision:618; }","duration":"308.752981ms","start":"2026-05-12T16:59:35.307222Z","end":"2026-05-12T16:59:35.615975Z","steps":["trace[1514146361] 'agreement among raft nodes before linearized reading' (duration: 308.51996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:59:35.615929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:59:35.205677Z","time spent":"410.19131ms","remote":"127.0.0.1:51466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T16:59:35.616049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:59:35.3072Z","time spent":"308.839402ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-cfhqp\" "} {"level":"warn","ts":"2026-05-12T16:59:35.616285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.705657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T16:59:35.616369Z","caller":"traceutil/trace.go:171","msg":"trace[762283854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"144.847237ms","start":"2026-05-12T16:59:35.471502Z","end":"2026-05-12T16:59:35.616349Z","steps":["trace[762283854] 'agreement among raft nodes before linearized reading' (duration: 144.303463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:59:58.473158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.638985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:59:58.473417Z","caller":"traceutil/trace.go:171","msg":"trace[181854138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"101.4194ms","start":"2026-05-12T16:59:58.371967Z","end":"2026-05-12T16:59:58.473386Z","steps":["trace[181854138] 'range keys from in-memory index tree' (duration: 100.446094ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:59:58.627588Z","caller":"traceutil/trace.go:171","msg":"trace[1553773969] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"145.285804ms","start":"2026-05-12T16:59:58.482277Z","end":"2026-05-12T16:59:58.627562Z","steps":["trace[1553773969] 'process raft request' (duration: 145.131193ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:00:21.481766Z","caller":"traceutil/trace.go:171","msg":"trace[1380867389] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"106.941352ms","start":"2026-05-12T17:00:21.374805Z","end":"2026-05-12T17:00:21.481746Z","steps":["trace[1380867389] 'process raft request' (duration: 106.706304ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:01:28.443574Z","caller":"traceutil/trace.go:171","msg":"trace[1046501048] linearizableReadLoop","detail":"{readStateIndex:2019; appliedIndex:2018; }","duration":"186.442481ms","start":"2026-05-12T17:01:28.257112Z","end":"2026-05-12T17:01:28.443555Z","steps":["trace[1046501048] 'read index received' (duration: 186.347519ms)","trace[1046501048] 'applied index is now lower than readState.Index' (duration: 94.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:01:28.443654Z","caller":"traceutil/trace.go:171","msg":"trace[490789451] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"229.545277ms","start":"2026-05-12T17:01:28.214063Z","end":"2026-05-12T17:01:28.443609Z","steps":["trace[490789451] 'process raft request' (duration: 229.391404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:01:28.443731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.618813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T17:01:28.443783Z","caller":"traceutil/trace.go:171","msg":"trace[1998334389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1942; }","duration":"186.722806ms","start":"2026-05-12T17:01:28.257055Z","end":"2026-05-12T17:01:28.443778Z","steps":["trace[1998334389] 'agreement among raft nodes before linearized reading' (duration: 186.595673ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:08.765024Z","caller":"traceutil/trace.go:171","msg":"trace[1906980928] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"114.002158ms","start":"2026-05-12T17:02:08.65099Z","end":"2026-05-12T17:02:08.764992Z","steps":["trace[1906980928] 'process raft request' (duration: 95.388704ms)","trace[1906980928] 'compare' (duration: 18.494243ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:02:30.163888Z","caller":"traceutil/trace.go:171","msg":"trace[1238050810] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"173.779368ms","start":"2026-05-12T17:02:29.990068Z","end":"2026-05-12T17:02:30.163847Z","steps":["trace[1238050810] 'process raft request' (duration: 94.959004ms)","trace[1238050810] 'compare' (duration: 78.110515ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:02:30.163893Z","caller":"traceutil/trace.go:171","msg":"trace[2099926303] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"101.902407ms","start":"2026-05-12T17:02:30.061949Z","end":"2026-05-12T17:02:30.163851Z","steps":["trace[2099926303] 'process raft request' (duration: 101.645715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:30.666771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.481312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T17:02:30.666876Z","caller":"traceutil/trace.go:171","msg":"trace[1361672227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2322; }","duration":"166.636473ms","start":"2026-05-12T17:02:30.500217Z","end":"2026-05-12T17:02:30.666853Z","steps":["trace[1361672227] 'range keys from in-memory index tree' (duration: 166.287309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:30.667136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.614514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T17:02:30.667239Z","caller":"traceutil/trace.go:171","msg":"trace[1998617076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2322; }","duration":"161.757965ms","start":"2026-05-12T17:02:30.505459Z","end":"2026-05-12T17:02:30.667217Z","steps":["trace[1998617076] 'range keys from in-memory index tree' (duration: 161.28793ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:30.837854Z","caller":"traceutil/trace.go:171","msg":"trace[274312053] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2412; }","duration":"156.634876ms","start":"2026-05-12T17:02:30.681197Z","end":"2026-05-12T17:02:30.837832Z","steps":["trace[274312053] 'read index received' (duration: 52.2585ms)","trace[274312053] 'applied index is now lower than readState.Index' (duration: 104.375496ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:02:30.838004Z","caller":"traceutil/trace.go:171","msg":"trace[750291250] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"166.180508ms","start":"2026-05-12T17:02:30.671772Z","end":"2026-05-12T17:02:30.837953Z","steps":["trace[750291250] 'process raft request' (duration: 146.998091ms)","trace[750291250] 'compare' (duration: 18.971805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:02:30.838087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.879938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-05-12T17:02:30.83812Z","caller":"traceutil/trace.go:171","msg":"trace[1466318204] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2324; }","duration":"156.952329ms","start":"2026-05-12T17:02:30.681158Z","end":"2026-05-12T17:02:30.83811Z","steps":["trace[1466318204] 'agreement among raft nodes before linearized reading' (duration: 156.809377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:30.838369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-12T17:02:30.838476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.656429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.236\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T17:02:30.838582Z","caller":"traceutil/trace.go:171","msg":"trace[249222183] range","detail":"{range_begin:/registry/masterleases/199.19.213.236; range_end:; response_count:1; response_revision:2324; }","duration":"137.821983ms","start":"2026-05-12T17:02:30.700738Z","end":"2026-05-12T17:02:30.83856Z","steps":["trace[249222183] 'agreement among raft nodes before linearized reading' (duration: 137.484688ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:30.838759Z","caller":"traceutil/trace.go:171","msg":"trace[1483419093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2324; }","duration":"104.444557ms","start":"2026-05-12T17:02:30.734005Z","end":"2026-05-12T17:02:30.83845Z","steps":["trace[1483419093] 'agreement among raft nodes before linearized reading' (duration: 104.215034ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:31.016276Z","caller":"traceutil/trace.go:171","msg":"trace[93556980] linearizableReadLoop","detail":"{readStateIndex:2416; appliedIndex:2414; }","duration":"171.466429ms","start":"2026-05-12T17:02:30.844777Z","end":"2026-05-12T17:02:31.016244Z","steps":["trace[93556980] 'read index received' (duration: 163.643647ms)","trace[93556980] 'applied index is now lower than readState.Index' (duration: 7.821592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:02:31.01692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.643792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T17:02:31.016941Z","caller":"traceutil/trace.go:171","msg":"trace[762905763] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"174.950931ms","start":"2026-05-12T17:02:30.841946Z","end":"2026-05-12T17:02:31.016897Z","steps":["trace[762905763] 'process raft request' (duration: 174.114531ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:31.016979Z","caller":"traceutil/trace.go:171","msg":"trace[448380328] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2325; }","duration":"172.213458ms","start":"2026-05-12T17:02:30.844752Z","end":"2026-05-12T17:02:31.016965Z","steps":["trace[448380328] 'agreement among raft nodes before linearized reading' (duration: 171.604291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:31.091976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.764612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T17:02:31.092056Z","caller":"traceutil/trace.go:171","msg":"trace[1264369204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2326; }","duration":"237.871713ms","start":"2026-05-12T17:02:30.854165Z","end":"2026-05-12T17:02:31.092037Z","steps":["trace[1264369204] 'agreement among raft nodes before linearized reading' (duration: 237.604189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:04:05.663218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.949687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T17:04:05.663504Z","caller":"traceutil/trace.go:171","msg":"trace[367988924] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"133.057141ms","start":"2026-05-12T17:04:05.530429Z","end":"2026-05-12T17:04:05.663486Z","steps":["trace[367988924] 'process raft request' (duration: 13.68095ms)","trace[367988924] 'compare' (duration: 118.765474ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:07:44.985255Z","caller":"traceutil/trace.go:171","msg":"trace[496409278] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"146.684906ms","start":"2026-05-12T17:07:44.838547Z","end":"2026-05-12T17:07:44.985232Z","steps":["trace[496409278] 'process raft request' (duration: 125.607226ms)","trace[496409278] 'compare' (duration: 20.869197ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:07:53.053326Z","caller":"traceutil/trace.go:171","msg":"trace[1424792650] linearizableReadLoop","detail":"{readStateIndex:5088; appliedIndex:5087; }","duration":"167.161462ms","start":"2026-05-12T17:07:52.886108Z","end":"2026-05-12T17:07:53.05327Z","steps":["trace[1424792650] 'read index received' (duration: 166.899719ms)","trace[1424792650] 'applied index is now lower than readState.Index' (duration: 259.923µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:07:53.053396Z","caller":"traceutil/trace.go:171","msg":"trace[851963192] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"266.947621ms","start":"2026-05-12T17:07:52.786417Z","end":"2026-05-12T17:07:53.053365Z","steps":["trace[851963192] 'process raft request' (duration: 266.588926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:07:53.053673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.540787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T17:07:53.053778Z","caller":"traceutil/trace.go:171","msg":"trace[813619010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4904; }","duration":"167.72561ms","start":"2026-05-12T17:07:52.886029Z","end":"2026-05-12T17:07:53.053755Z","steps":["trace[813619010] 'agreement among raft nodes before linearized reading' (duration: 167.428596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:07:53.113035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.84614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce5cb415-4a7a-4a3b-8e33-06e5cd614581\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-12T17:07:53.113148Z","caller":"traceutil/trace.go:171","msg":"trace[629018438] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce5cb415-4a7a-4a3b-8e33-06e5cd614581; range_end:; response_count:1; response_revision:4905; }","duration":"203.024884ms","start":"2026-05-12T17:07:52.910098Z","end":"2026-05-12T17:07:53.113123Z","steps":["trace[629018438] 'agreement among raft nodes before linearized reading' (duration: 202.621497ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:08:05.812807Z","caller":"traceutil/trace.go:171","msg":"trace[785822102] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"280.415892ms","start":"2026-05-12T17:08:05.532362Z","end":"2026-05-12T17:08:05.812778Z","steps":["trace[785822102] 'process raft request' (duration: 258.442921ms)","trace[785822102] 'compare' (duration: 21.642187ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:08:05.813424Z","caller":"traceutil/trace.go:171","msg":"trace[2065015048] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"280.840808ms","start":"2026-05-12T17:08:05.532455Z","end":"2026-05-12T17:08:05.813296Z","steps":["trace[2065015048] 'process raft request' (duration: 280.774217ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:08:57.877669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-05-12T17:08:57.975834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"96.499587ms","hash":3929065881,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":28577792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T17:08:57.976016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3929065881,"revision":2904,"compact-revision":-1} {"level":"info","ts":"2026-05-12T17:11:50.926402Z","caller":"traceutil/trace.go:171","msg":"trace[2146308718] linearizableReadLoop","detail":"{readStateIndex:6954; appliedIndex:6952; }","duration":"169.037305ms","start":"2026-05-12T17:11:50.757334Z","end":"2026-05-12T17:11:50.926371Z","steps":["trace[2146308718] 'read index received' (duration: 51.258708ms)","trace[2146308718] 'applied index is now lower than readState.Index' (duration: 117.777427ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:11:50.926471Z","caller":"traceutil/trace.go:171","msg":"trace[1698093484] transaction","detail":"{read_only:false; response_revision:6710; number_of_response:1; }","duration":"170.197248ms","start":"2026-05-12T17:11:50.756231Z","end":"2026-05-12T17:11:50.926428Z","steps":["trace[1698093484] 'process raft request' (duration: 86.09223ms)","trace[1698093484] 'compare' (duration: 83.850645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:11:50.926671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.282128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-12T17:11:50.926671Z","caller":"traceutil/trace.go:171","msg":"trace[1938602871] transaction","detail":"{read_only:false; response_revision:6711; number_of_response:1; }","duration":"169.257687ms","start":"2026-05-12T17:11:50.757402Z","end":"2026-05-12T17:11:50.926659Z","steps":["trace[1938602871] 'process raft request' (duration: 168.917253ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:11:50.92671Z","caller":"traceutil/trace.go:171","msg":"trace[1000143443] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:6711; }","duration":"169.408968ms","start":"2026-05-12T17:11:50.757289Z","end":"2026-05-12T17:11:50.926698Z","steps":["trace[1000143443] 'agreement among raft nodes before linearized reading' (duration: 169.199966ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:13:57.887836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5522} {"level":"info","ts":"2026-05-12T17:13:57.967158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5522,"took":"77.205984ms","hash":3702205878,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":18530304,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-12T17:13:57.967233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3702205878,"revision":5522,"compact-revision":2904}