{"level":"warn","ts":"2026-03-25T21:03:33.542893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:03:33.542982Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-03-25T21:03:33.543045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:03:33.543059Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-03-25T21:03:33.543077Z","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-03-25T21:03:33.543745Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-03-25T21:03:33.54382Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-03-25T21:03:33.548125Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.104197ms"} {"level":"info","ts":"2026-03-25T21:03:33.563838Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-03-25T21:03:33.563949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:03:33.563986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:03:33.564001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:03:33.564011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:03:33.564066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-03-25T21:03:33.578736Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:03:33.583752Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:03:33.584919Z","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-03-25T21:03:33.588368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:03:33.588621Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:03:33.588551Z","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-03-25T21:03:33.588903Z","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-03-25T21:03:33.58894Z","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-03-25T21:03:33.590017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-03-25T21:03:33.594761Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-03-25T21:03:33.595042Z","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-03-25T21:03:33.595319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:03:33.595374Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:03:33.596049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-03-25T21:03:33.596082Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-03-25T21:03:33.965049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:03:33.965105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:03:33.965133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-03-25T21:03:33.965151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:03:33.965163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-03-25T21:03:33.965178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:03:33.965192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-03-25T21:03:33.966102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:03:33.966118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:03:33.966139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:03:33.966269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:03:33.966708Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:03:33.966786Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:03:33.966879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:03:33.966947Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:03:33.966969Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:03:33.968025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-03-25T21:03:33.96979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-25T21:04:08.84718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.060288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-03-25T21:04:08.847332Z","caller":"traceutil/trace.go:171","msg":"trace[541690597] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:488; }","duration":"148.273473ms","start":"2026-03-25T21:04:08.699037Z","end":"2026-03-25T21:04:08.847311Z","steps":["trace[541690597] 'range keys from in-memory index tree' (duration: 147.817483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:17.597218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.311814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8fmbd\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-25T21:04:17.597257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.416545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T21:04:17.597386Z","caller":"traceutil/trace.go:171","msg":"trace[643700987] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:623; }","duration":"302.589569ms","start":"2026-03-25T21:04:17.294767Z","end":"2026-03-25T21:04:17.597357Z","steps":["trace[643700987] 'range keys from in-memory index tree' (duration: 302.310723ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:17.597302Z","caller":"traceutil/trace.go:171","msg":"trace[2000649453] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8fmbd; range_end:; response_count:1; response_revision:623; }","duration":"344.457967ms","start":"2026-03-25T21:04:17.252828Z","end":"2026-03-25T21:04:17.597286Z","steps":["trace[2000649453] 'range keys from in-memory index tree' (duration: 344.065818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:17.597455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:17.252805Z","time spent":"344.639351ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8fmbd\" "} {"level":"warn","ts":"2026-03-25T21:04:17.597445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:17.294756Z","time spent":"302.674541ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-03-25T21:04:17.622619Z","caller":"traceutil/trace.go:171","msg":"trace[931868504] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"177.003397ms","start":"2026-03-25T21:04:17.445589Z","end":"2026-03-25T21:04:17.622592Z","steps":["trace[931868504] 'process raft request' (duration: 176.371932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:18.062704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:17.598556Z","time spent":"464.139668ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-25T21:04:18.062876Z","caller":"traceutil/trace.go:171","msg":"trace[2100043774] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"369.266197ms","start":"2026-03-25T21:04:17.693581Z","end":"2026-03-25T21:04:18.062847Z","steps":["trace[2100043774] 'process raft request' (duration: 369.219256ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:18.062963Z","caller":"traceutil/trace.go:171","msg":"trace[804398815] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"451.704542ms","start":"2026-03-25T21:04:17.611238Z","end":"2026-03-25T21:04:18.062943Z","steps":["trace[804398815] 'process raft request' (duration: 451.373904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:18.063438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:17.693559Z","time spent":"369.405871ms","remote":"127.0.0.1:36856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:04:18.06374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:17.611214Z","time spent":"451.841735ms","remote":"127.0.0.1:36838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:19.194826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.632391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:04:19.195177Z","caller":"traceutil/trace.go:171","msg":"trace[1744452493] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.06986091s","start":"2026-03-25T21:04:18.125296Z","end":"2026-03-25T21:04:19.195157Z","steps":["trace[1744452493] 'process raft request' (duration: 337.665005ms)","trace[1744452493] 'compare' (duration: 731.376785ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:19.1953Z","caller":"traceutil/trace.go:171","msg":"trace[117788562] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"894.83325ms","start":"2026-03-25T21:04:18.300402Z","end":"2026-03-25T21:04:19.195236Z","steps":["trace[117788562] 'read index received' (duration: 162.590513ms)","trace[117788562] 'applied index is now lower than readState.Index' (duration: 732.239757ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:19.195296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.125278Z","time spent":"1.069946553s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:19.195508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.101457ms","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-03-25T21:04:19.195548Z","caller":"traceutil/trace.go:171","msg":"trace[2099357888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"895.197359ms","start":"2026-03-25T21:04:18.30034Z","end":"2026-03-25T21:04:19.195538Z","steps":["trace[2099357888] 'agreement among raft nodes before linearized reading' (duration: 895.049726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:19.195581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.300317Z","time spent":"895.25664ms","remote":"127.0.0.1:36902","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-03-25T21:04:19.257644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.467196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:04:19.257728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.280855ms","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-03-25T21:04:19.257796Z","caller":"traceutil/trace.go:171","msg":"trace[380880292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"517.388817ms","start":"2026-03-25T21:04:18.740392Z","end":"2026-03-25T21:04:19.257781Z","steps":["trace[380880292] 'agreement among raft nodes before linearized reading' (duration: 517.179392ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:19.257785Z","caller":"traceutil/trace.go:171","msg":"trace[444584888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"469.598269ms","start":"2026-03-25T21:04:18.78814Z","end":"2026-03-25T21:04:19.257738Z","steps":["trace[444584888] 'agreement among raft nodes before linearized reading' (duration: 469.373264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:19.257875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.740374Z","time spent":"517.49327ms","remote":"127.0.0.1:36902","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-03-25T21:04:19.257953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.788095Z","time spent":"469.782433ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:04:19.258199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.267173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:04:19.258321Z","caller":"traceutil/trace.go:171","msg":"trace[208292341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"548.437297ms","start":"2026-03-25T21:04:18.709874Z","end":"2026-03-25T21:04:19.258311Z","steps":["trace[208292341] 'agreement among raft nodes before linearized reading' (duration: 548.299884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:19.258356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.709854Z","time spent":"548.495768ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:04:19.259357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.248827ms","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-03-25T21:04:19.259431Z","caller":"traceutil/trace.go:171","msg":"trace[900013559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"779.346029ms","start":"2026-03-25T21:04:18.480067Z","end":"2026-03-25T21:04:19.259413Z","steps":["trace[900013559] 'agreement among raft nodes before linearized reading' (duration: 777.393683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:19.259472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.480053Z","time spent":"779.409771ms","remote":"127.0.0.1:36902","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-03-25T21:04:19.260473Z","caller":"traceutil/trace.go:171","msg":"trace[1752482935] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"779.933153ms","start":"2026-03-25T21:04:18.480512Z","end":"2026-03-25T21:04:19.260446Z","steps":["trace[1752482935] 'process raft request' (duration: 776.47856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:19.260753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:18.480483Z","time spent":"780.055846ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:20.695032Z","caller":"traceutil/trace.go:171","msg":"trace[505370104] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"250.463257ms","start":"2026-03-25T21:04:20.444549Z","end":"2026-03-25T21:04:20.695012Z","steps":["trace[505370104] 'process raft request' (duration: 244.31312ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:24.577666Z","caller":"traceutil/trace.go:171","msg":"trace[1907280893] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"113.895424ms","start":"2026-03-25T21:04:24.463738Z","end":"2026-03-25T21:04:24.577634Z","steps":["trace[1907280893] 'process raft request' (duration: 113.232648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:35.52751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.500581ms","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-03-25T21:04:35.527589Z","caller":"traceutil/trace.go:171","msg":"trace[1397397868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"180.673605ms","start":"2026-03-25T21:04:35.3469Z","end":"2026-03-25T21:04:35.527573Z","steps":["trace[1397397868] 'range keys from in-memory index tree' (duration: 180.290176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:35.527652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.054576ms","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-03-25T21:04:35.527705Z","caller":"traceutil/trace.go:171","msg":"trace[515052001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"260.157879ms","start":"2026-03-25T21:04:35.267533Z","end":"2026-03-25T21:04:35.527691Z","steps":["trace[515052001] 'range keys from in-memory index tree' (duration: 259.915543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:35.527741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.372309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vgszt\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-03-25T21:04:35.527758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.655397ms","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-03-25T21:04:35.527824Z","caller":"traceutil/trace.go:171","msg":"trace[619392625] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vgszt; range_end:; response_count:1; response_revision:799; }","duration":"170.475202ms","start":"2026-03-25T21:04:35.35733Z","end":"2026-03-25T21:04:35.527805Z","steps":["trace[619392625] 'range keys from in-memory index tree' (duration: 170.165544ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:35.527835Z","caller":"traceutil/trace.go:171","msg":"trace[594550743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"191.77285ms","start":"2026-03-25T21:04:35.336043Z","end":"2026-03-25T21:04:35.527815Z","steps":["trace[594550743] 'range keys from in-memory index tree' (duration: 191.150105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:35.528295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.361531ms","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-03-25T21:04:35.528413Z","caller":"traceutil/trace.go:171","msg":"trace[1470188682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"181.528525ms","start":"2026-03-25T21:04:35.346856Z","end":"2026-03-25T21:04:35.528385Z","steps":["trace[1470188682] 'range keys from in-memory index tree' (duration: 180.429239ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:10.90426Z","caller":"traceutil/trace.go:171","msg":"trace[1373440255] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"268.936344ms","start":"2026-03-25T21:05:10.635299Z","end":"2026-03-25T21:05:10.904236Z","steps":["trace[1373440255] 'process raft request' (duration: 268.74913ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:11.349668Z","caller":"traceutil/trace.go:171","msg":"trace[1569805678] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1055; }","duration":"558.834863ms","start":"2026-03-25T21:05:10.790811Z","end":"2026-03-25T21:05:11.349646Z","steps":["trace[1569805678] 'read index received' (duration: 113.272275ms)","trace[1569805678] 'applied index is now lower than readState.Index' (duration: 445.561478ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:11.349756Z","caller":"traceutil/trace.go:171","msg":"trace[1838691740] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"677.446621ms","start":"2026-03-25T21:05:10.672259Z","end":"2026-03-25T21:05:11.349706Z","steps":["trace[1838691740] 'process raft request' (duration: 591.577448ms)","trace[1838691740] 'compare' (duration: 85.695729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:11.349815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.976516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:11.349853Z","caller":"traceutil/trace.go:171","msg":"trace[12423220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"559.064989ms","start":"2026-03-25T21:05:10.790778Z","end":"2026-03-25T21:05:11.349843Z","steps":["trace[12423220] 'agreement among raft nodes before linearized reading' (duration: 558.986997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:11.349878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:10.790755Z","time spent":"559.116509ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:05:11.349906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.232938ms","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-03-25T21:05:11.349921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:10.672236Z","time spent":"677.599593ms","remote":"127.0.0.1:36902","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-03-25T21:05:11.34994Z","caller":"traceutil/trace.go:171","msg":"trace[1039155598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"356.29605ms","start":"2026-03-25T21:05:10.993633Z","end":"2026-03-25T21:05:11.349929Z","steps":["trace[1039155598] 'agreement among raft nodes before linearized reading' (duration: 356.150116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:11.349972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:10.993616Z","time spent":"356.34886ms","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:05:32.187123Z","caller":"traceutil/trace.go:171","msg":"trace[41687605] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"272.848352ms","start":"2026-03-25T21:05:31.914236Z","end":"2026-03-25T21:05:32.187084Z","steps":["trace[41687605] 'process raft request' (duration: 272.629417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.845736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.672685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:05:32.845812Z","caller":"traceutil/trace.go:171","msg":"trace[803413874] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"920.820243ms","start":"2026-03-25T21:05:31.92498Z","end":"2026-03-25T21:05:32.8458Z","steps":["trace[803413874] 'read index received' (duration: 261.885213ms)","trace[803413874] 'applied index is now lower than readState.Index' (duration: 658.93404ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:32.845854Z","caller":"traceutil/trace.go:171","msg":"trace[1120806487] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"931.160298ms","start":"2026-03-25T21:05:31.914687Z","end":"2026-03-25T21:05:32.845848Z","steps":["trace[1120806487] 'process raft request' (duration: 586.311728ms)","trace[1120806487] 'compare' (duration: 344.347248ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:32.845889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.914675Z","time spent":"931.192019ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:32.846065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.088319ms","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-03-25T21:05:32.84608Z","caller":"traceutil/trace.go:171","msg":"trace[1989496308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"921.11108ms","start":"2026-03-25T21:05:31.924963Z","end":"2026-03-25T21:05:32.846074Z","steps":["trace[1989496308] 'agreement among raft nodes before linearized reading' (duration: 921.036048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.846095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.924956Z","time spent":"921.13573ms","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:05:32.949244Z","caller":"traceutil/trace.go:171","msg":"trace[134698627] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"103.37899ms","start":"2026-03-25T21:05:32.845825Z","end":"2026-03-25T21:05:32.949204Z","steps":["trace[134698627] 'read index received' (duration: 31.282681ms)","trace[134698627] 'applied index is now lower than readState.Index' (duration: 72.094589ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:32.949324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.794563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:32.949419Z","caller":"traceutil/trace.go:171","msg":"trace[1144778148] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1090; }","duration":"320.933976ms","start":"2026-03-25T21:05:32.628452Z","end":"2026-03-25T21:05:32.949386Z","steps":["trace[1144778148] 'agreement among raft nodes before linearized reading' (duration: 320.766342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.949474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:32.628434Z","time spent":"321.028729ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:05:32.949533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019589688s","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-03-25T21:05:32.949506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.690824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:32.94958Z","caller":"traceutil/trace.go:171","msg":"trace[866585861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.019694041s","start":"2026-03-25T21:05:31.929874Z","end":"2026-03-25T21:05:32.949568Z","steps":["trace[866585861] 'agreement among raft nodes before linearized reading' (duration: 1.019459385s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.949619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.929865Z","time spent":"1.019745742s","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:05:32.949624Z","caller":"traceutil/trace.go:171","msg":"trace[1778481257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"160.852418ms","start":"2026-03-25T21:05:32.788738Z","end":"2026-03-25T21:05:32.94959Z","steps":["trace[1778481257] 'agreement among raft nodes before linearized reading' (duration: 160.664072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.949789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.422745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:05:32.949851Z","caller":"traceutil/trace.go:171","msg":"trace[165104355] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1090; }","duration":"452.532718ms","start":"2026-03-25T21:05:32.497304Z","end":"2026-03-25T21:05:32.949837Z","steps":["trace[165104355] 'agreement among raft nodes before linearized reading' (duration: 452.234581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.949874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.652987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:05:32.949912Z","caller":"traceutil/trace.go:171","msg":"trace[1127729917] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1090; }","duration":"586.732988ms","start":"2026-03-25T21:05:32.363168Z","end":"2026-03-25T21:05:32.949901Z","steps":["trace[1127729917] 'agreement among raft nodes before linearized reading' (duration: 586.642636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.949905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:32.497274Z","time spent":"452.61977ms","remote":"127.0.0.1:36728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":115,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:05:32.949945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:32.363146Z","time spent":"586.79146ms","remote":"127.0.0.1:36856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:05:32.985634Z","caller":"traceutil/trace.go:171","msg":"trace[1091841355] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"134.784734ms","start":"2026-03-25T21:05:32.850838Z","end":"2026-03-25T21:05:32.985623Z","steps":["trace[1091841355] 'process raft request' (duration: 134.662851ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:34.67692Z","caller":"traceutil/trace.go:171","msg":"trace[1394674753] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"478.808085ms","start":"2026-03-25T21:05:34.198087Z","end":"2026-03-25T21:05:34.676895Z","steps":["trace[1394674753] 'process raft request' (duration: 478.648602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:34.677076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:34.198053Z","time spent":"478.937538ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:35.14838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.436307ms","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-03-25T21:05:35.148511Z","caller":"traceutil/trace.go:171","msg":"trace[1518747742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"120.644992ms","start":"2026-03-25T21:05:35.027835Z","end":"2026-03-25T21:05:35.14848Z","steps":["trace[1518747742] 'range keys from in-memory index tree' (duration: 120.179832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:35.148491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.218903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:35.14857Z","caller":"traceutil/trace.go:171","msg":"trace[730806064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"358.342556ms","start":"2026-03-25T21:05:34.790209Z","end":"2026-03-25T21:05:35.148552Z","steps":["trace[730806064] 'range keys from in-memory index tree' (duration: 358.11706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:35.148633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:34.790189Z","time spent":"358.435068ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:05:35.149021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.23676ms","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-03-25T21:05:35.149094Z","caller":"traceutil/trace.go:171","msg":"trace[901177948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"159.418274ms","start":"2026-03-25T21:05:34.989646Z","end":"2026-03-25T21:05:35.149065Z","steps":["trace[901177948] 'range keys from in-memory index tree' (duration: 158.987714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:35.149375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.03489ms","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-03-25T21:05:35.149451Z","caller":"traceutil/trace.go:171","msg":"trace[304087186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"300.148753ms","start":"2026-03-25T21:05:34.849285Z","end":"2026-03-25T21:05:35.149433Z","steps":["trace[304087186] 'range keys from in-memory index tree' (duration: 299.694953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:35.149499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:34.849229Z","time spent":"300.261106ms","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:05:35.53726Z","caller":"traceutil/trace.go:171","msg":"trace[1977526076] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"382.176178ms","start":"2026-03-25T21:05:35.155058Z","end":"2026-03-25T21:05:35.537234Z","steps":["trace[1977526076] 'process raft request' (duration: 382.123317ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:35.537346Z","caller":"traceutil/trace.go:171","msg":"trace[272318419] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"382.906664ms","start":"2026-03-25T21:05:35.154388Z","end":"2026-03-25T21:05:35.537295Z","steps":["trace[272318419] 'process raft request' (duration: 382.645099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:35.537429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:35.155049Z","time spent":"382.28888ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:35.537549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:35.154363Z","time spent":"383.066187ms","remote":"127.0.0.1:36902","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-03-25T21:05:35.747275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.342146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:05:35.747401Z","caller":"traceutil/trace.go:171","msg":"trace[1623799579] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1137; }","duration":"234.087971ms","start":"2026-03-25T21:05:35.513285Z","end":"2026-03-25T21:05:35.747373Z","steps":["trace[1623799579] 'read index received' (duration: 23.851702ms)","trace[1623799579] 'applied index is now lower than readState.Index' (duration: 210.234569ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:35.74758Z","caller":"traceutil/trace.go:171","msg":"trace[2142282283] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"293.81619ms","start":"2026-03-25T21:05:35.453727Z","end":"2026-03-25T21:05:35.747543Z","steps":["trace[2142282283] 'process raft request' (duration: 158.104125ms)","trace[2142282283] 'compare' (duration: 135.238854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:35.747817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.53515ms","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-03-25T21:05:35.74786Z","caller":"traceutil/trace.go:171","msg":"trace[968528106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"234.622892ms","start":"2026-03-25T21:05:35.513226Z","end":"2026-03-25T21:05:35.747849Z","steps":["trace[968528106] 'agreement among raft nodes before linearized reading' (duration: 234.212253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.323414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.322674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:37.323618Z","caller":"traceutil/trace.go:171","msg":"trace[832259582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"533.533459ms","start":"2026-03-25T21:05:36.790059Z","end":"2026-03-25T21:05:37.323592Z","steps":["trace[832259582] 'range keys from in-memory index tree' (duration: 533.19694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.323683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.614276ms","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-03-25T21:05:37.324117Z","caller":"traceutil/trace.go:171","msg":"trace[1977341216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"104.078736ms","start":"2026-03-25T21:05:37.219995Z","end":"2026-03-25T21:05:37.324074Z","steps":["trace[1977341216] 'range keys from in-memory index tree' (duration: 103.470822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.323773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:36.790006Z","time spent":"533.748994ms","remote":"127.0.0.1:36666","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-03-25T21:05:37.530468Z","caller":"traceutil/trace.go:171","msg":"trace[2048860410] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"204.610791ms","start":"2026-03-25T21:05:37.325834Z","end":"2026-03-25T21:05:37.530445Z","steps":["trace[2048860410] 'read index received' (duration: 204.517989ms)","trace[2048860410] 'applied index is now lower than readState.Index' (duration: 91.112µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:37.530678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.783205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:37.53077Z","caller":"traceutil/trace.go:171","msg":"trace[1427502788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"204.924639ms","start":"2026-03-25T21:05:37.325805Z","end":"2026-03-25T21:05:37.53073Z","steps":["trace[1427502788] 'agreement among raft nodes before linearized reading' (duration: 204.774115ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:37.915711Z","caller":"traceutil/trace.go:171","msg":"trace[1197067498] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"586.86654ms","start":"2026-03-25T21:05:37.328819Z","end":"2026-03-25T21:05:37.915685Z","steps":["trace[1197067498] 'process raft request' (duration: 586.689426ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:37.915739Z","caller":"traceutil/trace.go:171","msg":"trace[1248397007] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1144; }","duration":"385.172855ms","start":"2026-03-25T21:05:37.530541Z","end":"2026-03-25T21:05:37.915714Z","steps":["trace[1248397007] 'read index received' (duration: 385.163255ms)","trace[1248397007] 'applied index is now lower than readState.Index' (duration: 7.77µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:37.915891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:37.328792Z","time spent":"587.003424ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:37.916152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.396752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:05:37.916199Z","caller":"traceutil/trace.go:171","msg":"trace[1388984249] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1102; }","duration":"499.490065ms","start":"2026-03-25T21:05:37.416694Z","end":"2026-03-25T21:05:37.916184Z","steps":["trace[1388984249] 'agreement among raft nodes before linearized reading' (duration: 499.137087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.916314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:37.416676Z","time spent":"499.624238ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:05:37.991052Z","caller":"traceutil/trace.go:171","msg":"trace[1786251456] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"459.026475ms","start":"2026-03-25T21:05:37.531982Z","end":"2026-03-25T21:05:37.991008Z","steps":["trace[1786251456] 'process raft request' (duration: 389.810411ms)","trace[1786251456] 'compare' (duration: 68.957378ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:37.99149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:37.531953Z","time spent":"459.208989ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:05:37.99171Z","caller":"traceutil/trace.go:171","msg":"trace[337639156] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"237.264643ms","start":"2026-03-25T21:05:37.754415Z","end":"2026-03-25T21:05:37.99168Z","steps":["trace[337639156] 'process raft request' (duration: 236.751442ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:37.991921Z","caller":"traceutil/trace.go:171","msg":"trace[516993462] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"106.427138ms","start":"2026-03-25T21:05:37.88545Z","end":"2026-03-25T21:05:37.991877Z","steps":["trace[516993462] 'process raft request' (duration: 106.158882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.991962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.452063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:05:37.992056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.170684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-25T21:05:37.992055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.304477ms","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-03-25T21:05:37.992106Z","caller":"traceutil/trace.go:171","msg":"trace[883806818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"450.269166ms","start":"2026-03-25T21:05:37.541825Z","end":"2026-03-25T21:05:37.992094Z","steps":["trace[883806818] 'agreement among raft nodes before linearized reading' (duration: 450.118553ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:37.992115Z","caller":"traceutil/trace.go:171","msg":"trace[1434492938] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1105; }","duration":"195.658948ms","start":"2026-03-25T21:05:37.796435Z","end":"2026-03-25T21:05:37.992094Z","steps":["trace[1434492938] 'agreement among raft nodes before linearized reading' (duration: 195.446652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.992142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:37.541801Z","time spent":"450.331798ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T21:05:37.992146Z","caller":"traceutil/trace.go:171","msg":"trace[838280548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"450.44261ms","start":"2026-03-25T21:05:37.541683Z","end":"2026-03-25T21:05:37.992126Z","steps":["trace[838280548] 'agreement among raft nodes before linearized reading' (duration: 450.179723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:37.992196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:37.541671Z","time spent":"450.517051ms","remote":"127.0.0.1:36902","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-03-25T21:05:37.992733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.908328ms","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-03-25T21:05:37.992815Z","caller":"traceutil/trace.go:171","msg":"trace[499081326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"154.008351ms","start":"2026-03-25T21:05:37.838795Z","end":"2026-03-25T21:05:37.992804Z","steps":["trace[499081326] 'agreement among raft nodes before linearized reading' (duration: 153.200393ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:51.01781Z","caller":"traceutil/trace.go:171","msg":"trace[564714049] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"207.061752ms","start":"2026-03-25T21:05:50.810732Z","end":"2026-03-25T21:05:51.017794Z","steps":["trace[564714049] 'process raft request' (duration: 206.96316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:51.619175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.763297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:51.619238Z","caller":"traceutil/trace.go:171","msg":"trace[1639712487] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1199; }","duration":"399.88505ms","start":"2026-03-25T21:05:51.219341Z","end":"2026-03-25T21:05:51.619226Z","steps":["trace[1639712487] 'count revisions from in-memory index tree' (duration: 399.651855ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:51.619235Z","caller":"traceutil/trace.go:171","msg":"trace[58346471] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"335.358068ms","start":"2026-03-25T21:05:51.283853Z","end":"2026-03-25T21:05:51.619211Z","steps":["trace[58346471] 'process raft request' (duration: 330.095155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:51.61927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:51.219319Z","time spent":"399.942472ms","remote":"127.0.0.1:36878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:05:51.619342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:51.283828Z","time spent":"335.4581ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:02.166107Z","caller":"traceutil/trace.go:171","msg":"trace[495932150] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"145.806777ms","start":"2026-03-25T21:06:02.020275Z","end":"2026-03-25T21:06:02.166082Z","steps":["trace[495932150] 'process raft request' (duration: 145.635643ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:04.311788Z","caller":"traceutil/trace.go:171","msg":"trace[1562278419] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"138.288631ms","start":"2026-03-25T21:06:04.173476Z","end":"2026-03-25T21:06:04.311765Z","steps":["trace[1562278419] 'process raft request' (duration: 138.132857ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:04.312505Z","caller":"traceutil/trace.go:171","msg":"trace[2082855215] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"120.115665ms","start":"2026-03-25T21:06:04.192368Z","end":"2026-03-25T21:06:04.312484Z","steps":["trace[2082855215] 'read index received' (duration: 119.222524ms)","trace[2082855215] 'applied index is now lower than readState.Index' (duration: 892.221µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:04.312593Z","caller":"traceutil/trace.go:171","msg":"trace[603487738] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"139.006887ms","start":"2026-03-25T21:06:04.173569Z","end":"2026-03-25T21:06:04.312576Z","steps":["trace[603487738] 'process raft request' (duration: 138.800762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:04.312698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.322569ms","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-03-25T21:06:04.312731Z","caller":"traceutil/trace.go:171","msg":"trace[264597648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"120.405971ms","start":"2026-03-25T21:06:04.192315Z","end":"2026-03-25T21:06:04.312721Z","steps":["trace[264597648] 'agreement among raft nodes before linearized reading' (duration: 120.286408ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:07.594054Z","caller":"traceutil/trace.go:171","msg":"trace[2007090222] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"516.453372ms","start":"2026-03-25T21:06:07.077579Z","end":"2026-03-25T21:06:07.594032Z","steps":["trace[2007090222] 'process raft request' (duration: 516.317528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:07.594199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:07.077567Z","time spent":"516.556424ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:06:07.998571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.681822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:06:07.998784Z","caller":"traceutil/trace.go:171","msg":"trace[873098904] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"820.333832ms","start":"2026-03-25T21:06:07.178415Z","end":"2026-03-25T21:06:07.998749Z","steps":["trace[873098904] 'process raft request' (duration: 622.396634ms)","trace[873098904] 'compare' (duration: 197.548649ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:07.998951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:07.178388Z","time spent":"820.479326ms","remote":"127.0.0.1:36728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:06:07.998751Z","caller":"traceutil/trace.go:171","msg":"trace[1591897472] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1307; }","duration":"696.175043ms","start":"2026-03-25T21:06:07.302559Z","end":"2026-03-25T21:06:07.998734Z","steps":["trace[1591897472] 'read index received' (duration: 291.441719ms)","trace[1591897472] 'applied index is now lower than readState.Index' (duration: 404.731854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:07.999274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.711226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T21:06:07.999316Z","caller":"traceutil/trace.go:171","msg":"trace[1018971310] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1260; }","duration":"696.796628ms","start":"2026-03-25T21:06:07.302509Z","end":"2026-03-25T21:06:07.999305Z","steps":["trace[1018971310] 'agreement among raft nodes before linearized reading' (duration: 696.609293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:07.999383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.154298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:07.999433Z","caller":"traceutil/trace.go:171","msg":"trace[1818798868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1260; }","duration":"430.24755ms","start":"2026-03-25T21:06:07.569172Z","end":"2026-03-25T21:06:07.99942Z","steps":["trace[1818798868] 'agreement among raft nodes before linearized reading' (duration: 430.164898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:07.999469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:07.569148Z","time spent":"430.311352ms","remote":"127.0.0.1:48700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:06:07.999351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:07.302489Z","time spent":"696.854179ms","remote":"127.0.0.1:36690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-03-25T21:06:08.425679Z","caller":"traceutil/trace.go:171","msg":"trace[1371228773] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"101.369735ms","start":"2026-03-25T21:06:08.324288Z","end":"2026-03-25T21:06:08.425657Z","steps":["trace[1371228773] 'read index received' (duration: 101.268252ms)","trace[1371228773] 'applied index is now lower than readState.Index' (duration: 100.093µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:08.425933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.612691ms","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-03-25T21:06:08.42602Z","caller":"traceutil/trace.go:171","msg":"trace[1033444086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"101.754254ms","start":"2026-03-25T21:06:08.324245Z","end":"2026-03-25T21:06:08.425999Z","steps":["trace[1033444086] 'agreement among raft nodes before linearized reading' (duration: 101.489977ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:08.425739Z","caller":"traceutil/trace.go:171","msg":"trace[2016989181] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"101.503608ms","start":"2026-03-25T21:06:08.324204Z","end":"2026-03-25T21:06:08.425707Z","steps":["trace[2016989181] 'process raft request' (duration: 101.301233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:09.026199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.982162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:06:09.026334Z","caller":"traceutil/trace.go:171","msg":"trace[385919723] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"316.176838ms","start":"2026-03-25T21:06:08.710135Z","end":"2026-03-25T21:06:09.026312Z","steps":["trace[385919723] 'read index received' (duration: 160.896709ms)","trace[385919723] 'applied index is now lower than readState.Index' (duration: 155.278179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:09.026542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.399203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:09.026397Z","caller":"traceutil/trace.go:171","msg":"trace[7283672] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"396.864569ms","start":"2026-03-25T21:06:08.629495Z","end":"2026-03-25T21:06:09.02636Z","steps":["trace[7283672] 'process raft request' (duration: 241.639722ms)","trace[7283672] 'compare' (duration: 154.835698ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:09.026596Z","caller":"traceutil/trace.go:171","msg":"trace[249500373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"316.488485ms","start":"2026-03-25T21:06:08.710093Z","end":"2026-03-25T21:06:09.026581Z","steps":["trace[249500373] 'agreement among raft nodes before linearized reading' (duration: 316.333732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:09.026819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.387266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:06:09.026996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:08.629465Z","time spent":"397.341121ms","remote":"127.0.0.1:36728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:06:09.027004Z","caller":"traceutil/trace.go:171","msg":"trace[5932756] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1268; }","duration":"311.56402ms","start":"2026-03-25T21:06:08.715403Z","end":"2026-03-25T21:06:09.026967Z","steps":["trace[5932756] 'agreement among raft nodes before linearized reading' (duration: 311.168531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:09.027305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:08.715395Z","time spent":"311.889798ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-25T21:06:09.026905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:08.710075Z","time spent":"316.815463ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:06:09.026668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.868763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:09.027507Z","caller":"traceutil/trace.go:171","msg":"trace[409410143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"238.775724ms","start":"2026-03-25T21:06:08.788713Z","end":"2026-03-25T21:06:09.027488Z","steps":["trace[409410143] 'agreement among raft nodes before linearized reading' (duration: 237.875414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:09.692552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.970746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:06:09.69297Z","caller":"traceutil/trace.go:171","msg":"trace[57234424] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"659.016242ms","start":"2026-03-25T21:06:09.033931Z","end":"2026-03-25T21:06:09.692947Z","steps":["trace[57234424] 'process raft request' (duration: 417.574685ms)","trace[57234424] 'compare' (duration: 240.859423ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:09.693036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:09.033913Z","time spent":"659.092774ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:06:09.909668Z","caller":"traceutil/trace.go:171","msg":"trace[1775982811] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1319; }","duration":"202.433503ms","start":"2026-03-25T21:06:09.707217Z","end":"2026-03-25T21:06:09.90965Z","steps":["trace[1775982811] 'read index received' (duration: 161.177446ms)","trace[1775982811] 'applied index is now lower than readState.Index' (duration: 41.254887ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:09.909884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.650238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:06:09.909866Z","caller":"traceutil/trace.go:171","msg":"trace[717306309] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"205.29029ms","start":"2026-03-25T21:06:09.704555Z","end":"2026-03-25T21:06:09.909845Z","steps":["trace[717306309] 'process raft request' (duration: 205.060904ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:09.909917Z","caller":"traceutil/trace.go:171","msg":"trace[690561868] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1272; }","duration":"202.72467ms","start":"2026-03-25T21:06:09.707185Z","end":"2026-03-25T21:06:09.90991Z","steps":["trace[690561868] 'agreement among raft nodes before linearized reading' (duration: 202.655239ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:09.909941Z","caller":"traceutil/trace.go:171","msg":"trace[1129395634] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"212.354605ms","start":"2026-03-25T21:06:09.697567Z","end":"2026-03-25T21:06:09.909921Z","steps":["trace[1129395634] 'process raft request' (duration: 170.770611ms)","trace[1129395634] 'compare' (duration: 40.933799ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:09.909983Z","caller":"traceutil/trace.go:171","msg":"trace[225451558] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"211.984416ms","start":"2026-03-25T21:06:09.697974Z","end":"2026-03-25T21:06:09.909958Z","steps":["trace[225451558] 'process raft request' (duration: 211.583507ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:10.047773Z","caller":"traceutil/trace.go:171","msg":"trace[597617369] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"138.032164ms","start":"2026-03-25T21:06:09.909718Z","end":"2026-03-25T21:06:10.04775Z","steps":["trace[597617369] 'read index received' (duration: 129.248598ms)","trace[597617369] 'applied index is now lower than readState.Index' (duration: 8.782276ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:10.047807Z","caller":"traceutil/trace.go:171","msg":"trace[1253092705] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"157.882059ms","start":"2026-03-25T21:06:09.88989Z","end":"2026-03-25T21:06:10.047772Z","steps":["trace[1253092705] 'process raft request' (duration: 149.074783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:10.047923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.578288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:10.047965Z","caller":"traceutil/trace.go:171","msg":"trace[1092318712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"258.637249ms","start":"2026-03-25T21:06:09.789316Z","end":"2026-03-25T21:06:10.047953Z","steps":["trace[1092318712] 'agreement among raft nodes before linearized reading' (duration: 258.530277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.005435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.013559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:06:11.005562Z","caller":"traceutil/trace.go:171","msg":"trace[43504550] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"542.624904ms","start":"2026-03-25T21:06:10.462914Z","end":"2026-03-25T21:06:11.005539Z","steps":["trace[43504550] 'read index received' (duration: 6.313848ms)","trace[43504550] 'applied index is now lower than readState.Index' (duration: 536.308946ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:11.005613Z","caller":"traceutil/trace.go:171","msg":"trace[2085818286] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"552.149897ms","start":"2026-03-25T21:06:10.453434Z","end":"2026-03-25T21:06:11.005584Z","steps":["trace[2085818286] 'process raft request' (duration: 15.882882ms)","trace[2085818286] 'compare' (duration: 535.895517ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:11.005725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:10.453418Z","time spent":"552.24935ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:06:11.005806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.777347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:06:11.005804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.86628ms","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-03-25T21:06:11.005906Z","caller":"traceutil/trace.go:171","msg":"trace[1946260763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"542.853429ms","start":"2026-03-25T21:06:10.462989Z","end":"2026-03-25T21:06:11.005842Z","steps":["trace[1946260763] 'agreement among raft nodes before linearized reading' (duration: 542.670395ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:11.005901Z","caller":"traceutil/trace.go:171","msg":"trace[1146114894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"542.987182ms","start":"2026-03-25T21:06:10.462896Z","end":"2026-03-25T21:06:11.005883Z","steps":["trace[1146114894] 'agreement among raft nodes before linearized reading' (duration: 542.720976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.00595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:10.46297Z","time spent":"542.971782ms","remote":"127.0.0.1:36902","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-03-25T21:06:11.005956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:10.462887Z","time spent":"543.059734ms","remote":"127.0.0.1:36902","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-03-25T21:06:11.006059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.719128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:11.006142Z","caller":"traceutil/trace.go:171","msg":"trace[2102211722] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1276; }","duration":"222.859752ms","start":"2026-03-25T21:06:10.783265Z","end":"2026-03-25T21:06:11.006124Z","steps":["trace[2102211722] 'agreement among raft nodes before linearized reading' (duration: 222.424721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.00626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.200805ms","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-03-25T21:06:11.006306Z","caller":"traceutil/trace.go:171","msg":"trace[1693687433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1276; }","duration":"542.255367ms","start":"2026-03-25T21:06:10.464037Z","end":"2026-03-25T21:06:11.006293Z","steps":["trace[1693687433] 'agreement among raft nodes before linearized reading' (duration: 542.110343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.006346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:10.464031Z","time spent":"542.304438ms","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:06:11.577178Z","caller":"traceutil/trace.go:171","msg":"trace[1682068276] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"566.253828ms","start":"2026-03-25T21:06:11.010906Z","end":"2026-03-25T21:06:11.577159Z","steps":["trace[1682068276] 'process raft request' (duration: 511.342092ms)","trace[1682068276] 'compare' (duration: 54.817284ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:11.577286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:11.01089Z","time spent":"566.34405ms","remote":"127.0.0.1:36902","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-03-25T21:06:11.6558Z","caller":"traceutil/trace.go:171","msg":"trace[752275293] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1326; }","duration":"476.160617ms","start":"2026-03-25T21:06:11.179615Z","end":"2026-03-25T21:06:11.655776Z","steps":["trace[752275293] 'read index received' (duration: 342.735701ms)","trace[752275293] 'applied index is now lower than readState.Index' (duration: 133.423456ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:11.655915Z","caller":"traceutil/trace.go:171","msg":"trace[1069880] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"644.91015ms","start":"2026-03-25T21:06:11.01097Z","end":"2026-03-25T21:06:11.65588Z","steps":["trace[1069880] 'process raft request' (duration: 644.631364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.656064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.30827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:06:11.656093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:11.010948Z","time spent":"645.062985ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:11.656114Z","caller":"traceutil/trace.go:171","msg":"trace[1923048027] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1279; }","duration":"476.532497ms","start":"2026-03-25T21:06:11.179567Z","end":"2026-03-25T21:06:11.6561Z","steps":["trace[1923048027] 'agreement among raft nodes before linearized reading' (duration: 476.318051ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:11.655933Z","caller":"traceutil/trace.go:171","msg":"trace[1172047661] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"643.879346ms","start":"2026-03-25T21:06:11.012013Z","end":"2026-03-25T21:06:11.655892Z","steps":["trace[1172047661] 'process raft request' (duration: 643.722382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:11.656167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:11.179549Z","time spent":"476.608599ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:06:11.656433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:11.011992Z","time spent":"644.239756ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:06:11.891856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.807146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:11.891952Z","caller":"traceutil/trace.go:171","msg":"trace[355414047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"101.961479ms","start":"2026-03-25T21:06:11.789971Z","end":"2026-03-25T21:06:11.891932Z","steps":["trace[355414047] 'range keys from in-memory index tree' (duration: 101.701983ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:20.665514Z","caller":"traceutil/trace.go:171","msg":"trace[1681659554] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"187.964425ms","start":"2026-03-25T21:06:20.477533Z","end":"2026-03-25T21:06:20.665497Z","steps":["trace[1681659554] 'process raft request' (duration: 187.862643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:21.877866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959393372620727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:06:22.378755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959393372620727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:06:22.653324Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54580419s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:06:22.654003Z","caller":"traceutil/trace.go:171","msg":"trace[522250097] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"1.546571168s","start":"2026-03-25T21:06:21.107409Z","end":"2026-03-25T21:06:22.65398Z","steps":["trace[522250097] 'process raft request' (duration: 1.546351253s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.65414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.107386Z","time spent":"1.54667056s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:22.656313Z","caller":"traceutil/trace.go:171","msg":"trace[993789418] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"1.279283675s","start":"2026-03-25T21:06:21.377008Z","end":"2026-03-25T21:06:22.656291Z","steps":["trace[993789418] 'read index received' (duration: 1.276610472s)","trace[993789418] 'applied index is now lower than readState.Index' (duration: 2.672173ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:22.656442Z","caller":"traceutil/trace.go:171","msg":"trace[1066245693] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"481.129033ms","start":"2026-03-25T21:06:22.175299Z","end":"2026-03-25T21:06:22.656428Z","steps":["trace[1066245693] 'process raft request' (duration: 480.811865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.656568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:22.175277Z","time spent":"481.200224ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:06:22.656667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.216008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-03-25T21:06:22.656689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.698962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:06:22.656729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.521337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:06:22.656756Z","caller":"traceutil/trace.go:171","msg":"trace[1264320730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1360; }","duration":"946.815565ms","start":"2026-03-25T21:06:21.709922Z","end":"2026-03-25T21:06:22.656737Z","steps":["trace[1264320730] 'agreement among raft nodes before linearized reading' (duration: 946.61858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.656752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279698884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-25T21:06:22.656833Z","caller":"traceutil/trace.go:171","msg":"trace[2019821362] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"1.279866598s","start":"2026-03-25T21:06:21.376956Z","end":"2026-03-25T21:06:22.656823Z","steps":["trace[2019821362] 'agreement among raft nodes before linearized reading' (duration: 1.279603512s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.656856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.76812ms","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-03-25T21:06:22.656736Z","caller":"traceutil/trace.go:171","msg":"trace[1159397485] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1360; }","duration":"645.32113ms","start":"2026-03-25T21:06:22.011397Z","end":"2026-03-25T21:06:22.656719Z","steps":["trace[1159397485] 'agreement among raft nodes before linearized reading' (duration: 645.149537ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:22.656907Z","caller":"traceutil/trace.go:171","msg":"trace[2112822668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"916.861973ms","start":"2026-03-25T21:06:21.740033Z","end":"2026-03-25T21:06:22.656895Z","steps":["trace[2112822668] 'agreement among raft nodes before linearized reading' (duration: 916.73593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.656871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.376938Z","time spent":"1.279926179s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-25T21:06:22.656955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:22.011378Z","time spent":"645.561986ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-25T21:06:22.656962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.740016Z","time spent":"916.931135ms","remote":"127.0.0.1:36902","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-03-25T21:06:22.656788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.709902Z","time spent":"946.879067ms","remote":"127.0.0.1:36902","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-03-25T21:06:22.656668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.594693ms","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-03-25T21:06:22.656989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.749891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T21:06:22.657082Z","caller":"traceutil/trace.go:171","msg":"trace[757164183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"916.047115ms","start":"2026-03-25T21:06:21.741028Z","end":"2026-03-25T21:06:22.657075Z","steps":["trace[757164183] 'agreement among raft nodes before linearized reading' (duration: 915.510051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.657205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.741014Z","time spent":"916.147217ms","remote":"127.0.0.1:36902","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":"info","ts":"2026-03-25T21:06:22.657175Z","caller":"traceutil/trace.go:171","msg":"trace[933884216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"591.011478ms","start":"2026-03-25T21:06:22.066129Z","end":"2026-03-25T21:06:22.65714Z","steps":["trace[933884216] 'agreement among raft nodes before linearized reading' (duration: 590.579927ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:22.656786Z","caller":"traceutil/trace.go:171","msg":"trace[601800243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"867.612709ms","start":"2026-03-25T21:06:21.789159Z","end":"2026-03-25T21:06:22.656771Z","steps":["trace[601800243] 'agreement among raft nodes before linearized reading' (duration: 867.543537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:22.657295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:21.78913Z","time spent":"868.157583ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:06:22.65753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:22.066107Z","time spent":"591.194853ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T21:07:57.390924Z","caller":"traceutil/trace.go:171","msg":"trace[1314603653] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"202.906401ms","start":"2026-03-25T21:07:57.187987Z","end":"2026-03-25T21:07:57.390893Z","steps":["trace[1314603653] 'read index received' (duration: 202.738077ms)","trace[1314603653] 'applied index is now lower than readState.Index' (duration: 166.824µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:57.391045Z","caller":"traceutil/trace.go:171","msg":"trace[501884607] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"226.99818ms","start":"2026-03-25T21:07:57.163996Z","end":"2026-03-25T21:07:57.390995Z","steps":["trace[501884607] 'process raft request' (duration: 226.717443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:57.391219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.161218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T21:07:57.391266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.23169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-25T21:07:57.391381Z","caller":"traceutil/trace.go:171","msg":"trace[1883240543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1793; }","duration":"203.430714ms","start":"2026-03-25T21:07:57.18793Z","end":"2026-03-25T21:07:57.39136Z","steps":["trace[1883240543] 'agreement among raft nodes before linearized reading' (duration: 203.084065ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:57.391291Z","caller":"traceutil/trace.go:171","msg":"trace[650738870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1793; }","duration":"156.280441ms","start":"2026-03-25T21:07:57.234989Z","end":"2026-03-25T21:07:57.391269Z","steps":["trace[650738870] 'agreement among raft nodes before linearized reading' (duration: 156.087146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:13.776837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.627194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:08:13.776943Z","caller":"traceutil/trace.go:171","msg":"trace[1246361809] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"156.630779ms","start":"2026-03-25T21:08:13.620296Z","end":"2026-03-25T21:08:13.776926Z","steps":["trace[1246361809] 'read index received' (duration: 39.781239ms)","trace[1246361809] 'applied index is now lower than readState.Index' (duration: 116.84854ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:13.777008Z","caller":"traceutil/trace.go:171","msg":"trace[1211262743] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"184.680031ms","start":"2026-03-25T21:08:13.592283Z","end":"2026-03-25T21:08:13.776963Z","steps":["trace[1211262743] 'process raft request' (duration: 67.837732ms)","trace[1211262743] 'compare' (duration: 116.493491ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:13.777119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.69377ms","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-03-25T21:08:13.777152Z","caller":"traceutil/trace.go:171","msg":"trace[804713584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"156.764532ms","start":"2026-03-25T21:08:13.620376Z","end":"2026-03-25T21:08:13.777141Z","steps":["trace[804713584] 'agreement among raft nodes before linearized reading' (duration: 156.643799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:13.777339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.989267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-25T21:08:13.777499Z","caller":"traceutil/trace.go:171","msg":"trace[1372972811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1872; }","duration":"157.189392ms","start":"2026-03-25T21:08:13.620251Z","end":"2026-03-25T21:08:13.77744Z","steps":["trace[1372972811] 'agreement among raft nodes before linearized reading' (duration: 156.756541ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:23.982168Z","caller":"traceutil/trace.go:171","msg":"trace[1310227223] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"108.927932ms","start":"2026-03-25T21:08:23.873205Z","end":"2026-03-25T21:08:23.982133Z","steps":["trace[1310227223] 'process raft request' (duration: 34.855753ms)","trace[1310227223] 'compare' (duration: 73.912695ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:49.991522Z","caller":"traceutil/trace.go:171","msg":"trace[2045300366] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"115.268622ms","start":"2026-03-25T21:08:49.876209Z","end":"2026-03-25T21:08:49.991477Z","steps":["trace[2045300366] 'process raft request' (duration: 84.051555ms)","trace[2045300366] 'compare' (duration: 30.967591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:52.603501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.614445ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T21:08:56.263594Z","caller":"traceutil/trace.go:171","msg":"trace[777312922] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"146.085774ms","start":"2026-03-25T21:08:56.117483Z","end":"2026-03-25T21:08:56.263569Z","steps":["trace[777312922] 'read index received' (duration: 144.522157ms)","trace[777312922] 'applied index is now lower than readState.Index' (duration: 1.562457ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:56.263637Z","caller":"traceutil/trace.go:171","msg":"trace[1158866305] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"215.28166ms","start":"2026-03-25T21:08:56.048324Z","end":"2026-03-25T21:08:56.263606Z","steps":["trace[1158866305] 'process raft request' (duration: 213.764155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:56.263807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.311269ms","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-03-25T21:08:56.263894Z","caller":"traceutil/trace.go:171","msg":"trace[1313681628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2064; }","duration":"146.434173ms","start":"2026-03-25T21:08:56.117439Z","end":"2026-03-25T21:08:56.263874Z","steps":["trace[1313681628] 'agreement among raft nodes before linearized reading' (duration: 146.207987ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:12.182346Z","caller":"traceutil/trace.go:171","msg":"trace[2117034326] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"111.443015ms","start":"2026-03-25T21:09:12.070877Z","end":"2026-03-25T21:09:12.18232Z","steps":["trace[2117034326] 'process raft request' (duration: 111.29223ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:12.569692Z","caller":"traceutil/trace.go:171","msg":"trace[971882923] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"131.218943ms","start":"2026-03-25T21:09:12.438433Z","end":"2026-03-25T21:09:12.569652Z","steps":["trace[971882923] 'process raft request' (duration: 131.032259ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:12.569613Z","caller":"traceutil/trace.go:171","msg":"trace[85247571] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"120.365726ms","start":"2026-03-25T21:09:12.449226Z","end":"2026-03-25T21:09:12.569592Z","steps":["trace[85247571] 'read index received' (duration: 120.301234ms)","trace[85247571] 'applied index is now lower than readState.Index' (duration: 63.342µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:12.569886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.645623ms","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-03-25T21:09:12.569941Z","caller":"traceutil/trace.go:171","msg":"trace[1955655684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"120.730615ms","start":"2026-03-25T21:09:12.449194Z","end":"2026-03-25T21:09:12.569925Z","steps":["trace[1955655684] 'agreement among raft nodes before linearized reading' (duration: 120.558061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:12.620852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.979554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T21:09:12.620914Z","caller":"traceutil/trace.go:171","msg":"trace[2069520420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2138; }","duration":"144.096177ms","start":"2026-03-25T21:09:12.476804Z","end":"2026-03-25T21:09:12.6209Z","steps":["trace[2069520420] 'agreement among raft nodes before linearized reading' (duration: 143.925573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:12.620918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.877895ms","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-03-25T21:09:12.620991Z","caller":"traceutil/trace.go:171","msg":"trace[1536984029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"141.993997ms","start":"2026-03-25T21:09:12.478983Z","end":"2026-03-25T21:09:12.620977Z","steps":["trace[1536984029] 'agreement among raft nodes before linearized reading' (duration: 141.775372ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:30.512368Z","caller":"traceutil/trace.go:171","msg":"trace[210218924] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"236.613565ms","start":"2026-03-25T21:09:30.275733Z","end":"2026-03-25T21:09:30.512347Z","steps":["trace[210218924] 'read index received' (duration: 236.489432ms)","trace[210218924] 'applied index is now lower than readState.Index' (duration: 123.153µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:30.512496Z","caller":"traceutil/trace.go:171","msg":"trace[1788330200] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"487.261022ms","start":"2026-03-25T21:09:30.025224Z","end":"2026-03-25T21:09:30.512485Z","steps":["trace[1788330200] 'process raft request' (duration: 486.988085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:30.512633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:30.0252Z","time spent":"487.323933ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:30.512846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.094617ms","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-03-25T21:09:30.512927Z","caller":"traceutil/trace.go:171","msg":"trace[1740266506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2241; }","duration":"237.191389ms","start":"2026-03-25T21:09:30.275718Z","end":"2026-03-25T21:09:30.512909Z","steps":["trace[1740266506] 'agreement among raft nodes before linearized reading' (duration: 236.937304ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:30.830035Z","caller":"traceutil/trace.go:171","msg":"trace[1885660182] linearizableReadLoop","detail":"{readStateIndex:2337; appliedIndex:2336; }","duration":"118.767698ms","start":"2026-03-25T21:09:30.71124Z","end":"2026-03-25T21:09:30.830008Z","steps":["trace[1885660182] 'read index received' (duration: 47.675317ms)","trace[1885660182] 'applied index is now lower than readState.Index' (duration: 71.091441ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:30.830136Z","caller":"traceutil/trace.go:171","msg":"trace[1814090616] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"156.257915ms","start":"2026-03-25T21:09:30.673838Z","end":"2026-03-25T21:09:30.830095Z","steps":["trace[1814090616] 'process raft request' (duration: 85.063222ms)","trace[1814090616] 'compare' (duration: 70.989209ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:30.83025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.999463ms","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-03-25T21:09:30.830327Z","caller":"traceutil/trace.go:171","msg":"trace[1968387462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2246; }","duration":"119.087425ms","start":"2026-03-25T21:09:30.711222Z","end":"2026-03-25T21:09:30.830309Z","steps":["trace[1968387462] 'agreement among raft nodes before linearized reading' (duration: 118.87981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:30.830527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.729993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T21:09:30.830582Z","caller":"traceutil/trace.go:171","msg":"trace[1590478136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2246; }","duration":"114.827555ms","start":"2026-03-25T21:09:30.715742Z","end":"2026-03-25T21:09:30.83057Z","steps":["trace[1590478136] 'agreement among raft nodes before linearized reading' (duration: 114.518538ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:31.079104Z","caller":"traceutil/trace.go:171","msg":"trace[639293084] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"243.190461ms","start":"2026-03-25T21:09:30.83589Z","end":"2026-03-25T21:09:31.07908Z","steps":["trace[639293084] 'process raft request' (duration: 243.067028ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:31.097119Z","caller":"traceutil/trace.go:171","msg":"trace[1402960354] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"260.098619ms","start":"2026-03-25T21:09:30.837Z","end":"2026-03-25T21:09:31.097098Z","steps":["trace[1402960354] 'process raft request' (duration: 259.957626ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:31.09733Z","caller":"traceutil/trace.go:171","msg":"trace[612603981] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2337; }","duration":"107.969743ms","start":"2026-03-25T21:09:30.989326Z","end":"2026-03-25T21:09:31.097296Z","steps":["trace[612603981] 'read index received' (duration: 89.611859ms)","trace[612603981] 'applied index is now lower than readState.Index' (duration: 18.341333ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:31.097362Z","caller":"traceutil/trace.go:171","msg":"trace[25269289] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"260.229473ms","start":"2026-03-25T21:09:30.837114Z","end":"2026-03-25T21:09:31.097343Z","steps":["trace[25269289] 'process raft request' (duration: 259.955586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:31.097489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.131096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:09:31.097544Z","caller":"traceutil/trace.go:171","msg":"trace[1586359898] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2249; }","duration":"108.248719ms","start":"2026-03-25T21:09:30.989277Z","end":"2026-03-25T21:09:31.097526Z","steps":["trace[1586359898] 'agreement among raft nodes before linearized reading' (duration: 108.130736ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:38.365005Z","caller":"traceutil/trace.go:171","msg":"trace[422828254] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"206.501234ms","start":"2026-03-25T21:09:38.158479Z","end":"2026-03-25T21:09:38.36498Z","steps":["trace[422828254] 'process raft request' (duration: 206.177786ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:38.966416Z","caller":"traceutil/trace.go:171","msg":"trace[1044414091] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"473.652411ms","start":"2026-03-25T21:09:38.492732Z","end":"2026-03-25T21:09:38.966385Z","steps":["trace[1044414091] 'process raft request' (duration: 472.485413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:38.966656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.49271Z","time spent":"473.796645ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:39.000829Z","caller":"traceutil/trace.go:171","msg":"trace[1856935965] linearizableReadLoop","detail":"{readStateIndex:2379; appliedIndex:2377; }","duration":"414.231485ms","start":"2026-03-25T21:09:38.586582Z","end":"2026-03-25T21:09:39.000813Z","steps":["trace[1856935965] 'read index received' (duration: 378.759385ms)","trace[1856935965] 'applied index is now lower than readState.Index' (duration: 35.47121ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:39.000888Z","caller":"traceutil/trace.go:171","msg":"trace[1062849241] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"433.664465ms","start":"2026-03-25T21:09:38.567197Z","end":"2026-03-25T21:09:39.000862Z","steps":["trace[1062849241] 'process raft request' (duration: 421.510978ms)","trace[1062849241] 'compare' (duration: 12.027984ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:39.001056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.56717Z","time spent":"433.771047ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:39.00116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.88149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-25T21:09:39.001196Z","caller":"traceutil/trace.go:171","msg":"trace[1101360818] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2286; }","duration":"123.960611ms","start":"2026-03-25T21:09:38.877225Z","end":"2026-03-25T21:09:39.001186Z","steps":["trace[1101360818] 'agreement among raft nodes before linearized reading' (duration: 123.878269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.001066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.44006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-25T21:09:39.001386Z","caller":"traceutil/trace.go:171","msg":"trace[254528868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2286; }","duration":"414.83969ms","start":"2026-03-25T21:09:38.586523Z","end":"2026-03-25T21:09:39.001363Z","steps":["trace[254528868] 'agreement among raft nodes before linearized reading' (duration: 414.394249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.001532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.5865Z","time spent":"415.014013ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T21:09:39.001205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.205794ms","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-03-25T21:09:39.002077Z","caller":"traceutil/trace.go:171","msg":"trace[1985644144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2286; }","duration":"363.141387ms","start":"2026-03-25T21:09:38.638918Z","end":"2026-03-25T21:09:39.002059Z","steps":["trace[1985644144] 'agreement among raft nodes before linearized reading' (duration: 362.152944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.001451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.992295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:09:39.002209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.638889Z","time spent":"363.30522ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T21:09:39.002289Z","caller":"traceutil/trace.go:171","msg":"trace[102384048] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2286; }","duration":"273.866025ms","start":"2026-03-25T21:09:38.728378Z","end":"2026-03-25T21:09:39.002244Z","steps":["trace[102384048] 'agreement among raft nodes before linearized reading' (duration: 272.937293ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:07.498738Z","caller":"traceutil/trace.go:171","msg":"trace[1130739197] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"156.763916ms","start":"2026-03-25T21:10:07.34195Z","end":"2026-03-25T21:10:07.498714Z","steps":["trace[1130739197] 'process raft request' (duration: 156.730725ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:07.498717Z","caller":"traceutil/trace.go:171","msg":"trace[834851872] linearizableReadLoop","detail":"{readStateIndex:2524; appliedIndex:2523; }","duration":"158.16926ms","start":"2026-03-25T21:10:07.340505Z","end":"2026-03-25T21:10:07.498674Z","steps":["trace[834851872] 'read index received' (duration: 73.511298ms)","trace[834851872] 'applied index is now lower than readState.Index' (duration: 84.654912ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:07.498901Z","caller":"traceutil/trace.go:171","msg":"trace[124284524] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"158.198411ms","start":"2026-03-25T21:10:07.340678Z","end":"2026-03-25T21:10:07.498876Z","steps":["trace[124284524] 'process raft request' (duration: 157.874653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:07.499149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.60248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363694"} {"level":"info","ts":"2026-03-25T21:10:07.499214Z","caller":"traceutil/trace.go:171","msg":"trace[1632399471] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2427; }","duration":"158.725233ms","start":"2026-03-25T21:10:07.340472Z","end":"2026-03-25T21:10:07.499197Z","steps":["trace[1632399471] 'agreement among raft nodes before linearized reading' (duration: 158.331844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:09.328982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.907194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:10:09.329422Z","caller":"traceutil/trace.go:171","msg":"trace[1664125468] linearizableReadLoop","detail":"{readStateIndex:2531; appliedIndex:2530; }","duration":"181.87565ms","start":"2026-03-25T21:10:09.147525Z","end":"2026-03-25T21:10:09.329401Z","steps":["trace[1664125468] 'read index received' (duration: 31.324611ms)","trace[1664125468] 'applied index is now lower than readState.Index' (duration: 150.549269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:10:09.329616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.99734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-03-25T21:10:09.329683Z","caller":"traceutil/trace.go:171","msg":"trace[763321998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2432; }","duration":"143.115553ms","start":"2026-03-25T21:10:09.186555Z","end":"2026-03-25T21:10:09.32967Z","steps":["trace[763321998] 'agreement among raft nodes before linearized reading' (duration: 143.00241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:09.329647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.089665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-03-25T21:10:09.32982Z","caller":"traceutil/trace.go:171","msg":"trace[2095033731] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2432; }","duration":"182.28307ms","start":"2026-03-25T21:10:09.147473Z","end":"2026-03-25T21:10:09.329756Z","steps":["trace[2095033731] 'agreement among raft nodes before linearized reading' (duration: 182.053414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:09.329954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.391412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-25T21:10:09.330105Z","caller":"traceutil/trace.go:171","msg":"trace[1801982789] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"208.346735ms","start":"2026-03-25T21:10:09.12108Z","end":"2026-03-25T21:10:09.329427Z","steps":["trace[1801982789] 'process raft request' (duration: 57.847067ms)","trace[1801982789] 'compare' (duration: 149.802522ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:09.330159Z","caller":"traceutil/trace.go:171","msg":"trace[51569291] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2432; }","duration":"138.647128ms","start":"2026-03-25T21:10:09.19149Z","end":"2026-03-25T21:10:09.330138Z","steps":["trace[51569291] 'agreement among raft nodes before linearized reading' (duration: 138.211228ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:09.447143Z","caller":"traceutil/trace.go:171","msg":"trace[1284568144] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"112.219023ms","start":"2026-03-25T21:10:09.334894Z","end":"2026-03-25T21:10:09.447113Z","steps":["trace[1284568144] 'process raft request' (duration: 110.746599ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:09.447222Z","caller":"traceutil/trace.go:171","msg":"trace[1094637767] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"111.988237ms","start":"2026-03-25T21:10:09.335204Z","end":"2026-03-25T21:10:09.447193Z","steps":["trace[1094637767] 'process raft request' (duration: 111.853114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:09.447605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.958172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T21:10:09.447674Z","caller":"traceutil/trace.go:171","msg":"trace[1989328390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2435; }","duration":"108.069404ms","start":"2026-03-25T21:10:09.339589Z","end":"2026-03-25T21:10:09.447658Z","steps":["trace[1989328390] 'agreement among raft nodes before linearized reading' (duration: 107.839279ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:15.747379Z","caller":"traceutil/trace.go:171","msg":"trace[1519663276] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"189.240903ms","start":"2026-03-25T21:10:15.558105Z","end":"2026-03-25T21:10:15.747345Z","steps":["trace[1519663276] 'read index received' (duration: 189.026188ms)","trace[1519663276] 'applied index is now lower than readState.Index' (duration: 213.485µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:15.747561Z","caller":"traceutil/trace.go:171","msg":"trace[2134543316] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"194.849556ms","start":"2026-03-25T21:10:15.552699Z","end":"2026-03-25T21:10:15.747549Z","steps":["trace[2134543316] 'process raft request' (duration: 194.473317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:15.747761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.633229ms","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-03-25T21:10:15.747861Z","caller":"traceutil/trace.go:171","msg":"trace[1177326163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2462; }","duration":"158.770443ms","start":"2026-03-25T21:10:15.58907Z","end":"2026-03-25T21:10:15.74784Z","steps":["trace[1177326163] 'agreement among raft nodes before linearized reading' (duration: 158.512037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:15.748029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.892238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4qnfj\" ","response":"range_response_count:1 size:13721"} {"level":"info","ts":"2026-03-25T21:10:15.748089Z","caller":"traceutil/trace.go:171","msg":"trace[729204936] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4qnfj; range_end:; response_count:1; response_revision:2462; }","duration":"189.994951ms","start":"2026-03-25T21:10:15.558076Z","end":"2026-03-25T21:10:15.748071Z","steps":["trace[729204936] 'agreement among raft nodes before linearized reading' (duration: 189.748405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:15.74842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.454703ms","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-03-25T21:10:15.748533Z","caller":"traceutil/trace.go:171","msg":"trace[1260840716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2462; }","duration":"157.598156ms","start":"2026-03-25T21:10:15.59091Z","end":"2026-03-25T21:10:15.748508Z","steps":["trace[1260840716] 'agreement among raft nodes before linearized reading' (duration: 156.96576ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:16.030562Z","caller":"traceutil/trace.go:171","msg":"trace[278504085] linearizableReadLoop","detail":"{readStateIndex:2564; appliedIndex:2562; }","duration":"276.726273ms","start":"2026-03-25T21:10:15.75381Z","end":"2026-03-25T21:10:16.030536Z","steps":["trace[278504085] 'read index received' (duration: 36.270757ms)","trace[278504085] 'applied index is now lower than readState.Index' (duration: 240.454196ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:16.030669Z","caller":"traceutil/trace.go:171","msg":"trace[1517910216] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"277.099572ms","start":"2026-03-25T21:10:15.753514Z","end":"2026-03-25T21:10:16.030614Z","steps":["trace[1517910216] 'process raft request' (duration: 237.481925ms)","trace[1517910216] 'compare' (duration: 39.408872ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:10:16.030898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.112835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4qnfj\" ","response":"range_response_count:1 size:13721"} {"level":"warn","ts":"2026-03-25T21:10:16.030913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.061321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-25T21:10:16.030947Z","caller":"traceutil/trace.go:171","msg":"trace[1392104820] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-4qnfj; range_end:; response_count:1; response_revision:2464; }","duration":"201.215697ms","start":"2026-03-25T21:10:15.829717Z","end":"2026-03-25T21:10:16.030933Z","steps":["trace[1392104820] 'agreement among raft nodes before linearized reading' (duration: 201.053733ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:16.030973Z","caller":"traceutil/trace.go:171","msg":"trace[2104279849] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2464; }","duration":"255.159383ms","start":"2026-03-25T21:10:15.775797Z","end":"2026-03-25T21:10:16.030956Z","steps":["trace[2104279849] 'agreement among raft nodes before linearized reading' (duration: 254.971288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:16.030977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.269641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:10:16.03103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.483583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:10:16.031041Z","caller":"traceutil/trace.go:171","msg":"trace[1011230802] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2464; }","duration":"128.359864ms","start":"2026-03-25T21:10:15.902664Z","end":"2026-03-25T21:10:16.031024Z","steps":["trace[1011230802] 'agreement among raft nodes before linearized reading' (duration: 128.244102ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:16.031076Z","caller":"traceutil/trace.go:171","msg":"trace[1091743312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2464; }","duration":"242.563965ms","start":"2026-03-25T21:10:15.788501Z","end":"2026-03-25T21:10:16.031065Z","steps":["trace[1091743312] 'agreement among raft nodes before linearized reading' (duration: 242.489473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:16.031547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.690696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-25T21:10:16.031729Z","caller":"traceutil/trace.go:171","msg":"trace[1021443305] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2464; }","duration":"277.917822ms","start":"2026-03-25T21:10:15.753785Z","end":"2026-03-25T21:10:16.031703Z","steps":["trace[1021443305] 'agreement among raft nodes before linearized reading' (duration: 276.868667ms)"],"step_count":1}