{"level":"warn","ts":"2026-04-22T06:05:43.929365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:05:43.929435Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-04-22T06:05:43.929492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:05:43.929506Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-22T06:05:43.929524Z","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-04-22T06:05:43.930225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-22T06:05:43.930298Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-04-22T06:05:43.935641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.155137ms"} {"level":"info","ts":"2026-04-22T06:05:43.942043Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-22T06:05:43.942186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:05:43.942228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-22T06:05:43.942267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:05:43.942303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-22T06:05:43.942393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-22T06:05:43.949578Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:05:43.950713Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:05:43.951753Z","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-04-22T06:05:43.954658Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:05:43.95481Z","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-04-22T06:05:43.954911Z","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-04-22T06:05:43.954935Z","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-04-22T06:05:43.955008Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:05:43.956616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-22T06:05:43.959652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-22T06:05:43.960902Z","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-04-22T06:05:43.961028Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-22T06:05:43.961066Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-22T06:05:43.961211Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:05:43.961263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:05:44.243647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:05:44.243716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:05:44.243757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-22T06:05:44.243777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:05:44.243815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-22T06:05:44.243831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-22T06:05:44.243851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-22T06:05:44.244805Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:05:44.244826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:05:44.244825Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:05:44.244871Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:05:44.245181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:05:44.245223Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:05:44.245742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:05:44.245886Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:05:44.245934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:05:44.248784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-04-22T06:05:44.248807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:05:58.923572Z","caller":"traceutil/trace.go:171","msg":"trace[773534516] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"134.238277ms","start":"2026-04-22T06:05:58.789305Z","end":"2026-04-22T06:05:58.923543Z","steps":["trace[773534516] 'read index received' (duration: 134.089753ms)","trace[773534516] 'applied index is now lower than readState.Index' (duration: 147.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:58.923641Z","caller":"traceutil/trace.go:171","msg":"trace[1077422834] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"149.966365ms","start":"2026-04-22T06:05:58.773636Z","end":"2026-04-22T06:05:58.923602Z","steps":["trace[1077422834] 'process raft request' (duration: 149.717358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:58.923794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.423068ms","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-04-22T06:05:58.923802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.489004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4371"} {"level":"info","ts":"2026-04-22T06:05:58.923853Z","caller":"traceutil/trace.go:171","msg":"trace[777245172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"109.544601ms","start":"2026-04-22T06:05:58.814296Z","end":"2026-04-22T06:05:58.923841Z","steps":["trace[777245172] 'agreement among raft nodes before linearized reading' (duration: 109.407297ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:05:58.923908Z","caller":"traceutil/trace.go:171","msg":"trace[1966573039] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:364; }","duration":"134.651578ms","start":"2026-04-22T06:05:58.789233Z","end":"2026-04-22T06:05:58.923884Z","steps":["trace[1966573039] 'agreement among raft nodes before linearized reading' (duration: 134.460073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:59.209425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.720967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:05:59.209551Z","caller":"traceutil/trace.go:171","msg":"trace[1949944568] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"281.078349ms","start":"2026-04-22T06:05:58.928454Z","end":"2026-04-22T06:05:59.209533Z","steps":["trace[1949944568] 'process raft request' (duration: 141.643764ms)","trace[1949944568] 'compare' (duration: 138.539711ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:05:59.304219Z","caller":"traceutil/trace.go:171","msg":"trace[1474845722] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"366.029127ms","start":"2026-04-22T06:05:58.938137Z","end":"2026-04-22T06:05:59.304166Z","steps":["trace[1474845722] 'process raft request' (duration: 365.838562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:05:59.30445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:05:58.938111Z","time spent":"366.215622ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4363,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:05:59.457622Z","caller":"traceutil/trace.go:171","msg":"trace[2043955799] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"255.148081ms","start":"2026-04-22T06:05:59.202448Z","end":"2026-04-22T06:05:59.457597Z","steps":["trace[2043955799] 'process raft request' (duration: 254.993696ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.454804Z","caller":"traceutil/trace.go:171","msg":"trace[1528702682] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"411.246869ms","start":"2026-04-22T06:06:14.043532Z","end":"2026-04-22T06:06:14.454779Z","steps":["trace[1528702682] 'process raft request' (duration: 411.062184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.454941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.043516Z","time spent":"411.363672ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:14.472326Z","caller":"traceutil/trace.go:171","msg":"trace[1318511668] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"426.179676ms","start":"2026-04-22T06:06:14.046119Z","end":"2026-04-22T06:06:14.472298Z","steps":["trace[1318511668] 'process raft request' (duration: 426.025462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.472465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.046094Z","time spent":"426.302539ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:14.472594Z","caller":"traceutil/trace.go:171","msg":"trace[1576476561] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"424.589883ms","start":"2026-04-22T06:06:14.047994Z","end":"2026-04-22T06:06:14.472584Z","steps":["trace[1576476561] 'process raft request' (duration: 424.233074ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.472592Z","caller":"traceutil/trace.go:171","msg":"trace[1607071456] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"414.500046ms","start":"2026-04-22T06:06:14.05805Z","end":"2026-04-22T06:06:14.47255Z","steps":["trace[1607071456] 'process raft request' (duration: 414.237059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.472639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.047966Z","time spent":"424.655325ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:14.4727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.058027Z","time spent":"414.618118ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:14.472755Z","caller":"traceutil/trace.go:171","msg":"trace[443115535] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"411.088485ms","start":"2026-04-22T06:06:14.061658Z","end":"2026-04-22T06:06:14.472747Z","steps":["trace[443115535] 'process raft request' (duration: 410.730945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.472796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.061647Z","time spent":"411.132096ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:14.47284Z","caller":"traceutil/trace.go:171","msg":"trace[1620371954] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"405.917967ms","start":"2026-04-22T06:06:14.066912Z","end":"2026-04-22T06:06:14.47283Z","steps":["trace[1620371954] 'process raft request' (duration: 405.65159ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.47278Z","caller":"traceutil/trace.go:171","msg":"trace[1827927048] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"411.948948ms","start":"2026-04-22T06:06:14.060729Z","end":"2026-04-22T06:06:14.472677Z","steps":["trace[1827927048] 'process raft request' (duration: 411.628909ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.472841Z","caller":"traceutil/trace.go:171","msg":"trace[172607166] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"411.182937ms","start":"2026-04-22T06:06:14.06158Z","end":"2026-04-22T06:06:14.472763Z","steps":["trace[172607166] 'process raft request' (duration: 410.739765ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.472895Z","caller":"traceutil/trace.go:171","msg":"trace[214192533] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"418.79309ms","start":"2026-04-22T06:06:14.054061Z","end":"2026-04-22T06:06:14.472854Z","steps":["trace[214192533] 'process raft request' (duration: 418.195044ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.473003Z","caller":"traceutil/trace.go:171","msg":"trace[1714650104] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"370.123596ms","start":"2026-04-22T06:06:14.102868Z","end":"2026-04-22T06:06:14.472992Z","steps":["trace[1714650104] 'process raft request' (duration: 369.765437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.473002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.054026Z","time spent":"418.933803ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:14.473047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.102837Z","time spent":"370.189318ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:14.473051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.061554Z","time spent":"411.411814ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:14.47289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.066895Z","time spent":"405.972469ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:14.472861Z","caller":"traceutil/trace.go:171","msg":"trace[1417818278] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"407.244872ms","start":"2026-04-22T06:06:14.065558Z","end":"2026-04-22T06:06:14.472803Z","steps":["trace[1417818278] 'process raft request' (duration: 406.935594ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:14.47309Z","caller":"traceutil/trace.go:171","msg":"trace[1282336707] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"230.163116ms","start":"2026-04-22T06:06:14.242895Z","end":"2026-04-22T06:06:14.473058Z","steps":["trace[1282336707] 'process raft request' (duration: 229.958571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:14.473105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.060705Z","time spent":"412.256095ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:14.473249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:14.065518Z","time spent":"407.619082ms","remote":"127.0.0.1:42126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T06:06:16.392468Z","caller":"traceutil/trace.go:171","msg":"trace[1202653770] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"118.399671ms","start":"2026-04-22T06:06:16.274045Z","end":"2026-04-22T06:06:16.392444Z","steps":["trace[1202653770] 'process raft request' (duration: 118.256137ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:06:21.349476Z","caller":"traceutil/trace.go:171","msg":"trace[1412836544] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"371.679339ms","start":"2026-04-22T06:06:20.977766Z","end":"2026-04-22T06:06:21.349445Z","steps":["trace[1412836544] 'read index received' (duration: 371.513115ms)","trace[1412836544] 'applied index is now lower than readState.Index' (duration: 164.974µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:06:21.349584Z","caller":"traceutil/trace.go:171","msg":"trace[2096872237] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"424.197128ms","start":"2026-04-22T06:06:20.925348Z","end":"2026-04-22T06:06:21.349545Z","steps":["trace[2096872237] 'process raft request' (duration: 423.927361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:21.349762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:20.925307Z","time spent":"424.386593ms","remote":"127.0.0.1:42346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:06:21.349871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.091581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qkk89\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-22T06:06:21.349938Z","caller":"traceutil/trace.go:171","msg":"trace[1598742912] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qkk89; range_end:; response_count:1; response_revision:582; }","duration":"372.246985ms","start":"2026-04-22T06:06:20.977663Z","end":"2026-04-22T06:06:21.34991Z","steps":["trace[1598742912] 'agreement among raft nodes before linearized reading' (duration: 371.952087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:06:21.349985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:06:20.977635Z","time spent":"372.341547ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-qkk89\" "} {"level":"warn","ts":"2026-04-22T06:06:21.350109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.64901ms","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-04-22T06:06:21.350276Z","caller":"traceutil/trace.go:171","msg":"trace[658979031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"275.813604ms","start":"2026-04-22T06:06:21.074385Z","end":"2026-04-22T06:06:21.350199Z","steps":["trace[658979031] 'agreement among raft nodes before linearized reading' (duration: 275.26957ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:07.481098Z","caller":"traceutil/trace.go:171","msg":"trace[1880697295] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"198.522982ms","start":"2026-04-22T06:07:07.282532Z","end":"2026-04-22T06:07:07.481055Z","steps":["trace[1880697295] 'process raft request' (duration: 161.330628ms)","trace[1880697295] 'compare' (duration: 36.977908ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:55.659988Z","caller":"traceutil/trace.go:171","msg":"trace[1419644592] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"186.904308ms","start":"2026-04-22T06:07:55.473053Z","end":"2026-04-22T06:07:55.659957Z","steps":["trace[1419644592] 'read index received' (duration: 186.793715ms)","trace[1419644592] 'applied index is now lower than readState.Index' (duration: 109.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:55.660066Z","caller":"traceutil/trace.go:171","msg":"trace[603540032] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"350.960397ms","start":"2026-04-22T06:07:55.309061Z","end":"2026-04-22T06:07:55.660021Z","steps":["trace[603540032] 'process raft request' (duration: 350.72874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:55.66023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.158824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d6f3898-f87d-4790-ad3a-e938aed6f458\" ","response":"range_response_count:1 size:3625"} {"level":"warn","ts":"2026-04-22T06:07:55.660244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:55.309036Z","time spent":"351.114981ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:07:55.660274Z","caller":"traceutil/trace.go:171","msg":"trace[2088630591] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d6f3898-f87d-4790-ad3a-e938aed6f458; range_end:; response_count:1; response_revision:1815; }","duration":"187.261087ms","start":"2026-04-22T06:07:55.473001Z","end":"2026-04-22T06:07:55.660262Z","steps":["trace[2088630591] 'agreement among raft nodes before linearized reading' (duration: 187.092382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:55.670145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.333966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T06:07:55.670211Z","caller":"traceutil/trace.go:171","msg":"trace[1520934554] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"179.450869ms","start":"2026-04-22T06:07:55.490745Z","end":"2026-04-22T06:07:55.670196Z","steps":["trace[1520934554] 'agreement among raft nodes before linearized reading' (duration: 179.281344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:56.131872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.798749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:07:56.131985Z","caller":"traceutil/trace.go:171","msg":"trace[455328458] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"280.394668ms","start":"2026-04-22T06:07:55.851552Z","end":"2026-04-22T06:07:56.131946Z","steps":["trace[455328458] 'process raft request' (duration: 127.453785ms)","trace[455328458] 'compare' (duration: 152.591644ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:56.13204Z","caller":"traceutil/trace.go:171","msg":"trace[495090] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"130.089745ms","start":"2026-04-22T06:07:56.001937Z","end":"2026-04-22T06:07:56.132027Z","steps":["trace[495090] 'process raft request' (duration: 130.056944ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:56.132048Z","caller":"traceutil/trace.go:171","msg":"trace[1001905152] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"255.028632ms","start":"2026-04-22T06:07:55.876992Z","end":"2026-04-22T06:07:56.132021Z","steps":["trace[1001905152] 'read index received' (duration: 102.029807ms)","trace[1001905152] 'applied index is now lower than readState.Index' (duration: 152.995745ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:56.132106Z","caller":"traceutil/trace.go:171","msg":"trace[568877513] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"201.649381ms","start":"2026-04-22T06:07:55.930428Z","end":"2026-04-22T06:07:56.132077Z","steps":["trace[568877513] 'process raft request' (duration: 201.519847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:56.132214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.221777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d6f3898-f87d-4790-ad3a-e938aed6f458\" ","response":"range_response_count:1 size:3745"} {"level":"info","ts":"2026-04-22T06:07:56.13227Z","caller":"traceutil/trace.go:171","msg":"trace[1767677828] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d6f3898-f87d-4790-ad3a-e938aed6f458; range_end:; response_count:1; response_revision:1821; }","duration":"255.285429ms","start":"2026-04-22T06:07:55.876944Z","end":"2026-04-22T06:07:56.13223Z","steps":["trace[1767677828] 'agreement among raft nodes before linearized reading' (duration: 255.198356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.093702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265334967375334,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T06:08:16.437173Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.33898645s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T06:08:16.439585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343045839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T06:08:16.439677Z","caller":"traceutil/trace.go:171","msg":"trace[1174234348] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1970; }","duration":"1.343157621s","start":"2026-04-22T06:08:15.096496Z","end":"2026-04-22T06:08:16.439654Z","steps":["trace[1174234348] 'range keys from in-memory index tree' (duration: 1.342911975s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.439727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.096489Z","time spent":"1.343227373s","remote":"127.0.0.1:58336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-22T06:08:16.439656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.670062ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:16.439781Z","caller":"traceutil/trace.go:171","msg":"trace[560311308] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1970; }","duration":"346.812716ms","start":"2026-04-22T06:08:16.09295Z","end":"2026-04-22T06:08:16.439762Z","steps":["trace[560311308] 'range keys from in-memory index tree' (duration: 346.653442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.439948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342447863s","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-04-22T06:08:16.44001Z","caller":"traceutil/trace.go:171","msg":"trace[1291967870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1970; }","duration":"1.342536674s","start":"2026-04-22T06:08:15.097457Z","end":"2026-04-22T06:08:16.439994Z","steps":["trace[1291967870] 'range keys from in-memory index tree' (duration: 1.342279388s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.440049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.09745Z","time spent":"1.342587997s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:16.440156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342689659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:16.440279Z","caller":"traceutil/trace.go:171","msg":"trace[2059015211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1970; }","duration":"1.342821612s","start":"2026-04-22T06:08:15.097438Z","end":"2026-04-22T06:08:16.440259Z","steps":["trace[2059015211] 'range keys from in-memory index tree' (duration: 1.342585986s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.440325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.097374Z","time spent":"1.342941176s","remote":"127.0.0.1:42076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T06:08:16.441662Z","caller":"traceutil/trace.go:171","msg":"trace[1744690909] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"1.343547331s","start":"2026-04-22T06:08:15.098096Z","end":"2026-04-22T06:08:16.441643Z","steps":["trace[1744690909] 'process raft request' (duration: 1.339396031s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.441826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.098082Z","time spent":"1.343648095s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:16.442037Z","caller":"traceutil/trace.go:171","msg":"trace[1410919504] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"1.341421855s","start":"2026-04-22T06:08:15.100579Z","end":"2026-04-22T06:08:16.442Z","steps":["trace[1410919504] 'process raft request' (duration: 1.341220029s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.442218Z","caller":"traceutil/trace.go:171","msg":"trace[1698607769] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"348.852401ms","start":"2026-04-22T06:08:16.093348Z","end":"2026-04-22T06:08:16.442201Z","steps":["trace[1698607769] 'process raft request' (duration: 348.604715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.442224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.100551Z","time spent":"1.341579089s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:16.442291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.093328Z","time spent":"348.927343ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:16.443536Z","caller":"traceutil/trace.go:171","msg":"trace[1769910383] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2045; }","duration":"1.3333755s","start":"2026-04-22T06:08:15.110099Z","end":"2026-04-22T06:08:16.443475Z","steps":["trace[1769910383] 'read index received' (duration: 1.327324109s)","trace[1769910383] 'applied index is now lower than readState.Index' (duration: 6.047681ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:16.44356Z","caller":"traceutil/trace.go:171","msg":"trace[932858388] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"350.052243ms","start":"2026-04-22T06:08:16.093485Z","end":"2026-04-22T06:08:16.443537Z","steps":["trace[932858388] 'process raft request' (duration: 348.619665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.443782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.093481Z","time spent":"350.226817ms","remote":"127.0.0.1:42370","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-04-22T06:08:16.44396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333812093s","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":"warn","ts":"2026-04-22T06:08:16.444045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322370907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-22T06:08:16.4441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.056066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:16.444099Z","caller":"traceutil/trace.go:171","msg":"trace[1628079845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1974; }","duration":"1.334004637s","start":"2026-04-22T06:08:15.110062Z","end":"2026-04-22T06:08:16.444067Z","steps":["trace[1628079845] 'agreement among raft nodes before linearized reading' (duration: 1.333559125s)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.44411Z","caller":"traceutil/trace.go:171","msg":"trace[1376224787] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1974; }","duration":"1.32248943s","start":"2026-04-22T06:08:15.121599Z","end":"2026-04-22T06:08:16.444088Z","steps":["trace[1376224787] 'agreement among raft nodes before linearized reading' (duration: 1.32211025s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.444147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.69046ms","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-04-22T06:08:16.444161Z","caller":"traceutil/trace.go:171","msg":"trace[510052579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1974; }","duration":"144.202811ms","start":"2026-04-22T06:08:16.299944Z","end":"2026-04-22T06:08:16.444147Z","steps":["trace[510052579] 'agreement among raft nodes before linearized reading' (duration: 144.123449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.444185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.121577Z","time spent":"1.322593774s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-22T06:08:16.444197Z","caller":"traceutil/trace.go:171","msg":"trace[2044370196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1974; }","duration":"350.741531ms","start":"2026-04-22T06:08:16.093442Z","end":"2026-04-22T06:08:16.444183Z","steps":["trace[2044370196] 'agreement among raft nodes before linearized reading' (duration: 350.599717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.444193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.110042Z","time spent":"1.334131121s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:16.44431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.093439Z","time spent":"350.857534ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:16.444007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.543445ms","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-04-22T06:08:16.444416Z","caller":"traceutil/trace.go:171","msg":"trace[1775370492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1974; }","duration":"350.987168ms","start":"2026-04-22T06:08:16.09342Z","end":"2026-04-22T06:08:16.444407Z","steps":["trace[1775370492] 'agreement among raft nodes before linearized reading' (duration: 350.344891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.44445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.093415Z","time spent":"351.028739ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:08:16.44534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.853631ms","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-04-22T06:08:16.445438Z","caller":"traceutil/trace.go:171","msg":"trace[1065889871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1974; }","duration":"351.953023ms","start":"2026-04-22T06:08:16.093456Z","end":"2026-04-22T06:08:16.445409Z","steps":["trace[1065889871] 'agreement among raft nodes before linearized reading' (duration: 350.270989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.445433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284326394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-22T06:08:16.4455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.093453Z","time spent":"352.033346ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T06:08:16.445508Z","caller":"traceutil/trace.go:171","msg":"trace[1916307882] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1974; }","duration":"1.284455468s","start":"2026-04-22T06:08:15.161036Z","end":"2026-04-22T06:08:16.445491Z","steps":["trace[1916307882] 'agreement among raft nodes before linearized reading' (duration: 1.282782963s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.445548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.161016Z","time spent":"1.28452384s","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-22T06:10:07.219241Z","caller":"traceutil/trace.go:171","msg":"trace[266086470] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2875; }","duration":"192.754964ms","start":"2026-04-22T06:10:07.026429Z","end":"2026-04-22T06:10:07.219184Z","steps":["trace[266086470] 'read index received' (duration: 192.498427ms)","trace[266086470] 'applied index is now lower than readState.Index' (duration: 254.317µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:07.219599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.123954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T06:10:07.219739Z","caller":"traceutil/trace.go:171","msg":"trace[496948198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2771; }","duration":"193.32684ms","start":"2026-04-22T06:10:07.026371Z","end":"2026-04-22T06:10:07.219698Z","steps":["trace[496948198] 'agreement among raft nodes before linearized reading' (duration: 192.94627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:07.219957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.932029ms","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-04-22T06:10:07.220103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.295281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-22T06:10:07.220165Z","caller":"traceutil/trace.go:171","msg":"trace[93778639] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2771; }","duration":"182.412275ms","start":"2026-04-22T06:10:07.037736Z","end":"2026-04-22T06:10:07.220148Z","steps":["trace[93778639] 'agreement among raft nodes before linearized reading' (duration: 182.266101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:07.220122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.587436ms","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-04-22T06:10:07.220294Z","caller":"traceutil/trace.go:171","msg":"trace[40532446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2771; }","duration":"180.77684ms","start":"2026-04-22T06:10:07.03949Z","end":"2026-04-22T06:10:07.220267Z","steps":["trace[40532446] 'agreement among raft nodes before linearized reading' (duration: 180.436351ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:07.220342Z","caller":"traceutil/trace.go:171","msg":"trace[1903052423] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"194.608054ms","start":"2026-04-22T06:10:07.024666Z","end":"2026-04-22T06:10:07.219274Z","steps":["trace[1903052423] 'process raft request' (duration: 194.335827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:07.220172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.408711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T06:10:07.220585Z","caller":"traceutil/trace.go:171","msg":"trace[1034716957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2771; }","duration":"180.865293ms","start":"2026-04-22T06:10:07.039708Z","end":"2026-04-22T06:10:07.220574Z","steps":["trace[1034716957] 'agreement among raft nodes before linearized reading' (duration: 180.198115ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:07.220164Z","caller":"traceutil/trace.go:171","msg":"trace[1673304541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2771; }","duration":"193.120184ms","start":"2026-04-22T06:10:07.026936Z","end":"2026-04-22T06:10:07.220056Z","steps":["trace[1673304541] 'agreement among raft nodes before linearized reading' (duration: 192.742493ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:07.208674Z","caller":"traceutil/trace.go:171","msg":"trace[574701499] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4984; }","duration":"117.834935ms","start":"2026-04-22T06:14:07.0908Z","end":"2026-04-22T06:14:07.208635Z","steps":["trace[574701499] 'read index received' (duration: 76.507794ms)","trace[574701499] 'applied index is now lower than readState.Index' (duration: 41.325581ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:07.208777Z","caller":"traceutil/trace.go:171","msg":"trace[1932441769] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"118.39996ms","start":"2026-04-22T06:14:07.090314Z","end":"2026-04-22T06:14:07.208714Z","steps":["trace[1932441769] 'process raft request' (duration: 76.999317ms)","trace[1932441769] 'compare' (duration: 41.089245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:07.209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.161854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-95aa9571-4c4b-487f-89d8-149f700ce023\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-22T06:14:07.209055Z","caller":"traceutil/trace.go:171","msg":"trace[802790579] range","detail":"{range_begin:/registry/persistentvolumes/pvc-95aa9571-4c4b-487f-89d8-149f700ce023; range_end:; response_count:1; response_revision:4805; }","duration":"118.274666ms","start":"2026-04-22T06:14:07.090764Z","end":"2026-04-22T06:14:07.209039Z","steps":["trace[802790579] 'agreement among raft nodes before linearized reading' (duration: 118.028689ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:08.081258Z","caller":"traceutil/trace.go:171","msg":"trace[1629174801] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"112.050006ms","start":"2026-04-22T06:14:07.969177Z","end":"2026-04-22T06:14:08.081227Z","steps":["trace[1629174801] 'process raft request' (duration: 111.358067ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:13.004462Z","caller":"traceutil/trace.go:171","msg":"trace[1519717638] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"124.489376ms","start":"2026-04-22T06:14:12.87984Z","end":"2026-04-22T06:14:13.00433Z","steps":["trace[1519717638] 'process raft request' (duration: 124.311242ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:13.201223Z","caller":"traceutil/trace.go:171","msg":"trace[1523996088] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"152.440811ms","start":"2026-04-22T06:14:13.048744Z","end":"2026-04-22T06:14:13.201185Z","steps":["trace[1523996088] 'process raft request' (duration: 152.215835ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:15.481027Z","caller":"traceutil/trace.go:171","msg":"trace[1918741047] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"230.712573ms","start":"2026-04-22T06:14:15.250293Z","end":"2026-04-22T06:14:15.481006Z","steps":["trace[1918741047] 'process raft request' (duration: 229.900981ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:15.862265Z","caller":"traceutil/trace.go:171","msg":"trace[445720359] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"213.77141ms","start":"2026-04-22T06:14:15.648454Z","end":"2026-04-22T06:14:15.862225Z","steps":["trace[445720359] 'read index received' (duration: 213.549714ms)","trace[445720359] 'applied index is now lower than readState.Index' (duration: 220.526µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:15.862394Z","caller":"traceutil/trace.go:171","msg":"trace[1032985098] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"286.587563ms","start":"2026-04-22T06:14:15.575771Z","end":"2026-04-22T06:14:15.862359Z","steps":["trace[1032985098] 'process raft request' (duration: 286.257884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:15.86264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.149921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m96d8\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-04-22T06:14:15.862688Z","caller":"traceutil/trace.go:171","msg":"trace[814289634] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m96d8; range_end:; response_count:1; response_revision:5067; }","duration":"214.259594ms","start":"2026-04-22T06:14:15.648417Z","end":"2026-04-22T06:14:15.862677Z","steps":["trace[814289634] 'agreement among raft nodes before linearized reading' (duration: 213.973506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:15.863364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.882864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:14:15.863482Z","caller":"traceutil/trace.go:171","msg":"trace[55999674] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5069; }","duration":"130.040709ms","start":"2026-04-22T06:14:15.733416Z","end":"2026-04-22T06:14:15.863456Z","steps":["trace[55999674] 'agreement among raft nodes before linearized reading' (duration: 129.805133ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.139691Z","caller":"traceutil/trace.go:171","msg":"trace[1968766656] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"249.819056ms","start":"2026-04-22T06:14:23.889822Z","end":"2026-04-22T06:14:24.139641Z","steps":["trace[1968766656] 'process raft request' (duration: 249.525918ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.139792Z","caller":"traceutil/trace.go:171","msg":"trace[1390537890] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"245.744065ms","start":"2026-04-22T06:14:23.894035Z","end":"2026-04-22T06:14:24.139779Z","steps":["trace[1390537890] 'process raft request' (duration: 245.59111ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.139813Z","caller":"traceutil/trace.go:171","msg":"trace[1135245936] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"249.505457ms","start":"2026-04-22T06:14:23.890262Z","end":"2026-04-22T06:14:24.139767Z","steps":["trace[1135245936] 'process raft request' (duration: 249.319962ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.139895Z","caller":"traceutil/trace.go:171","msg":"trace[394633452] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"249.786295ms","start":"2026-04-22T06:14:23.890076Z","end":"2026-04-22T06:14:24.139863Z","steps":["trace[394633452] 'process raft request' (duration: 249.434315ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.139946Z","caller":"traceutil/trace.go:171","msg":"trace[531583271] linearizableReadLoop","detail":"{readStateIndex:5404; appliedIndex:5400; }","duration":"248.418138ms","start":"2026-04-22T06:14:23.891513Z","end":"2026-04-22T06:14:24.139931Z","steps":["trace[531583271] 'read index received' (duration: 43.682925ms)","trace[531583271] 'applied index is now lower than readState.Index' (duration: 204.733923ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:24.140013Z","caller":"traceutil/trace.go:171","msg":"trace[1041982334] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"243.815662ms","start":"2026-04-22T06:14:23.896185Z","end":"2026-04-22T06:14:24.140001Z","steps":["trace[1041982334] 'process raft request' (duration: 243.502663ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.140187Z","caller":"traceutil/trace.go:171","msg":"trace[742673062] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"242.393883ms","start":"2026-04-22T06:14:23.897757Z","end":"2026-04-22T06:14:24.140151Z","steps":["trace[742673062] 'process raft request' (duration: 241.964181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:24.140308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.770667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-04-22T06:14:24.140357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.28463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m96d8\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-04-22T06:14:24.140399Z","caller":"traceutil/trace.go:171","msg":"trace[1545282018] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m96d8; range_end:; response_count:1; response_revision:5211; }","duration":"242.365922ms","start":"2026-04-22T06:14:23.898023Z","end":"2026-04-22T06:14:24.140389Z","steps":["trace[1545282018] 'agreement among raft nodes before linearized reading' (duration: 242.245729ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:24.140366Z","caller":"traceutil/trace.go:171","msg":"trace[1542327503] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5211; }","duration":"248.87925ms","start":"2026-04-22T06:14:23.891471Z","end":"2026-04-22T06:14:24.14035Z","steps":["trace[1542327503] 'agreement among raft nodes before linearized reading' (duration: 248.701345ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:31.929225Z","caller":"traceutil/trace.go:171","msg":"trace[341158358] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"186.448762ms","start":"2026-04-22T06:14:31.742731Z","end":"2026-04-22T06:14:31.92918Z","steps":["trace[341158358] 'process raft request' (duration: 164.07809ms)","trace[341158358] 'compare' (duration: 22.077464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:15:09.542315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265334967384405,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T06:15:09.665568Z","caller":"traceutil/trace.go:171","msg":"trace[1134286358] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"625.164413ms","start":"2026-04-22T06:15:09.040374Z","end":"2026-04-22T06:15:09.665538Z","steps":["trace[1134286358] 'process raft request' (duration: 625.034249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.66573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.040343Z","time spent":"625.278686ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:15:09.922815Z","caller":"traceutil/trace.go:171","msg":"trace[1537279555] linearizableReadLoop","detail":"{readStateIndex:5918; appliedIndex:5915; }","duration":"881.190414ms","start":"2026-04-22T06:15:09.041595Z","end":"2026-04-22T06:15:09.922785Z","steps":["trace[1537279555] 'read index received' (duration: 623.922878ms)","trace[1537279555] 'applied index is now lower than readState.Index' (duration: 257.266206ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:15:09.923047Z","caller":"traceutil/trace.go:171","msg":"trace[273687674] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"881.41283ms","start":"2026-04-22T06:15:09.041624Z","end":"2026-04-22T06:15:09.923037Z","steps":["trace[273687674] 'process raft request' (duration: 879.257021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.923157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.041591Z","time spent":"881.484792ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:15:09.923253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.461012ms","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-04-22T06:15:09.923326Z","caller":"traceutil/trace.go:171","msg":"trace[1235811506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5709; }","duration":"881.573345ms","start":"2026-04-22T06:15:09.041737Z","end":"2026-04-22T06:15:09.92331Z","steps":["trace[1235811506] 'agreement among raft nodes before linearized reading' (duration: 881.338938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.923359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.04173Z","time spent":"881.624247ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:15:09.923423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.78941ms","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-04-22T06:15:09.923521Z","caller":"traceutil/trace.go:171","msg":"trace[1304440036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5709; }","duration":"881.925895ms","start":"2026-04-22T06:15:09.041569Z","end":"2026-04-22T06:15:09.923495Z","steps":["trace[1304440036] 'agreement among raft nodes before linearized reading' (duration: 881.633147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.923559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.041557Z","time spent":"881.995636ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:15:09.923593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.99507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:15:09.923616Z","caller":"traceutil/trace.go:171","msg":"trace[1033167508] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5709; }","duration":"712.091033ms","start":"2026-04-22T06:15:09.211517Z","end":"2026-04-22T06:15:09.923609Z","steps":["trace[1033167508] 'agreement among raft nodes before linearized reading' (duration: 712.00083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.923634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.211498Z","time spent":"712.132294ms","remote":"127.0.0.1:42616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":21,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:15:09.923526Z","caller":"traceutil/trace.go:171","msg":"trace[1365775532] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"881.78038ms","start":"2026-04-22T06:15:09.041701Z","end":"2026-04-22T06:15:09.923481Z","steps":["trace[1365775532] 'process raft request' (duration: 881.04243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:09.923874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:15:09.04162Z","time spent":"882.059879ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:15:44.355774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-22T06:15:44.423676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"66.155512ms","hash":1782495357,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":33366016,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-22T06:15:44.423751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1782495357,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-04-22T06:16:12.189891Z","caller":"traceutil/trace.go:171","msg":"trace[821627492] transaction","detail":"{read_only:false; response_revision:6227; number_of_response:1; }","duration":"159.328836ms","start":"2026-04-22T06:16:12.03053Z","end":"2026-04-22T06:16:12.189858Z","steps":["trace[821627492] 'process raft request' (duration: 158.564356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.952361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.59845ms","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-04-22T06:16:12.952382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.731284ms","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-04-22T06:16:12.95242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.660184ms","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-04-22T06:16:12.952471Z","caller":"traceutil/trace.go:171","msg":"trace[1843082180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6227; }","duration":"675.890508ms","start":"2026-04-22T06:16:12.27656Z","end":"2026-04-22T06:16:12.95245Z","steps":["trace[1843082180] 'range keys from in-memory index tree' (duration: 675.489427ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:16:12.952443Z","caller":"traceutil/trace.go:171","msg":"trace[800229279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6227; }","duration":"675.707644ms","start":"2026-04-22T06:16:12.276716Z","end":"2026-04-22T06:16:12.952424Z","steps":["trace[800229279] 'range keys from in-memory index tree' (duration: 675.432646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.953049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.27653Z","time spent":"676.004272ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:16:12.953568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.276708Z","time spent":"676.347311ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:16:12.95246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.208932ms","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-04-22T06:16:12.95398Z","caller":"traceutil/trace.go:171","msg":"trace[1911698118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6227; }","duration":"678.820478ms","start":"2026-04-22T06:16:12.273705Z","end":"2026-04-22T06:16:12.952526Z","steps":["trace[1911698118] 'range keys from in-memory index tree' (duration: 678.48673ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:16:12.95422Z","caller":"traceutil/trace.go:171","msg":"trace[470457845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6227; }","duration":"675.973081ms","start":"2026-04-22T06:16:12.277731Z","end":"2026-04-22T06:16:12.953705Z","steps":["trace[470457845] 'range keys from in-memory index tree' (duration: 673.958755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.954399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.277713Z","time spent":"676.605887ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:16:12.954952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.273659Z","time spent":"680.399222ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:16:12.965251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.233074ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:16:12.965645Z","caller":"traceutil/trace.go:171","msg":"trace[562572239] transaction","detail":"{read_only:false; response_revision:6228; number_of_response:1; }","duration":"392.314275ms","start":"2026-04-22T06:16:12.573311Z","end":"2026-04-22T06:16:12.965626Z","steps":["trace[562572239] 'process raft request' (duration: 392.098179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.965849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.57328Z","time spent":"392.433779ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:16:12.966079Z","caller":"traceutil/trace.go:171","msg":"trace[1751238681] transaction","detail":"{read_only:false; response_revision:6229; number_of_response:1; }","duration":"386.296371ms","start":"2026-04-22T06:16:12.579736Z","end":"2026-04-22T06:16:12.966033Z","steps":["trace[1751238681] 'process raft request' (duration: 385.844018ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:16:12.966163Z","caller":"traceutil/trace.go:171","msg":"trace[2118755510] transaction","detail":"{read_only:false; response_revision:6230; number_of_response:1; }","duration":"261.015067ms","start":"2026-04-22T06:16:12.705128Z","end":"2026-04-22T06:16:12.966143Z","steps":["trace[2118755510] 'process raft request' (duration: 260.745739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:16:12.966138Z","caller":"traceutil/trace.go:171","msg":"trace[536257701] linearizableReadLoop","detail":"{readStateIndex:6454; appliedIndex:6451; }","duration":"363.611761ms","start":"2026-04-22T06:16:12.602464Z","end":"2026-04-22T06:16:12.966076Z","steps":["trace[536257701] 'read index received' (duration: 154.559805ms)","trace[536257701] 'applied index is now lower than readState.Index' (duration: 209.050796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:16:12.966291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.818046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:16:12.966328Z","caller":"traceutil/trace.go:171","msg":"trace[1954587786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6230; }","duration":"363.894358ms","start":"2026-04-22T06:16:12.602423Z","end":"2026-04-22T06:16:12.966318Z","steps":["trace[1954587786] 'agreement among raft nodes before linearized reading' (duration: 363.825576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.966348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.602409Z","time spent":"363.932429ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:16:12.966552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:16:12.579722Z","time spent":"386.477155ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:16:12.969976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.008608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-sync-xlznf\" ","response":"range_response_count:1 size:10238"} {"level":"info","ts":"2026-04-22T06:16:12.970044Z","caller":"traceutil/trace.go:171","msg":"trace[44504996] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-sync-xlznf; range_end:; response_count:1; response_revision:6234; }","duration":"249.145942ms","start":"2026-04-22T06:16:12.720885Z","end":"2026-04-22T06:16:12.970031Z","steps":["trace[44504996] 'agreement among raft nodes before linearized reading' (duration: 248.925336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:16:12.970257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.925553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m96d8\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-04-22T06:16:12.970301Z","caller":"traceutil/trace.go:171","msg":"trace[402480405] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m96d8; range_end:; response_count:1; response_revision:6234; }","duration":"201.028896ms","start":"2026-04-22T06:16:12.769263Z","end":"2026-04-22T06:16:12.970292Z","steps":["trace[402480405] 'agreement among raft nodes before linearized reading' (duration: 200.917573ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:19:09.48818Z","caller":"traceutil/trace.go:171","msg":"trace[1439454691] transaction","detail":"{read_only:false; response_revision:7464; number_of_response:1; }","duration":"179.180329ms","start":"2026-04-22T06:19:09.308975Z","end":"2026-04-22T06:19:09.488155Z","steps":["trace[1439454691] 'process raft request' (duration: 176.922767ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:19:09.816487Z","caller":"traceutil/trace.go:171","msg":"trace[1937630358] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7465; }","duration":"207.326998ms","start":"2026-04-22T06:19:09.609133Z","end":"2026-04-22T06:19:09.81646Z","steps":["trace[1937630358] 'process raft request' (duration: 207.078331ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:19:09.817035Z","caller":"traceutil/trace.go:171","msg":"trace[675919684] linearizableReadLoop","detail":"{readStateIndex:7733; appliedIndex:7731; }","duration":"128.693678ms","start":"2026-04-22T06:19:09.688323Z","end":"2026-04-22T06:19:09.817017Z","steps":["trace[675919684] 'read index received' (duration: 127.964299ms)","trace[675919684] 'applied index is now lower than readState.Index' (duration: 728.479µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:19:09.817116Z","caller":"traceutil/trace.go:171","msg":"trace[1032019818] transaction","detail":"{read_only:false; response_revision:7466; number_of_response:1; }","duration":"172.124456ms","start":"2026-04-22T06:19:09.644947Z","end":"2026-04-22T06:19:09.817071Z","steps":["trace[1032019818] 'process raft request' (duration: 171.927831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:19:09.817179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.826582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:19:09.817223Z","caller":"traceutil/trace.go:171","msg":"trace[1924607396] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:7467; }","duration":"128.923225ms","start":"2026-04-22T06:19:09.688286Z","end":"2026-04-22T06:19:09.817209Z","steps":["trace[1924607396] 'agreement among raft nodes before linearized reading' (duration: 128.802881ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:19:09.817141Z","caller":"traceutil/trace.go:171","msg":"trace[1009260666] transaction","detail":"{read_only:false; response_revision:7467; number_of_response:1; }","duration":"101.291839ms","start":"2026-04-22T06:19:09.715826Z","end":"2026-04-22T06:19:09.817118Z","steps":["trace[1009260666] 'process raft request' (duration: 101.151035ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:19:10.005234Z","caller":"traceutil/trace.go:171","msg":"trace[156170659] linearizableReadLoop","detail":"{readStateIndex:7736; appliedIndex:7735; }","duration":"145.869957ms","start":"2026-04-22T06:19:09.859338Z","end":"2026-04-22T06:19:10.005208Z","steps":["trace[156170659] 'read index received' (duration: 145.629651ms)","trace[156170659] 'applied index is now lower than readState.Index' (duration: 238.656µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:19:10.005572Z","caller":"traceutil/trace.go:171","msg":"trace[1396878900] transaction","detail":"{read_only:false; response_revision:7469; number_of_response:1; }","duration":"147.744188ms","start":"2026-04-22T06:19:09.857797Z","end":"2026-04-22T06:19:10.005541Z","steps":["trace[1396878900] 'process raft request' (duration: 147.256945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:19:10.005902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.540536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T06:19:10.005942Z","caller":"traceutil/trace.go:171","msg":"trace[161592937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7469; }","duration":"146.607798ms","start":"2026-04-22T06:19:09.859321Z","end":"2026-04-22T06:19:10.005929Z","steps":["trace[161592937] 'agreement among raft nodes before linearized reading' (duration: 146.460314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:19:10.00616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.67918ms","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-04-22T06:19:10.006237Z","caller":"traceutil/trace.go:171","msg":"trace[102658106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7469; }","duration":"146.754142ms","start":"2026-04-22T06:19:09.859427Z","end":"2026-04-22T06:19:10.006181Z","steps":["trace[102658106] 'agreement among raft nodes before linearized reading' (duration: 146.66251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:19:10.006489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.494419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:19:10.00652Z","caller":"traceutil/trace.go:171","msg":"trace[1679228165] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:7469; }","duration":"107.580951ms","start":"2026-04-22T06:19:09.898929Z","end":"2026-04-22T06:19:10.00651Z","steps":["trace[1679228165] 'agreement among raft nodes before linearized reading' (duration: 107.520319ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:36.865003Z","caller":"traceutil/trace.go:171","msg":"trace[1115222051] transaction","detail":"{read_only:false; response_revision:8208; number_of_response:1; }","duration":"130.980408ms","start":"2026-04-22T06:20:36.73398Z","end":"2026-04-22T06:20:36.86496Z","steps":["trace[1115222051] 'process raft request' (duration: 130.633708ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:44.379763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6027} {"level":"info","ts":"2026-04-22T06:20:44.497132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6027,"took":"115.094905ms","hash":1205777766,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":23552000,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-22T06:20:44.497208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1205777766,"revision":6027,"compact-revision":2974} {"level":"warn","ts":"2026-04-22T06:20:48.704536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.741635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:20:48.704928Z","caller":"traceutil/trace.go:171","msg":"trace[1592237414] linearizableReadLoop","detail":"{readStateIndex:8561; appliedIndex:8560; }","duration":"160.171196ms","start":"2026-04-22T06:20:48.544732Z","end":"2026-04-22T06:20:48.704903Z","steps":["trace[1592237414] 'read index received' (duration: 42.961µs)","trace[1592237414] 'applied index is now lower than readState.Index' (duration: 160.124795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:48.705164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.437313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T06:20:48.705222Z","caller":"traceutil/trace.go:171","msg":"trace[1893279933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8269; }","duration":"160.537827ms","start":"2026-04-22T06:20:48.544666Z","end":"2026-04-22T06:20:48.705204Z","steps":["trace[1893279933] 'agreement among raft nodes before linearized reading' (duration: 160.328901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:48.705223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.060626ms","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-04-22T06:20:48.705274Z","caller":"traceutil/trace.go:171","msg":"trace[583784415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8269; }","duration":"159.184469ms","start":"2026-04-22T06:20:48.546075Z","end":"2026-04-22T06:20:48.705259Z","steps":["trace[583784415] 'agreement among raft nodes before linearized reading' (duration: 158.990734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:48.705286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.127888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T06:20:48.705373Z","caller":"traceutil/trace.go:171","msg":"trace[448592895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8269; }","duration":"155.246422ms","start":"2026-04-22T06:20:48.550116Z","end":"2026-04-22T06:20:48.705362Z","steps":["trace[448592895] 'agreement among raft nodes before linearized reading' (duration: 155.077937ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:48.704869Z","caller":"traceutil/trace.go:171","msg":"trace[1622426692] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"307.701717ms","start":"2026-04-22T06:20:48.397102Z","end":"2026-04-22T06:20:48.704804Z","steps":["trace[1622426692] 'process raft request' (duration: 13.585921ms)","trace[1622426692] 'compare' (duration: 293.607582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:48.705511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:48.397072Z","time spent":"308.398106ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:49.810026Z","caller":"traceutil/trace.go:171","msg":"trace[1672125457] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"108.504205ms","start":"2026-04-22T06:20:49.701498Z","end":"2026-04-22T06:20:49.810002Z","steps":["trace[1672125457] 'process raft request' (duration: 56.270577ms)","trace[1672125457] 'compare' (duration: 51.924159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:50.653458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.584284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:20:50.653648Z","caller":"traceutil/trace.go:171","msg":"trace[235949989] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"271.656112ms","start":"2026-04-22T06:20:50.381979Z","end":"2026-04-22T06:20:50.653635Z","steps":["trace[235949989] 'process raft request' (duration: 271.58877ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:50.653631Z","caller":"traceutil/trace.go:171","msg":"trace[1473770230] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"445.465911ms","start":"2026-04-22T06:20:50.208116Z","end":"2026-04-22T06:20:50.653582Z","steps":["trace[1473770230] 'process raft request' (duration: 253.66493ms)","trace[1473770230] 'compare' (duration: 191.468201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:50.653782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:50.208088Z","time spent":"445.630135ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:50.653855Z","caller":"traceutil/trace.go:171","msg":"trace[743031647] linearizableReadLoop","detail":"{readStateIndex:8570; appliedIndex:8569; }","duration":"329.77488ms","start":"2026-04-22T06:20:50.324031Z","end":"2026-04-22T06:20:50.653806Z","steps":["trace[743031647] 'read index received' (duration: 137.764604ms)","trace[743031647] 'applied index is now lower than readState.Index' (duration: 192.005676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:50.654081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.016327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:50.654366Z","caller":"traceutil/trace.go:171","msg":"trace[1240147941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8278; }","duration":"330.401497ms","start":"2026-04-22T06:20:50.323944Z","end":"2026-04-22T06:20:50.654345Z","steps":["trace[1240147941] 'agreement among raft nodes before linearized reading' (duration: 329.999956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:50.654417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:50.323914Z","time spent":"330.490469ms","remote":"127.0.0.1:35812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:50.968181Z","caller":"traceutil/trace.go:171","msg":"trace[408938066] transaction","detail":"{read_only:false; response_revision:8281; number_of_response:1; }","duration":"167.740523ms","start":"2026-04-22T06:20:50.800387Z","end":"2026-04-22T06:20:50.968128Z","steps":["trace[408938066] 'process raft request' (duration: 166.221301ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:50.968242Z","caller":"traceutil/trace.go:171","msg":"trace[1539602660] linearizableReadLoop","detail":"{readStateIndex:8575; appliedIndex:8573; }","duration":"165.7985ms","start":"2026-04-22T06:20:50.802403Z","end":"2026-04-22T06:20:50.968201Z","steps":["trace[1539602660] 'read index received' (duration: 164.185516ms)","trace[1539602660] 'applied index is now lower than readState.Index' (duration: 1.612054ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:50.968296Z","caller":"traceutil/trace.go:171","msg":"trace[1907243084] transaction","detail":"{read_only:false; response_revision:8282; number_of_response:1; }","duration":"167.65837ms","start":"2026-04-22T06:20:50.800607Z","end":"2026-04-22T06:20:50.968266Z","steps":["trace[1907243084] 'process raft request' (duration: 167.400453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:50.968475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.365566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:50.96864Z","caller":"traceutil/trace.go:171","msg":"trace[1456419000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8282; }","duration":"143.47206ms","start":"2026-04-22T06:20:50.825039Z","end":"2026-04-22T06:20:50.968511Z","steps":["trace[1456419000] 'agreement among raft nodes before linearized reading' (duration: 143.366707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:50.968719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.284473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223759"} {"level":"info","ts":"2026-04-22T06:20:50.968756Z","caller":"traceutil/trace.go:171","msg":"trace[1558948517] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8282; }","duration":"166.388096ms","start":"2026-04-22T06:20:50.802358Z","end":"2026-04-22T06:20:50.968746Z","steps":["trace[1558948517] 'agreement among raft nodes before linearized reading' (duration: 165.981944ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:17.65188Z","caller":"traceutil/trace.go:171","msg":"trace[363535533] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"354.31817ms","start":"2026-04-22T06:21:17.297532Z","end":"2026-04-22T06:21:17.65185Z","steps":["trace[363535533] 'read index received' (duration: 350.884827ms)","trace[363535533] 'applied index is now lower than readState.Index' (duration: 3.431693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:21:17.652119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.581527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-22T06:21:17.652157Z","caller":"traceutil/trace.go:171","msg":"trace[1392695312] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:8477; }","duration":"354.681041ms","start":"2026-04-22T06:21:17.297467Z","end":"2026-04-22T06:21:17.652148Z","steps":["trace[1392695312] 'agreement among raft nodes before linearized reading' (duration: 354.492405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:17.652191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:17.297436Z","time spent":"354.746212ms","remote":"127.0.0.1:42104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-04-22T06:21:17.652407Z","caller":"traceutil/trace.go:171","msg":"trace[1008338068] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"434.135651ms","start":"2026-04-22T06:21:17.218214Z","end":"2026-04-22T06:21:17.652349Z","steps":["trace[1008338068] 'process raft request' (duration: 430.286476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:17.652711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:17.218181Z","time spent":"434.434689ms","remote":"127.0.0.1:42370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:30.578915Z","caller":"traceutil/trace.go:171","msg":"trace[658803177] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"110.5237ms","start":"2026-04-22T06:21:30.468362Z","end":"2026-04-22T06:21:30.578886Z","steps":["trace[658803177] 'process raft request' (duration: 110.374086ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:30.595903Z","caller":"traceutil/trace.go:171","msg":"trace[1233100469] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"120.744449ms","start":"2026-04-22T06:21:30.475136Z","end":"2026-04-22T06:21:30.59588Z","steps":["trace[1233100469] 'process raft request' (duration: 120.197504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:31.126108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.802508ms","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-04-22T06:21:31.126211Z","caller":"traceutil/trace.go:171","msg":"trace[1751489849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8590; }","duration":"106.953392ms","start":"2026-04-22T06:21:31.019236Z","end":"2026-04-22T06:21:31.12619Z","steps":["trace[1751489849] 'range keys from in-memory index tree' (duration: 106.575662ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:31.250679Z","caller":"traceutil/trace.go:171","msg":"trace[1344922841] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"119.3438ms","start":"2026-04-22T06:21:31.131308Z","end":"2026-04-22T06:21:31.250651Z","steps":["trace[1344922841] 'process raft request' (duration: 118.928498ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:31.442437Z","caller":"traceutil/trace.go:171","msg":"trace[1088669080] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"184.416799ms","start":"2026-04-22T06:21:31.257987Z","end":"2026-04-22T06:21:31.442404Z","steps":["trace[1088669080] 'process raft request' (duration: 184.346777ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:31.442699Z","caller":"traceutil/trace.go:171","msg":"trace[1815006532] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"186.260059ms","start":"2026-04-22T06:21:31.256412Z","end":"2026-04-22T06:21:31.442672Z","steps":["trace[1815006532] 'process raft request' (duration: 170.613621ms)","trace[1815006532] 'compare' (duration: 15.110113ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:32.146281Z","caller":"traceutil/trace.go:171","msg":"trace[1398257750] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"100.106605ms","start":"2026-04-22T06:21:32.046147Z","end":"2026-04-22T06:21:32.146253Z","steps":["trace[1398257750] 'process raft request' (duration: 99.625782ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:47.659112Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002392.wal"} {"level":"info","ts":"2026-04-22T06:22:45.834242Z","caller":"traceutil/trace.go:171","msg":"trace[1719991882] linearizableReadLoop","detail":"{readStateIndex:9655; appliedIndex:9654; }","duration":"102.050529ms","start":"2026-04-22T06:22:45.732164Z","end":"2026-04-22T06:22:45.834214Z","steps":["trace[1719991882] 'read index received' (duration: 101.807742ms)","trace[1719991882] 'applied index is now lower than readState.Index' (duration: 241.997µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:22:45.83445Z","caller":"traceutil/trace.go:171","msg":"trace[1367089189] transaction","detail":"{read_only:false; response_revision:9329; number_of_response:1; }","duration":"138.273008ms","start":"2026-04-22T06:22:45.69615Z","end":"2026-04-22T06:22:45.834423Z","steps":["trace[1367089189] 'process raft request' (duration: 137.897098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:45.834602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.421028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:22:45.834645Z","caller":"traceutil/trace.go:171","msg":"trace[72248797] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:9329; }","duration":"102.531701ms","start":"2026-04-22T06:22:45.732101Z","end":"2026-04-22T06:22:45.834633Z","steps":["trace[72248797] 'agreement among raft nodes before linearized reading' (duration: 102.338536ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:23:32.059575Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"597401b06ce1cf3d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T06:23:32.064894Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T06:23:32.064972Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T06:25:44.386152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8245} {"level":"info","ts":"2026-04-22T06:25:44.463492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8245,"took":"75.086ms","hash":2827681196,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":25153536,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-22T06:25:44.463567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2827681196,"revision":8245,"compact-revision":6027} {"level":"info","ts":"2026-04-22T06:30:44.393687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10644} {"level":"info","ts":"2026-04-22T06:30:44.476563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10644,"took":"80.7208ms","hash":187122892,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":20520960,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-22T06:30:44.476625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":187122892,"revision":10644,"compact-revision":8245}