{"level":"warn","ts":"2026-05-21T00:24:29.902052Z","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-21T00:24:29.902155Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-21T00:24:29.902242Z","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-21T00:24:29.902253Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-21T00:24:29.90227Z","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-21T00:24:29.902944Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-21T00:24:29.903033Z","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":16,"max-cpu-available":16,"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.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-21T00:24:29.906924Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.645179ms"} {"level":"info","ts":"2026-05-21T00:24:29.912521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-21T00:24:29.912618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T00:24:29.912648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-21T00:24:29.912668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T00:24:29.912686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-21T00:24:29.912737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-21T00:24:29.918019Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T00:24:29.919224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T00:24:29.92027Z","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-21T00:24:29.922772Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T00:24:29.922904Z","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-21T00:24:29.92299Z","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-21T00:24:29.923001Z","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-21T00:24:29.922978Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T00:24:29.923963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-21T00:24:29.924105Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-21T00:24:29.928417Z","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-21T00:24:29.928686Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T00:24:29.928724Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T00:24:29.92882Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-21T00:24:29.928836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-21T00:24:30.31341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T00:24:30.313562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T00:24:30.313628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-21T00:24:30.313686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-21T00:24:30.313728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-21T00:24:30.31377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-21T00:24:30.313817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-21T00:24:30.314806Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T00:24:30.31568Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T00:24:30.315692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T00:24:30.315845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T00:24:30.31635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T00:24:30.316429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T00:24:30.316528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T00:24:30.316596Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T00:24:30.316677Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T00:24:30.319276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-21T00:24:30.319698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T00:24:47.42272Z","caller":"traceutil/trace.go:171","msg":"trace[1822780099] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"178.914482ms","start":"2026-05-21T00:24:47.243781Z","end":"2026-05-21T00:24:47.422696Z","steps":["trace[1822780099] 'process raft request' (duration: 178.784119ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:25:14.136274Z","caller":"traceutil/trace.go:171","msg":"trace[1037858443] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"113.780544ms","start":"2026-05-21T00:25:14.022471Z","end":"2026-05-21T00:25:14.136251Z","steps":["trace[1037858443] 'process raft request' (duration: 113.633701ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:25:14.536583Z","caller":"traceutil/trace.go:171","msg":"trace[1299685252] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"165.560457ms","start":"2026-05-21T00:25:14.371Z","end":"2026-05-21T00:25:14.536561Z","steps":["trace[1299685252] 'process raft request' (duration: 165.398143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:25:15.025169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.706776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T00:25:15.02529Z","caller":"traceutil/trace.go:171","msg":"trace[2024124637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"239.8826ms","start":"2026-05-21T00:25:14.785385Z","end":"2026-05-21T00:25:15.025268Z","steps":["trace[2024124637] 'range keys from in-memory index tree' (duration: 239.48824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:25:29.661224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.290675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T00:25:29.66137Z","caller":"traceutil/trace.go:171","msg":"trace[1160780260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"110.4615ms","start":"2026-05-21T00:25:29.550859Z","end":"2026-05-21T00:25:29.66132Z","steps":["trace[1160780260] 'range keys from in-memory index tree' (duration: 110.183744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:25:42.735901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.817258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T00:25:42.73601Z","caller":"traceutil/trace.go:171","msg":"trace[751142259] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:955; }","duration":"126.037094ms","start":"2026-05-21T00:25:42.609953Z","end":"2026-05-21T00:25:42.73599Z","steps":["trace[751142259] 'count revisions from in-memory index tree' (duration: 125.679605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:26:46.961998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.627482ms","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-21T00:26:46.962081Z","caller":"traceutil/trace.go:171","msg":"trace[65034611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"137.745345ms","start":"2026-05-21T00:26:46.82432Z","end":"2026-05-21T00:26:46.962066Z","steps":["trace[65034611] 'range keys from in-memory index tree' (duration: 137.50283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:26:46.962026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.666671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa6d3eaa-d599-4029-ac6c-3a5a82b62d89\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-21T00:26:46.962155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.466405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa6d3eaa-d599-4029-ac6c-3a5a82b62d89\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-21T00:26:46.962239Z","caller":"traceutil/trace.go:171","msg":"trace[990011473] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa6d3eaa-d599-4029-ac6c-3a5a82b62d89; range_end:; response_count:1; response_revision:1872; }","duration":"174.597408ms","start":"2026-05-21T00:26:46.78762Z","end":"2026-05-21T00:26:46.962217Z","steps":["trace[990011473] 'range keys from in-memory index tree' (duration: 174.26078ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:26:46.962227Z","caller":"traceutil/trace.go:171","msg":"trace[278901278] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa6d3eaa-d599-4029-ac6c-3a5a82b62d89; range_end:; response_count:1; response_revision:1872; }","duration":"178.923607ms","start":"2026-05-21T00:26:46.783264Z","end":"2026-05-21T00:26:46.962188Z","steps":["trace[278901278] 'range keys from in-memory index tree' (duration: 178.469026ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:26:47.142247Z","caller":"traceutil/trace.go:171","msg":"trace[1811841704] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"172.701011ms","start":"2026-05-21T00:26:46.969515Z","end":"2026-05-21T00:26:47.142216Z","steps":["trace[1811841704] 'process raft request' (duration: 172.390463ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:26:47.515517Z","caller":"traceutil/trace.go:171","msg":"trace[1980983712] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"305.902961ms","start":"2026-05-21T00:26:47.209592Z","end":"2026-05-21T00:26:47.515495Z","steps":["trace[1980983712] 'process raft request' (duration: 305.777708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:26:47.51615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:26:47.209571Z","time spent":"306.015733ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T00:26:47.574684Z","caller":"traceutil/trace.go:171","msg":"trace[1208248836] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"364.721929ms","start":"2026-05-21T00:26:47.209938Z","end":"2026-05-21T00:26:47.57466Z","steps":["trace[1208248836] 'process raft request' (duration: 364.552025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:26:47.574899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:26:47.209924Z","time spent":"364.893913ms","remote":"127.0.0.1:48254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T00:26:47.575022Z","caller":"traceutil/trace.go:171","msg":"trace[733566158] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"364.482403ms","start":"2026-05-21T00:26:47.210507Z","end":"2026-05-21T00:26:47.57499Z","steps":["trace[733566158] 'process raft request' (duration: 364.101884ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:26:47.575043Z","caller":"traceutil/trace.go:171","msg":"trace[1383484378] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"364.140375ms","start":"2026-05-21T00:26:47.210885Z","end":"2026-05-21T00:26:47.575025Z","steps":["trace[1383484378] 'process raft request' (duration: 364.004942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:26:47.575137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:26:47.210492Z","time spent":"364.584256ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T00:26:47.575172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:26:47.210874Z","time spent":"364.227597ms","remote":"127.0.0.1:48254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T00:27:01.970369Z","caller":"traceutil/trace.go:171","msg":"trace[160548054] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"146.368833ms","start":"2026-05-21T00:27:01.82398Z","end":"2026-05-21T00:27:01.970349Z","steps":["trace[160548054] 'process raft request' (duration: 146.22248ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:27:36.202637Z","caller":"traceutil/trace.go:171","msg":"trace[672435839] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"124.562649ms","start":"2026-05-21T00:27:36.07805Z","end":"2026-05-21T00:27:36.202612Z","steps":["trace[672435839] 'process raft request' (duration: 124.368225ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:27:42.348756Z","caller":"traceutil/trace.go:171","msg":"trace[755505894] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"129.400772ms","start":"2026-05-21T00:27:42.219329Z","end":"2026-05-21T00:27:42.34873Z","steps":["trace[755505894] 'process raft request' (duration: 129.175797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:27:42.780044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.681695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T00:27:42.780131Z","caller":"traceutil/trace.go:171","msg":"trace[507718763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2228; }","duration":"355.780778ms","start":"2026-05-21T00:27:42.424334Z","end":"2026-05-21T00:27:42.780115Z","steps":["trace[507718763] 'range keys from in-memory index tree' (duration: 355.525611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:27:42.780171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:27:42.42428Z","time spent":"355.88153ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T00:27:42.780707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.790119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T00:27:42.780875Z","caller":"traceutil/trace.go:171","msg":"trace[2070763600] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"339.945269ms","start":"2026-05-21T00:27:42.440912Z","end":"2026-05-21T00:27:42.780857Z","steps":["trace[2070763600] 'process raft request' (duration: 16.317799ms)","trace[2070763600] 'compare' (duration: 322.46664ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:27:42.780864Z","caller":"traceutil/trace.go:171","msg":"trace[1671412484] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"258.216026ms","start":"2026-05-21T00:27:42.522593Z","end":"2026-05-21T00:27:42.780809Z","steps":["trace[1671412484] 'read index received' (duration: 40.281µs)","trace[1671412484] 'applied index is now lower than readState.Index' (duration: 258.174365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T00:27:42.780987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T00:27:42.440891Z","time spent":"340.031442ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T00:27:42.781385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.78228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T00:27:42.781577Z","caller":"traceutil/trace.go:171","msg":"trace[174262949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2229; }","duration":"258.867492ms","start":"2026-05-21T00:27:42.522553Z","end":"2026-05-21T00:27:42.781421Z","steps":["trace[174262949] 'agreement among raft nodes before linearized reading' (duration: 258.396771ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:27:42.902967Z","caller":"traceutil/trace.go:171","msg":"trace[613564192] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"118.293212ms","start":"2026-05-21T00:27:42.784651Z","end":"2026-05-21T00:27:42.902945Z","steps":["trace[613564192] 'process raft request' (duration: 107.919552ms)","trace[613564192] 'compare' (duration: 10.282607ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:27:43.040467Z","caller":"traceutil/trace.go:171","msg":"trace[1759551362] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2315; }","duration":"112.59004ms","start":"2026-05-21T00:27:42.92785Z","end":"2026-05-21T00:27:43.040441Z","steps":["trace[1759551362] 'read index received' (duration: 109.706147ms)","trace[1759551362] 'applied index is now lower than readState.Index' (duration: 2.882513ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:27:43.040509Z","caller":"traceutil/trace.go:171","msg":"trace[824568793] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"117.498462ms","start":"2026-05-21T00:27:42.922984Z","end":"2026-05-21T00:27:43.040482Z","steps":["trace[824568793] 'process raft request' (duration: 114.589908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:27:43.04086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.992309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T00:27:43.04091Z","caller":"traceutil/trace.go:171","msg":"trace[832590183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2231; }","duration":"113.088532ms","start":"2026-05-21T00:27:42.92781Z","end":"2026-05-21T00:27:43.040898Z","steps":["trace[832590183] 'agreement among raft nodes before linearized reading' (duration: 112.728623ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:31:28.471493Z","caller":"traceutil/trace.go:171","msg":"trace[481720085] transaction","detail":"{read_only:false; response_revision:3827; number_of_response:1; }","duration":"133.264022ms","start":"2026-05-21T00:31:28.338203Z","end":"2026-05-21T00:31:28.471467Z","steps":["trace[481720085] 'process raft request' (duration: 133.132498ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:51.634567Z","caller":"traceutil/trace.go:171","msg":"trace[1868614566] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"129.006569ms","start":"2026-05-21T00:32:51.505525Z","end":"2026-05-21T00:32:51.634531Z","steps":["trace[1868614566] 'process raft request' (duration: 36.064968ms)","trace[1868614566] 'compare' (duration: 92.804928ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:32:51.635839Z","caller":"traceutil/trace.go:171","msg":"trace[113733827] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"123.996297ms","start":"2026-05-21T00:32:51.511818Z","end":"2026-05-21T00:32:51.635814Z","steps":["trace[113733827] 'process raft request' (duration: 123.670709ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:54.398751Z","caller":"traceutil/trace.go:171","msg":"trace[1572853464] linearizableReadLoop","detail":"{readStateIndex:5190; appliedIndex:5189; }","duration":"101.537591ms","start":"2026-05-21T00:32:54.297193Z","end":"2026-05-21T00:32:54.39873Z","steps":["trace[1572853464] 'read index received' (duration: 70.854594ms)","trace[1572853464] 'applied index is now lower than readState.Index' (duration: 30.682157ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:32:54.398821Z","caller":"traceutil/trace.go:171","msg":"trace[583665509] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"150.452842ms","start":"2026-05-21T00:32:54.248331Z","end":"2026-05-21T00:32:54.398783Z","steps":["trace[583665509] 'process raft request' (duration: 119.790426ms)","trace[583665509] 'compare' (duration: 30.500872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T00:32:54.398963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.750246ms","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-21T00:32:54.399041Z","caller":"traceutil/trace.go:171","msg":"trace[1141160470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5001; }","duration":"101.856479ms","start":"2026-05-21T00:32:54.297162Z","end":"2026-05-21T00:32:54.399019Z","steps":["trace[1141160470] 'agreement among raft nodes before linearized reading' (duration: 101.657864ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:54.597217Z","caller":"traceutil/trace.go:171","msg":"trace[402714143] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5190; }","duration":"193.113639ms","start":"2026-05-21T00:32:54.404079Z","end":"2026-05-21T00:32:54.597193Z","steps":["trace[402714143] 'read index received' (duration: 94.639683ms)","trace[402714143] 'applied index is now lower than readState.Index' (duration: 98.473056ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:32:54.597329Z","caller":"traceutil/trace.go:171","msg":"trace[423977297] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"194.661876ms","start":"2026-05-21T00:32:54.402655Z","end":"2026-05-21T00:32:54.597317Z","steps":["trace[423977297] 'process raft request' (duration: 194.478132ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:54.59749Z","caller":"traceutil/trace.go:171","msg":"trace[1910937452] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"195.497097ms","start":"2026-05-21T00:32:54.401981Z","end":"2026-05-21T00:32:54.597478Z","steps":["trace[1910937452] 'process raft request' (duration: 195.023056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:32:54.597539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.324841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdr4q\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-21T00:32:54.597601Z","caller":"traceutil/trace.go:171","msg":"trace[303590957] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdr4q; range_end:; response_count:1; response_revision:5004; }","duration":"192.428003ms","start":"2026-05-21T00:32:54.405159Z","end":"2026-05-21T00:32:54.597587Z","steps":["trace[303590957] 'agreement among raft nodes before linearized reading' (duration: 192.257949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:32:54.597568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.456477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T00:32:54.597616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.638628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T00:32:54.597662Z","caller":"traceutil/trace.go:171","msg":"trace[1004592273] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5004; }","duration":"193.593041ms","start":"2026-05-21T00:32:54.404043Z","end":"2026-05-21T00:32:54.597636Z","steps":["trace[1004592273] 'agreement among raft nodes before linearized reading' (duration: 193.434897ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:54.597695Z","caller":"traceutil/trace.go:171","msg":"trace[546617446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5004; }","duration":"102.761301ms","start":"2026-05-21T00:32:54.494911Z","end":"2026-05-21T00:32:54.597672Z","steps":["trace[546617446] 'agreement among raft nodes before linearized reading' (duration: 102.536135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:32:54.597703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.243155ms","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-21T00:32:54.597811Z","caller":"traceutil/trace.go:171","msg":"trace[834338868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5004; }","duration":"113.389909ms","start":"2026-05-21T00:32:54.4844Z","end":"2026-05-21T00:32:54.59779Z","steps":["trace[834338868] 'agreement among raft nodes before linearized reading' (duration: 113.115452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:32:54.598184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.709881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-45jvs-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-05-21T00:32:54.598255Z","caller":"traceutil/trace.go:171","msg":"trace[537117808] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-45jvs-tls; range_end:; response_count:1; response_revision:5004; }","duration":"100.838544ms","start":"2026-05-21T00:32:54.497398Z","end":"2026-05-21T00:32:54.598237Z","steps":["trace[537117808] 'agreement among raft nodes before linearized reading' (duration: 100.041835ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:56.728448Z","caller":"traceutil/trace.go:171","msg":"trace[1636291690] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"139.71535ms","start":"2026-05-21T00:32:56.5887Z","end":"2026-05-21T00:32:56.728415Z","steps":["trace[1636291690] 'process raft request' (duration: 105.99525ms)","trace[1636291690] 'compare' (duration: 33.504895ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:32:56.728484Z","caller":"traceutil/trace.go:171","msg":"trace[342006779] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"115.955353ms","start":"2026-05-21T00:32:56.612514Z","end":"2026-05-21T00:32:56.72847Z","steps":["trace[342006779] 'process raft request' (duration: 115.905931ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:56.728529Z","caller":"traceutil/trace.go:171","msg":"trace[424303023] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5239; }","duration":"125.970996ms","start":"2026-05-21T00:32:56.602521Z","end":"2026-05-21T00:32:56.728492Z","steps":["trace[424303023] 'read index received' (duration: 5.138026ms)","trace[424303023] 'applied index is now lower than readState.Index' (duration: 120.82919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T00:32:56.728705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.181441ms","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-21T00:32:56.728746Z","caller":"traceutil/trace.go:171","msg":"trace[1405026880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5052; }","duration":"126.265423ms","start":"2026-05-21T00:32:56.602475Z","end":"2026-05-21T00:32:56.728741Z","steps":["trace[1405026880] 'agreement among raft nodes before linearized reading' (duration: 126.14803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:32:56.728853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.193774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdr4q\" ","response":"range_response_count:1 size:6445"} {"level":"warn","ts":"2026-05-21T00:32:56.728881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.724529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T00:32:56.728881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.784188ms","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-21T00:32:56.7289Z","caller":"traceutil/trace.go:171","msg":"trace[2015354284] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdr4q; range_end:; response_count:1; response_revision:5052; }","duration":"106.283297ms","start":"2026-05-21T00:32:56.622606Z","end":"2026-05-21T00:32:56.72889Z","steps":["trace[2015354284] 'agreement among raft nodes before linearized reading' (duration: 106.069061ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:56.728924Z","caller":"traceutil/trace.go:171","msg":"trace[391291707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5052; }","duration":"118.808711ms","start":"2026-05-21T00:32:56.610102Z","end":"2026-05-21T00:32:56.728911Z","steps":["trace[391291707] 'agreement among raft nodes before linearized reading' (duration: 118.555355ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:56.728948Z","caller":"traceutil/trace.go:171","msg":"trace[1738628629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5052; }","duration":"108.88516ms","start":"2026-05-21T00:32:56.620046Z","end":"2026-05-21T00:32:56.728931Z","steps":["trace[1738628629] 'agreement among raft nodes before linearized reading' (duration: 108.713415ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:32:56.926279Z","caller":"traceutil/trace.go:171","msg":"trace[733632916] linearizableReadLoop","detail":"{readStateIndex:5249; appliedIndex:5248; }","duration":"106.089292ms","start":"2026-05-21T00:32:56.820167Z","end":"2026-05-21T00:32:56.926256Z","steps":["trace[733632916] 'read index received' (duration: 20.772726ms)","trace[733632916] 'applied index is now lower than readState.Index' (duration: 85.315506ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:32:56.926328Z","caller":"traceutil/trace.go:171","msg":"trace[1562666694] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"108.566842ms","start":"2026-05-21T00:32:56.817714Z","end":"2026-05-21T00:32:56.926281Z","steps":["trace[1562666694] 'process raft request' (duration: 23.197495ms)","trace[1562666694] 'compare' (duration: 85.202863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T00:32:56.926531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.352448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-tkj7w\" ","response":"range_response_count:1 size:3945"} {"level":"info","ts":"2026-05-21T00:32:56.926594Z","caller":"traceutil/trace.go:171","msg":"trace[2107295851] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-tkj7w; range_end:; response_count:1; response_revision:5059; }","duration":"106.468051ms","start":"2026-05-21T00:32:56.820112Z","end":"2026-05-21T00:32:56.92658Z","steps":["trace[2107295851] 'agreement among raft nodes before linearized reading' (duration: 106.257596ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:33:05.70385Z","caller":"traceutil/trace.go:171","msg":"trace[1383546761] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"147.653774ms","start":"2026-05-21T00:33:05.556166Z","end":"2026-05-21T00:33:05.70382Z","steps":["trace[1383546761] 'process raft request' (duration: 147.376587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:33:13.593517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.622485ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-21T00:33:13.593837Z","caller":"traceutil/trace.go:171","msg":"trace[535801383] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"106.999333ms","start":"2026-05-21T00:33:13.486816Z","end":"2026-05-21T00:33:13.593815Z","steps":["trace[535801383] 'process raft request' (duration: 106.832809ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:33:13.59423Z","caller":"traceutil/trace.go:171","msg":"trace[1059905278] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5599; }","duration":"186.534889ms","start":"2026-05-21T00:33:13.407681Z","end":"2026-05-21T00:33:13.594216Z","steps":["trace[1059905278] 'read index received' (duration: 35.132315ms)","trace[1059905278] 'applied index is now lower than readState.Index' (duration: 151.401284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T00:33:13.594425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.617886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de48dee7-425a-40ff-86ab-066371c6ff9c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-21T00:33:13.594465Z","caller":"traceutil/trace.go:171","msg":"trace[1070260951] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de48dee7-425a-40ff-86ab-066371c6ff9c; range_end:; response_count:1; response_revision:5401; }","duration":"155.705619ms","start":"2026-05-21T00:33:13.438749Z","end":"2026-05-21T00:33:13.594454Z","steps":["trace[1070260951] 'agreement among raft nodes before linearized reading' (duration: 155.576095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:33:13.594959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.265237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T00:33:13.595015Z","caller":"traceutil/trace.go:171","msg":"trace[549909913] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5401; }","duration":"187.3498ms","start":"2026-05-21T00:33:13.407649Z","end":"2026-05-21T00:33:13.594999Z","steps":["trace[549909913] 'agreement among raft nodes before linearized reading' (duration: 186.654862ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:34:30.344102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-05-21T00:34:30.422725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"77.500759ms","hash":3978481186,"current-db-size-bytes":58757120,"current-db-size":"59 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-21T00:34:30.422794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3978481186,"revision":3007,"compact-revision":-1} {"level":"info","ts":"2026-05-21T00:39:21.169989Z","caller":"traceutil/trace.go:171","msg":"trace[1552779872] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"100.68757ms","start":"2026-05-21T00:39:21.069279Z","end":"2026-05-21T00:39:21.169967Z","steps":["trace[1552779872] 'process raft request' (duration: 100.558127ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:30.350826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6123} {"level":"info","ts":"2026-05-21T00:39:30.441635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6123,"took":"89.472686ms","hash":3063854111,"current-db-size-bytes":58757120,"current-db-size":"59 MB","current-db-size-in-use-bytes":23285760,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-21T00:39:30.441685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3063854111,"revision":6123,"compact-revision":3007} {"level":"warn","ts":"2026-05-21T00:39:32.782591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.05977ms","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-21T00:39:32.7827Z","caller":"traceutil/trace.go:171","msg":"trace[476005428] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8301; }","duration":"113.202753ms","start":"2026-05-21T00:39:32.669475Z","end":"2026-05-21T00:39:32.782678Z","steps":["trace[476005428] 'range keys from in-memory index tree' (duration: 112.986988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:32.893012Z","caller":"traceutil/trace.go:171","msg":"trace[622467379] transaction","detail":"{read_only:false; response_revision:8302; number_of_response:1; }","duration":"105.67442ms","start":"2026-05-21T00:39:32.787314Z","end":"2026-05-21T00:39:32.892989Z","steps":["trace[622467379] 'process raft request' (duration: 105.501505ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:33.074017Z","caller":"traceutil/trace.go:171","msg":"trace[307009881] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"286.310594ms","start":"2026-05-21T00:39:32.787643Z","end":"2026-05-21T00:39:33.073953Z","steps":["trace[307009881] 'process raft request' (duration: 224.205553ms)","trace[307009881] 'compare' (duration: 61.954287ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:39:33.074151Z","caller":"traceutil/trace.go:171","msg":"trace[21232046] linearizableReadLoop","detail":"{readStateIndex:8595; appliedIndex:8593; }","duration":"285.33024ms","start":"2026-05-21T00:39:32.788789Z","end":"2026-05-21T00:39:33.074119Z","steps":["trace[21232046] 'read index received' (duration: 104.076181ms)","trace[21232046] 'applied index is now lower than readState.Index' (duration: 181.252189ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:39:33.07417Z","caller":"traceutil/trace.go:171","msg":"trace[1470622477] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"196.690103ms","start":"2026-05-21T00:39:32.877446Z","end":"2026-05-21T00:39:33.074136Z","steps":["trace[1470622477] 'process raft request' (duration: 196.472798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:39:33.074348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.236846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hdr4q\" ","response":"range_response_count:1 size:6445"} {"level":"warn","ts":"2026-05-21T00:39:33.07437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.571396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-21T00:39:33.074384Z","caller":"traceutil/trace.go:171","msg":"trace[1401204883] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hdr4q; range_end:; response_count:1; response_revision:8304; }","duration":"229.320298ms","start":"2026-05-21T00:39:32.845053Z","end":"2026-05-21T00:39:33.074374Z","steps":["trace[1401204883] 'agreement among raft nodes before linearized reading' (duration: 229.202955ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:33.07441Z","caller":"traceutil/trace.go:171","msg":"trace[1768625204] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8304; }","duration":"285.633958ms","start":"2026-05-21T00:39:32.788764Z","end":"2026-05-21T00:39:33.074398Z","steps":["trace[1768625204] 'agreement among raft nodes before linearized reading' (duration: 285.439893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:39:33.074513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.36906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T00:39:33.074604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.995773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T00:39:33.074631Z","caller":"traceutil/trace.go:171","msg":"trace[1264798284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8304; }","duration":"186.064925ms","start":"2026-05-21T00:39:32.888559Z","end":"2026-05-21T00:39:33.074624Z","steps":["trace[1264798284] 'agreement among raft nodes before linearized reading' (duration: 185.986703ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:33.0746Z","caller":"traceutil/trace.go:171","msg":"trace[1283808747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8304; }","duration":"162.501693ms","start":"2026-05-21T00:39:32.912074Z","end":"2026-05-21T00:39:33.074575Z","steps":["trace[1283808747] 'agreement among raft nodes before linearized reading' (duration: 162.347809ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:34.010285Z","caller":"traceutil/trace.go:171","msg":"trace[1515433318] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"231.754996ms","start":"2026-05-21T00:39:33.778509Z","end":"2026-05-21T00:39:34.010264Z","steps":["trace[1515433318] 'process raft request' (duration: 231.604233ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:35.185093Z","caller":"traceutil/trace.go:171","msg":"trace[2001596280] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"105.365663ms","start":"2026-05-21T00:39:35.079702Z","end":"2026-05-21T00:39:35.185067Z","steps":["trace[2001596280] 'process raft request' (duration: 105.228769ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:35.22833Z","caller":"traceutil/trace.go:171","msg":"trace[508465266] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"147.24663ms","start":"2026-05-21T00:39:35.081048Z","end":"2026-05-21T00:39:35.228295Z","steps":["trace[508465266] 'process raft request' (duration: 147.160678ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:39:35.228486Z","caller":"traceutil/trace.go:171","msg":"trace[1234323797] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"147.921928ms","start":"2026-05-21T00:39:35.080427Z","end":"2026-05-21T00:39:35.228349Z","steps":["trace[1234323797] 'process raft request' (duration: 146.315339ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:40:01.071102Z","caller":"traceutil/trace.go:171","msg":"trace[1220374357] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"226.980701ms","start":"2026-05-21T00:40:00.844101Z","end":"2026-05-21T00:40:01.071082Z","steps":["trace[1220374357] 'process raft request' (duration: 226.608513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:40:01.226626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.345535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T00:40:01.226721Z","caller":"traceutil/trace.go:171","msg":"trace[236950811] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8509; }","duration":"117.493087ms","start":"2026-05-21T00:40:01.109211Z","end":"2026-05-21T00:40:01.226704Z","steps":["trace[236950811] 'count revisions from in-memory index tree' (duration: 117.246072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:40:04.640106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.265296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T00:40:04.640387Z","caller":"traceutil/trace.go:171","msg":"trace[452835700] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"155.964733ms","start":"2026-05-21T00:40:04.484395Z","end":"2026-05-21T00:40:04.64036Z","steps":["trace[452835700] 'process raft request' (duration: 21.368409ms)","trace[452835700] 'compare' (duration: 134.148063ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:40:15.202765Z","caller":"traceutil/trace.go:171","msg":"trace[718251337] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"215.655696ms","start":"2026-05-21T00:40:14.987086Z","end":"2026-05-21T00:40:15.202741Z","steps":["trace[718251337] 'process raft request' (duration: 215.522953ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:40:15.267353Z","caller":"traceutil/trace.go:171","msg":"trace[1107377719] linearizableReadLoop","detail":"{readStateIndex:8933; appliedIndex:8931; }","duration":"133.703703ms","start":"2026-05-21T00:40:15.133624Z","end":"2026-05-21T00:40:15.267328Z","steps":["trace[1107377719] 'read index received' (duration: 69.059001ms)","trace[1107377719] 'applied index is now lower than readState.Index' (duration: 64.643702ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T00:40:15.267397Z","caller":"traceutil/trace.go:171","msg":"trace[1236040638] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"273.817031ms","start":"2026-05-21T00:40:14.993546Z","end":"2026-05-21T00:40:15.267364Z","steps":["trace[1236040638] 'process raft request' (duration: 273.589255ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:40:15.267495Z","caller":"traceutil/trace.go:171","msg":"trace[840499265] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"112.035765ms","start":"2026-05-21T00:40:15.155426Z","end":"2026-05-21T00:40:15.267462Z","steps":["trace[840499265] 'process raft request' (duration: 111.853541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:40:15.267729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.088642ms","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-21T00:40:15.267773Z","caller":"traceutil/trace.go:171","msg":"trace[829051954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8627; }","duration":"134.186064ms","start":"2026-05-21T00:40:15.133576Z","end":"2026-05-21T00:40:15.267762Z","steps":["trace[829051954] 'agreement among raft nodes before linearized reading' (duration: 133.876067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T00:40:15.267772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.099109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T00:40:15.26786Z","caller":"traceutil/trace.go:171","msg":"trace[1260862130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8627; }","duration":"124.247822ms","start":"2026-05-21T00:40:15.143594Z","end":"2026-05-21T00:40:15.267842Z","steps":["trace[1260862130] 'agreement among raft nodes before linearized reading' (duration: 123.909444ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:40:16.351913Z","caller":"traceutil/trace.go:171","msg":"trace[169068350] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"126.902317ms","start":"2026-05-21T00:40:16.224984Z","end":"2026-05-21T00:40:16.351886Z","steps":["trace[169068350] 'process raft request' (duration: 126.748473ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T00:40:59.080228Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002482.wal"} {"level":"info","ts":"2026-05-21T00:42:09.875912Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-21T00:42:09.883116Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-21T00:42:09.883217Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-21T00:44:30.356659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8291} {"level":"info","ts":"2026-05-21T00:44:30.42697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8291,"took":"68.279261ms","hash":444380786,"current-db-size-bytes":58757120,"current-db-size":"59 MB","current-db-size-in-use-bytes":24043520,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-21T00:44:30.427016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":444380786,"revision":8291,"compact-revision":6123}