{"level":"warn","ts":"2026-03-13T09:52:16.485138Z","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-13T09:52:16.485222Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237: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-13T09:52:16.485283Z","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-13T09:52:16.485296Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-03-13T09:52:16.485314Z","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-13T09:52:16.485949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-03-13T09:52:16.486029Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237: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-13T09:52:16.491161Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.93457ms"} {"level":"info","ts":"2026-03-13T09:52:16.498596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-03-13T09:52:16.498691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T09:52:16.498728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-03-13T09:52:16.498751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T09:52:16.498771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-03-13T09:52:16.498825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-03-13T09:52:16.513122Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T09:52:16.51557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T09:52:16.517292Z","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-13T09:52:16.52018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T09:52:16.520757Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T09:52:16.520734Z","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-13T09:52:16.520973Z","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-13T09:52:16.520992Z","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-13T09:52:16.524147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-03-13T09:52:16.524413Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-03-13T09:52:16.525903Z","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-13T09:52:16.526173Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T09:52:16.526209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T09:52:16.52635Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-03-13T09:52:16.526373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-03-13T09:52:17.399301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T09:52:17.399382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T09:52:17.399398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-03-13T09:52:17.399411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-03-13T09:52:17.399417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-03-13T09:52:17.399426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-03-13T09:52:17.39944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-03-13T09:52:17.401924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T09:52:17.401968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:17.402136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:17.402193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T09:52:17.402443Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T09:52:17.402522Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T09:52:17.405424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T09:52:17.409631Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:17.410151Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:17.412314Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T09:52:17.41449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"info","ts":"2026-03-13T09:52:53.951936Z","caller":"traceutil/trace.go:171","msg":"trace[417190084] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"421.748636ms","start":"2026-03-13T09:52:53.530155Z","end":"2026-03-13T09:52:53.951904Z","steps":["trace[417190084] 'process raft request' (duration: 421.196791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:52:53.952615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:52:53.530138Z","time spent":"421.89613ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:52:54.186511Z","caller":"traceutil/trace.go:171","msg":"trace[1238881227] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"219.057773ms","start":"2026-03-13T09:52:53.967434Z","end":"2026-03-13T09:52:54.186492Z","steps":["trace[1238881227] 'process raft request' (duration: 218.95739ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:09.018592Z","caller":"traceutil/trace.go:171","msg":"trace[1227267013] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"147.664107ms","start":"2026-03-13T09:53:08.870884Z","end":"2026-03-13T09:53:09.018548Z","steps":["trace[1227267013] 'process raft request' (duration: 147.41883ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:14.757212Z","caller":"traceutil/trace.go:171","msg":"trace[1303359905] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"201.701346ms","start":"2026-03-13T09:53:14.555437Z","end":"2026-03-13T09:53:14.757138Z","steps":["trace[1303359905] 'process raft request' (duration: 201.350127ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:15.187023Z","caller":"traceutil/trace.go:171","msg":"trace[1178794725] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"132.548579ms","start":"2026-03-13T09:53:15.054446Z","end":"2026-03-13T09:53:15.186994Z","steps":["trace[1178794725] 'process raft request' (duration: 132.408095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:39.678663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.209945ms","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-13T09:53:39.678814Z","caller":"traceutil/trace.go:171","msg":"trace[1338465458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"236.491783ms","start":"2026-03-13T09:53:39.442302Z","end":"2026-03-13T09:53:39.678794Z","steps":["trace[1338465458] 'range keys from in-memory index tree' (duration: 236.00835ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:53.103632Z","caller":"traceutil/trace.go:171","msg":"trace[584621080] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"101.654877ms","start":"2026-03-13T09:53:53.001954Z","end":"2026-03-13T09:53:53.103609Z","steps":["trace[584621080] 'process raft request' (duration: 101.507062ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:53:56.199297Z","caller":"traceutil/trace.go:171","msg":"trace[2132071973] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"388.583173ms","start":"2026-03-13T09:53:55.810695Z","end":"2026-03-13T09:53:56.199279Z","steps":["trace[2132071973] 'read index received' (duration: 388.410589ms)","trace[2132071973] 'applied index is now lower than readState.Index' (duration: 171.954µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:53:56.199485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.776488ms","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-13T09:53:56.199526Z","caller":"traceutil/trace.go:171","msg":"trace[1423265443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"388.883301ms","start":"2026-03-13T09:53:55.810624Z","end":"2026-03-13T09:53:56.199507Z","steps":["trace[1423265443] 'agreement among raft nodes before linearized reading' (duration: 388.727486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:56.199555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:55.81059Z","time spent":"388.958243ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-13T09:53:56.199592Z","caller":"traceutil/trace.go:171","msg":"trace[2135632111] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"391.650016ms","start":"2026-03-13T09:53:55.807909Z","end":"2026-03-13T09:53:56.199559Z","steps":["trace[2135632111] 'process raft request' (duration: 391.254085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:53:56.199745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:53:55.807882Z","time spent":"391.780059ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:53:56.460096Z","caller":"traceutil/trace.go:171","msg":"trace[1018038729] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"256.455879ms","start":"2026-03-13T09:53:56.203614Z","end":"2026-03-13T09:53:56.46007Z","steps":["trace[1018038729] 'process raft request' (duration: 238.174108ms)","trace[1018038729] 'compare' (duration: 17.934941ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:11.079635Z","caller":"traceutil/trace.go:171","msg":"trace[519064430] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"112.899119ms","start":"2026-03-13T09:54:10.966711Z","end":"2026-03-13T09:54:11.07961Z","steps":["trace[519064430] 'process raft request' (duration: 112.731454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.113558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559288004s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:13.113785Z","caller":"traceutil/trace.go:171","msg":"trace[2118735006] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"1.614728842s","start":"2026-03-13T09:54:11.499041Z","end":"2026-03-13T09:54:13.11377Z","steps":["trace[2118735006] 'read index received' (duration: 55.033636ms)","trace[2118735006] 'applied index is now lower than readState.Index' (duration: 1.559694366s)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:13.113876Z","caller":"traceutil/trace.go:171","msg":"trace[1112134922] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.802413685s","start":"2026-03-13T09:54:11.311451Z","end":"2026-03-13T09:54:13.113865Z","steps":["trace[1112134922] 'process raft request' (duration: 242.612477ms)","trace[1112134922] 'compare' (duration: 1.559022448s)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:13.113928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:11.311428Z","time spent":"1.802470237s","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:13.114206Z","caller":"traceutil/trace.go:171","msg":"trace[1922431988] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.798755937s","start":"2026-03-13T09:54:11.315439Z","end":"2026-03-13T09:54:13.114195Z","steps":["trace[1922431988] 'process raft request' (duration: 1.798213453s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.114259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:11.315432Z","time spent":"1.798797769s","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:13.114448Z","caller":"traceutil/trace.go:171","msg":"trace[1509025357] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.684398779s","start":"2026-03-13T09:54:11.43004Z","end":"2026-03-13T09:54:13.114439Z","steps":["trace[1509025357] 'process raft request' (duration: 1.683691311s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.114512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:11.430011Z","time spent":"1.684459171s","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:13.114728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.615694307s","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-13T09:54:13.114755Z","caller":"traceutil/trace.go:171","msg":"trace[726556312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"1.615759349s","start":"2026-03-13T09:54:11.498987Z","end":"2026-03-13T09:54:13.114746Z","steps":["trace[726556312] 'agreement among raft nodes before linearized reading' (duration: 1.615654226s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.114784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:11.498965Z","time spent":"1.615812509s","remote":"127.0.0.1:46086","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-13T09:54:13.114981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.752555ms","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-13T09:54:13.11503Z","caller":"traceutil/trace.go:171","msg":"trace[892990262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"826.834818ms","start":"2026-03-13T09:54:12.288183Z","end":"2026-03-13T09:54:13.115017Z","steps":["trace[892990262] 'agreement among raft nodes before linearized reading' (duration: 826.702003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.288168Z","time spent":"826.878889ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-13T09:54:13.115136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.916047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:13.115169Z","caller":"traceutil/trace.go:171","msg":"trace[1601502946] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:954; }","duration":"503.967668ms","start":"2026-03-13T09:54:12.611192Z","end":"2026-03-13T09:54:13.11516Z","steps":["trace[1601502946] 'agreement among raft nodes before linearized reading' (duration: 503.917066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.18383ms","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-13T09:54:13.115211Z","caller":"traceutil/trace.go:171","msg":"trace[1384178388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"505.231021ms","start":"2026-03-13T09:54:12.609975Z","end":"2026-03-13T09:54:13.115206Z","steps":["trace[1384178388] 'agreement among raft nodes before linearized reading' (duration: 505.17497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.611184Z","time spent":"504.020349ms","remote":"127.0.0.1:46098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:54:13.115226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.609963Z","time spent":"505.259951ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T09:54:13.115309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.561324ms","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-13T09:54:13.115321Z","caller":"traceutil/trace.go:171","msg":"trace[207145564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"507.573394ms","start":"2026-03-13T09:54:12.607744Z","end":"2026-03-13T09:54:13.115317Z","steps":["trace[207145564] 'agreement among raft nodes before linearized reading' (duration: 507.536723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.607696Z","time spent":"507.634295ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T09:54:13.115411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.752804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:13.115425Z","caller":"traceutil/trace.go:171","msg":"trace[1091000811] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:954; }","duration":"716.792336ms","start":"2026-03-13T09:54:12.398628Z","end":"2026-03-13T09:54:13.11542Z","steps":["trace[1091000811] 'agreement among raft nodes before linearized reading' (duration: 716.750725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.398618Z","time spent":"716.815827ms","remote":"127.0.0.1:46272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:54:13.115433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.687524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:54:13.115461Z","caller":"traceutil/trace.go:171","msg":"trace[319565367] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:954; }","duration":"291.756536ms","start":"2026-03-13T09:54:12.823697Z","end":"2026-03-13T09:54:13.115453Z","steps":["trace[319565367] 'agreement among raft nodes before linearized reading' (duration: 291.677904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.11551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.300219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:13.115524Z","caller":"traceutil/trace.go:171","msg":"trace[261372242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"736.3505ms","start":"2026-03-13T09:54:12.379169Z","end":"2026-03-13T09:54:13.11552Z","steps":["trace[261372242] 'agreement among raft nodes before linearized reading' (duration: 736.32976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.115535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:12.379149Z","time spent":"736.383461ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T09:54:13.645225Z","caller":"traceutil/trace.go:171","msg":"trace[1116702899] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:994; }","duration":"412.575266ms","start":"2026-03-13T09:54:13.232631Z","end":"2026-03-13T09:54:13.645206Z","steps":["trace[1116702899] 'read index received' (duration: 330.714759ms)","trace[1116702899] 'applied index is now lower than readState.Index' (duration: 81.859717ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:13.645498Z","caller":"traceutil/trace.go:171","msg":"trace[938250299] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"524.444297ms","start":"2026-03-13T09:54:13.121029Z","end":"2026-03-13T09:54:13.645474Z","steps":["trace[938250299] 'process raft request' (duration: 524.100758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.645513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.488053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:13.645506Z","caller":"traceutil/trace.go:171","msg":"trace[966565746] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"523.46218ms","start":"2026-03-13T09:54:13.122018Z","end":"2026-03-13T09:54:13.64548Z","steps":["trace[966565746] 'process raft request' (duration: 523.152862ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:13.645569Z","caller":"traceutil/trace.go:171","msg":"trace[891231988] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:958; }","duration":"173.628547ms","start":"2026-03-13T09:54:13.471929Z","end":"2026-03-13T09:54:13.645557Z","steps":["trace[891231988] 'agreement among raft nodes before linearized reading' (duration: 173.536494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.645592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.966788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T09:54:13.645651Z","caller":"traceutil/trace.go:171","msg":"trace[1427506707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"413.07502ms","start":"2026-03-13T09:54:13.232562Z","end":"2026-03-13T09:54:13.645637Z","steps":["trace[1427506707] 'agreement among raft nodes before linearized reading' (duration: 412.863824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:13.645656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:13.121999Z","time spent":"523.578614ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:13.645664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:13.121023Z","time spent":"524.54101ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:13.645701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:13.232539Z","time spent":"413.149602ms","remote":"127.0.0.1:46086","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-13T09:54:13.64571Z","caller":"traceutil/trace.go:171","msg":"trace[1606098846] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"525.129905ms","start":"2026-03-13T09:54:13.120509Z","end":"2026-03-13T09:54:13.645638Z","steps":["trace[1606098846] 'process raft request' (duration: 442.906359ms)","trace[1606098846] 'compare' (duration: 81.582479ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:13.647766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:13.120497Z","time spent":"526.941604ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:14.010929Z","caller":"traceutil/trace.go:171","msg":"trace[1957917866] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"361.471355ms","start":"2026-03-13T09:54:13.649423Z","end":"2026-03-13T09:54:14.010894Z","steps":["trace[1957917866] 'process raft request' (duration: 289.634929ms)","trace[1957917866] 'compare' (duration: 71.160758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:14.011091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:13.649392Z","time spent":"361.619749ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:14.829516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.959682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:14.829582Z","caller":"traceutil/trace.go:171","msg":"trace[1932610106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"452.080016ms","start":"2026-03-13T09:54:14.377489Z","end":"2026-03-13T09:54:14.829569Z","steps":["trace[1932610106] 'range keys from in-memory index tree' (duration: 451.869851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:14.829618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:14.377468Z","time spent":"452.144747ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T09:54:14.835661Z","caller":"traceutil/trace.go:171","msg":"trace[1473309977] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"169.609359ms","start":"2026-03-13T09:54:14.666042Z","end":"2026-03-13T09:54:14.835651Z","steps":["trace[1473309977] 'process raft request' (duration: 169.489926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:15.565053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334016459799127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T09:54:15.851209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.89935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:15.85132Z","caller":"traceutil/trace.go:171","msg":"trace[758079514] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"787.228144ms","start":"2026-03-13T09:54:15.064075Z","end":"2026-03-13T09:54:15.851303Z","steps":["trace[758079514] 'read index received' (duration: 558.14674ms)","trace[758079514] 'applied index is now lower than readState.Index' (duration: 229.079524ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:15.851369Z","caller":"traceutil/trace.go:171","msg":"trace[1163796308] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"889.192611ms","start":"2026-03-13T09:54:14.962143Z","end":"2026-03-13T09:54:15.851336Z","steps":["trace[1163796308] 'process raft request' (duration: 660.086365ms)","trace[1163796308] 'compare' (duration: 228.695785ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:15.851493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.38905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:54:15.851534Z","caller":"traceutil/trace.go:171","msg":"trace[113725520] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:961; }","duration":"787.493482ms","start":"2026-03-13T09:54:15.064027Z","end":"2026-03-13T09:54:15.851521Z","steps":["trace[113725520] 'agreement among raft nodes before linearized reading' (duration: 787.349329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:15.851596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:14.962115Z","time spent":"889.374515ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:15.851634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.06401Z","time spent":"787.612695ms","remote":"127.0.0.1:45942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:54:16.11845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.216559ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-13T09:54:16.118566Z","caller":"traceutil/trace.go:171","msg":"trace[205760578] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"267.198657ms","start":"2026-03-13T09:54:15.851349Z","end":"2026-03-13T09:54:16.118547Z","steps":["trace[205760578] 'read index received' (duration: 15.748312ms)","trace[205760578] 'applied index is now lower than readState.Index' (duration: 251.448945ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:16.118717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.222871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:54:16.118797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.029068ms","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-13T09:54:16.118798Z","caller":"traceutil/trace.go:171","msg":"trace[879341573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"741.331925ms","start":"2026-03-13T09:54:15.377441Z","end":"2026-03-13T09:54:16.118773Z","steps":["trace[879341573] 'agreement among raft nodes before linearized reading' (duration: 741.202371ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:16.118832Z","caller":"traceutil/trace.go:171","msg":"trace[844610166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"970.11468ms","start":"2026-03-13T09:54:15.148705Z","end":"2026-03-13T09:54:16.11882Z","steps":["trace[844610166] 'agreement among raft nodes before linearized reading' (duration: 969.956547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.11885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.377427Z","time spent":"741.411757ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T09:54:16.11887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.148682Z","time spent":"970.179822ms","remote":"127.0.0.1:46086","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-13T09:54:16.11891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.039214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T09:54:16.118937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.373509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T09:54:16.118955Z","caller":"traceutil/trace.go:171","msg":"trace[740403049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"468.126007ms","start":"2026-03-13T09:54:15.650816Z","end":"2026-03-13T09:54:16.118942Z","steps":["trace[740403049] 'agreement among raft nodes before linearized reading' (duration: 467.946951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.118988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.650789Z","time spent":"468.192208ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T09:54:16.118993Z","caller":"traceutil/trace.go:171","msg":"trace[638375887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"104.460392ms","start":"2026-03-13T09:54:16.014516Z","end":"2026-03-13T09:54:16.118976Z","steps":["trace[638375887] 'agreement among raft nodes before linearized reading' (duration: 104.311228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.119089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.342173ms","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-13T09:54:16.119113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.1572ms","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-13T09:54:16.119167Z","caller":"traceutil/trace.go:171","msg":"trace[1214068317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"468.464435ms","start":"2026-03-13T09:54:15.650683Z","end":"2026-03-13T09:54:16.119148Z","steps":["trace[1214068317] 'agreement among raft nodes before linearized reading' (duration: 468.216639ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:16.119193Z","caller":"traceutil/trace.go:171","msg":"trace[1737135093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"467.266364ms","start":"2026-03-13T09:54:15.651907Z","end":"2026-03-13T09:54:16.119174Z","steps":["trace[1737135093] 'agreement among raft nodes before linearized reading' (duration: 467.012186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.119214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.650664Z","time spent":"468.540447ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T09:54:16.119239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:15.6519Z","time spent":"467.330275ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T09:54:16.438835Z","caller":"traceutil/trace.go:171","msg":"trace[1260444152] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"314.63364ms","start":"2026-03-13T09:54:16.124178Z","end":"2026-03-13T09:54:16.438812Z","steps":["trace[1260444152] 'process raft request' (duration: 314.441205ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:16.439139Z","caller":"traceutil/trace.go:171","msg":"trace[2022995786] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"314.623819ms","start":"2026-03-13T09:54:16.124465Z","end":"2026-03-13T09:54:16.439089Z","steps":["trace[2022995786] 'process raft request' (duration: 314.30649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.43928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:16.124452Z","time spent":"314.753532ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:16.439155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:16.124159Z","time spent":"314.743552ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:16.439421Z","caller":"traceutil/trace.go:171","msg":"trace[512603919] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"314.858865ms","start":"2026-03-13T09:54:16.124552Z","end":"2026-03-13T09:54:16.439411Z","steps":["trace[512603919] 'process raft request' (duration: 314.464585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.439484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:16.124545Z","time spent":"314.909306ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:16.439632Z","caller":"traceutil/trace.go:171","msg":"trace[2135643303] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"314.919507ms","start":"2026-03-13T09:54:16.124688Z","end":"2026-03-13T09:54:16.439607Z","steps":["trace[2135643303] 'process raft request' (duration: 314.675671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:16.439766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:16.124682Z","time spent":"315.01459ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:17.162747Z","caller":"traceutil/trace.go:171","msg":"trace[609804606] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"302.695429ms","start":"2026-03-13T09:54:16.860025Z","end":"2026-03-13T09:54:17.16272Z","steps":["trace[609804606] 'process raft request' (duration: 302.553215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:17.162888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:16.860004Z","time spent":"302.811691ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:17.878995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038334016459799140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T09:54:17.962922Z","caller":"traceutil/trace.go:171","msg":"trace[1669512167] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"584.883078ms","start":"2026-03-13T09:54:17.378014Z","end":"2026-03-13T09:54:17.962897Z","steps":["trace[1669512167] 'read index received' (duration: 514.941691ms)","trace[1669512167] 'applied index is now lower than readState.Index' (duration: 69.940397ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:17.962981Z","caller":"traceutil/trace.go:171","msg":"trace[255044227] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"774.398482ms","start":"2026-03-13T09:54:17.188549Z","end":"2026-03-13T09:54:17.962947Z","steps":["trace[255044227] 'process raft request' (duration: 704.356653ms)","trace[255044227] 'compare' (duration: 69.881265ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:17.963112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.582538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T09:54:17.963118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:17.188527Z","time spent":"774.516194ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:17.963113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.087144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:17.963162Z","caller":"traceutil/trace.go:171","msg":"trace[532483038] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:968; }","duration":"355.694051ms","start":"2026-03-13T09:54:17.60745Z","end":"2026-03-13T09:54:17.963144Z","steps":["trace[532483038] 'agreement among raft nodes before linearized reading' (duration: 355.607419ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:17.963187Z","caller":"traceutil/trace.go:171","msg":"trace[1368438914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"585.230898ms","start":"2026-03-13T09:54:17.377938Z","end":"2026-03-13T09:54:17.963169Z","steps":["trace[1368438914] 'agreement among raft nodes before linearized reading' (duration: 585.042692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:17.9632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:17.607426Z","time spent":"355.764672ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-13T09:54:17.96324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:17.377905Z","time spent":"585.32621ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T09:54:18.208378Z","caller":"traceutil/trace.go:171","msg":"trace[1263966546] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"239.008051ms","start":"2026-03-13T09:54:17.969345Z","end":"2026-03-13T09:54:18.208353Z","steps":["trace[1263966546] 'process raft request' (duration: 210.954188ms)","trace[1263966546] 'compare' (duration: 27.731315ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:18.418796Z","caller":"traceutil/trace.go:171","msg":"trace[616000509] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"447.966077ms","start":"2026-03-13T09:54:17.970804Z","end":"2026-03-13T09:54:18.41877Z","steps":["trace[616000509] 'process raft request' (duration: 430.755814ms)","trace[616000509] 'compare' (duration: 16.982256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:18.418984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:17.970786Z","time spent":"448.093769ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:18.419172Z","caller":"traceutil/trace.go:171","msg":"trace[227270920] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"244.405585ms","start":"2026-03-13T09:54:18.174743Z","end":"2026-03-13T09:54:18.419149Z","steps":["trace[227270920] 'read index received' (duration: 5.567749ms)","trace[227270920] 'applied index is now lower than readState.Index' (duration: 238.835726ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:18.419237Z","caller":"traceutil/trace.go:171","msg":"trace[336390541] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"351.700753ms","start":"2026-03-13T09:54:18.067524Z","end":"2026-03-13T09:54:18.419225Z","steps":["trace[336390541] 'process raft request' (duration: 351.19409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.419297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.067485Z","time spent":"351.780375ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T09:54:18.419408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.642491ms","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-13T09:54:18.419453Z","caller":"traceutil/trace.go:171","msg":"trace[1760453416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"244.731745ms","start":"2026-03-13T09:54:18.174708Z","end":"2026-03-13T09:54:18.419439Z","steps":["trace[1760453416] 'agreement among raft nodes before linearized reading' (duration: 244.53161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.802083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.203793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:18.802178Z","caller":"traceutil/trace.go:171","msg":"trace[1103548901] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"358.926778ms","start":"2026-03-13T09:54:18.443236Z","end":"2026-03-13T09:54:18.802162Z","steps":["trace[1103548901] 'read index received' (duration: 16.056561ms)","trace[1103548901] 'applied index is now lower than readState.Index' (duration: 342.869147ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:18.802264Z","caller":"traceutil/trace.go:171","msg":"trace[949569313] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"378.45031ms","start":"2026-03-13T09:54:18.423804Z","end":"2026-03-13T09:54:18.802254Z","steps":["trace[949569313] 'process raft request' (duration: 162.969061ms)","trace[949569313] 'compare' (duration: 215.091239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:18.802326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.423787Z","time spent":"378.508583ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:18.802514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.161883ms","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-13T09:54:18.802592Z","caller":"traceutil/trace.go:171","msg":"trace[1578301186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"359.290777ms","start":"2026-03-13T09:54:18.443283Z","end":"2026-03-13T09:54:18.802574Z","steps":["trace[1578301186] 'agreement among raft nodes before linearized reading' (duration: 359.063421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.802644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.443264Z","time spent":"359.370279ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T09:54:18.802734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.489342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T09:54:18.802778Z","caller":"traceutil/trace.go:171","msg":"trace[340381538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"359.555873ms","start":"2026-03-13T09:54:18.443212Z","end":"2026-03-13T09:54:18.802767Z","steps":["trace[340381538] 'agreement among raft nodes before linearized reading' (duration: 359.41564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.802815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.4432Z","time spent":"359.608046ms","remote":"127.0.0.1:46086","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":"warn","ts":"2026-03-13T09:54:18.802919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.835124ms","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-13T09:54:18.80296Z","caller":"traceutil/trace.go:171","msg":"trace[1810470661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"358.898196ms","start":"2026-03-13T09:54:18.444051Z","end":"2026-03-13T09:54:18.802949Z","steps":["trace[1810470661] 'agreement among raft nodes before linearized reading' (duration: 358.773983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.802999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.444042Z","time spent":"358.945958ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T09:54:18.802938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.747183ms","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-13T09:54:18.803111Z","caller":"traceutil/trace.go:171","msg":"trace[616986304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"358.949458ms","start":"2026-03-13T09:54:18.444147Z","end":"2026-03-13T09:54:18.803096Z","steps":["trace[616986304] 'agreement among raft nodes before linearized reading' (duration: 358.416304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:18.803226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:18.444141Z","time spent":"359.073821ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T09:54:18.969615Z","caller":"traceutil/trace.go:171","msg":"trace[945546855] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"162.416586ms","start":"2026-03-13T09:54:18.807174Z","end":"2026-03-13T09:54:18.969591Z","steps":["trace[945546855] 'process raft request' (duration: 162.332494ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:18.969632Z","caller":"traceutil/trace.go:171","msg":"trace[1872697880] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"163.277049ms","start":"2026-03-13T09:54:18.806323Z","end":"2026-03-13T09:54:18.9696Z","steps":["trace[1872697880] 'process raft request' (duration: 154.459883ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:18.969873Z","caller":"traceutil/trace.go:171","msg":"trace[1499151235] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"162.344075ms","start":"2026-03-13T09:54:18.807501Z","end":"2026-03-13T09:54:18.969845Z","steps":["trace[1499151235] 'process raft request' (duration: 162.044177ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:18.97004Z","caller":"traceutil/trace.go:171","msg":"trace[1497497708] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"162.365585ms","start":"2026-03-13T09:54:18.807641Z","end":"2026-03-13T09:54:18.970006Z","steps":["trace[1497497708] 'process raft request' (duration: 162.19078ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:20.91327Z","caller":"traceutil/trace.go:171","msg":"trace[2131066548] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"103.578209ms","start":"2026-03-13T09:54:20.80967Z","end":"2026-03-13T09:54:20.913248Z","steps":["trace[2131066548] 'process raft request' (duration: 103.436785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.022889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.145943ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:24.023025Z","caller":"traceutil/trace.go:171","msg":"trace[1478287675] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1020; }","duration":"384.297768ms","start":"2026-03-13T09:54:23.638705Z","end":"2026-03-13T09:54:24.023003Z","steps":["trace[1478287675] 'range keys from in-memory index tree' (duration: 384.128653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.023607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.88762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:24.023752Z","caller":"traceutil/trace.go:171","msg":"trace[1112834250] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"644.812796ms","start":"2026-03-13T09:54:23.378925Z","end":"2026-03-13T09:54:24.023738Z","steps":["trace[1112834250] 'read index received' (duration: 143.272183ms)","trace[1112834250] 'applied index is now lower than readState.Index' (duration: 501.539922ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:24.023919Z","caller":"traceutil/trace.go:171","msg":"trace[1211854984] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"854.596022ms","start":"2026-03-13T09:54:23.169311Z","end":"2026-03-13T09:54:24.023907Z","steps":["trace[1211854984] 'process raft request' (duration: 197.337912ms)","trace[1211854984] 'compare' (duration: 656.794808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:24.023963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.039131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:24.024018Z","caller":"traceutil/trace.go:171","msg":"trace[380611036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"645.124144ms","start":"2026-03-13T09:54:23.378881Z","end":"2026-03-13T09:54:24.024005Z","steps":["trace[380611036] 'agreement among raft nodes before linearized reading' (duration: 645.036311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.02406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:23.378835Z","time spent":"645.218126ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-13T09:54:24.024338Z","caller":"traceutil/trace.go:171","msg":"trace[1668091569] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"682.405464ms","start":"2026-03-13T09:54:23.34192Z","end":"2026-03-13T09:54:24.024325Z","steps":["trace[1668091569] 'process raft request' (duration: 681.771367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.023973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:23.169284Z","time spent":"854.664705ms","remote":"127.0.0.1:46232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:24.024433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:23.341892Z","time spent":"682.481525ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:24.024726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.998503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-13T09:54:24.024826Z","caller":"traceutil/trace.go:171","msg":"trace[137472767] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1022; }","duration":"600.151076ms","start":"2026-03-13T09:54:23.424647Z","end":"2026-03-13T09:54:24.024798Z","steps":["trace[137472767] 'agreement among raft nodes before linearized reading' (duration: 599.362216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:24.024915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:23.424625Z","time spent":"600.254081ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-13T09:54:25.0493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018250082s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T09:54:25.057604Z","caller":"traceutil/trace.go:171","msg":"trace[700894582] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.026621796s","start":"2026-03-13T09:54:24.030961Z","end":"2026-03-13T09:54:25.057582Z","steps":["trace[700894582] 'process raft request' (duration: 1.01890184s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:25.058264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:24.030942Z","time spent":"1.02672796s","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:25.05893Z","caller":"traceutil/trace.go:171","msg":"trace[271382612] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"147.444845ms","start":"2026-03-13T09:54:24.911407Z","end":"2026-03-13T09:54:25.058851Z","steps":["trace[271382612] 'process raft request' (duration: 147.062655ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:25.058967Z","caller":"traceutil/trace.go:171","msg":"trace[1404264155] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1063; }","duration":"128.542967ms","start":"2026-03-13T09:54:24.930115Z","end":"2026-03-13T09:54:25.058658Z","steps":["trace[1404264155] 'read index received' (duration: 119.723701ms)","trace[1404264155] 'applied index is now lower than readState.Index' (duration: 8.817446ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:25.059218Z","caller":"traceutil/trace.go:171","msg":"trace[1870980080] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"153.721553ms","start":"2026-03-13T09:54:24.905484Z","end":"2026-03-13T09:54:25.059206Z","steps":["trace[1870980080] 'process raft request' (duration: 152.677835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:25.059428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.290948ms","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-13T09:54:25.059505Z","caller":"traceutil/trace.go:171","msg":"trace[1813524320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"129.418312ms","start":"2026-03-13T09:54:24.930067Z","end":"2026-03-13T09:54:25.059485Z","steps":["trace[1813524320] 'agreement among raft nodes before linearized reading' (duration: 128.9684ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:30.527247Z","caller":"traceutil/trace.go:171","msg":"trace[1651349950] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"149.849819ms","start":"2026-03-13T09:54:30.377371Z","end":"2026-03-13T09:54:30.527221Z","steps":["trace[1651349950] 'read index received' (duration: 88.863713ms)","trace[1651349950] 'applied index is now lower than readState.Index' (duration: 60.985086ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:30.527316Z","caller":"traceutil/trace.go:171","msg":"trace[444736266] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"231.340605ms","start":"2026-03-13T09:54:30.295954Z","end":"2026-03-13T09:54:30.527295Z","steps":["trace[444736266] 'process raft request' (duration: 231.211311ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:30.527331Z","caller":"traceutil/trace.go:171","msg":"trace[1401456054] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"251.839425ms","start":"2026-03-13T09:54:30.275451Z","end":"2026-03-13T09:54:30.527291Z","steps":["trace[1401456054] 'process raft request' (duration: 190.719116ms)","trace[1401456054] 'compare' (duration: 60.843822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:30.527569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.031724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:30.527621Z","caller":"traceutil/trace.go:171","msg":"trace[534736727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"150.305361ms","start":"2026-03-13T09:54:30.377303Z","end":"2026-03-13T09:54:30.527608Z","steps":["trace[534736727] 'agreement among raft nodes before linearized reading' (duration: 150.032344ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:31.262785Z","caller":"traceutil/trace.go:171","msg":"trace[677035608] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"139.010657ms","start":"2026-03-13T09:54:31.123736Z","end":"2026-03-13T09:54:31.262747Z","steps":["trace[677035608] 'read index received' (duration: 138.691199ms)","trace[677035608] 'applied index is now lower than readState.Index' (duration: 312.558µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:31.26289Z","caller":"traceutil/trace.go:171","msg":"trace[857212317] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"139.136452ms","start":"2026-03-13T09:54:31.123702Z","end":"2026-03-13T09:54:31.262839Z","steps":["trace[857212317] 'process raft request' (duration: 138.774723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.263259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.465951ms","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-13T09:54:31.263335Z","caller":"traceutil/trace.go:171","msg":"trace[2096908605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"139.606445ms","start":"2026-03-13T09:54:31.123705Z","end":"2026-03-13T09:54:31.263311Z","steps":["trace[2096908605] 'agreement among raft nodes before linearized reading' (duration: 139.294167ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:31.269644Z","caller":"traceutil/trace.go:171","msg":"trace[1312217133] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"144.888636ms","start":"2026-03-13T09:54:31.124739Z","end":"2026-03-13T09:54:31.269628Z","steps":["trace[1312217133] 'process raft request' (duration: 144.723642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.269651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.582334ms","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:530"} {"level":"info","ts":"2026-03-13T09:54:31.269705Z","caller":"traceutil/trace.go:171","msg":"trace[1197964086] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1051; }","duration":"139.673146ms","start":"2026-03-13T09:54:31.130018Z","end":"2026-03-13T09:54:31.269691Z","steps":["trace[1197964086] 'agreement among raft nodes before linearized reading' (duration: 139.536013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.269837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.514291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-13T09:54:31.269863Z","caller":"traceutil/trace.go:171","msg":"trace[1347903546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"139.590574ms","start":"2026-03-13T09:54:31.130263Z","end":"2026-03-13T09:54:31.269853Z","steps":["trace[1347903546] 'agreement among raft nodes before linearized reading' (duration: 139.464881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.269889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.777968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T09:54:31.26994Z","caller":"traceutil/trace.go:171","msg":"trace[32962716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"139.875652ms","start":"2026-03-13T09:54:31.130052Z","end":"2026-03-13T09:54:31.269927Z","steps":["trace[32962716] 'agreement among raft nodes before linearized reading' (duration: 139.735339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.586786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.919456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:54:31.587062Z","caller":"traceutil/trace.go:171","msg":"trace[859676517] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"319.474349ms","start":"2026-03-13T09:54:31.267537Z","end":"2026-03-13T09:54:31.587012Z","steps":["trace[859676517] 'process raft request' (duration: 89.249115ms)","trace[859676517] 'compare' (duration: 229.805663ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:31.587084Z","caller":"traceutil/trace.go:171","msg":"trace[1794354233] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"313.346546ms","start":"2026-03-13T09:54:31.273702Z","end":"2026-03-13T09:54:31.587048Z","steps":["trace[1794354233] 'process raft request' (duration: 313.233653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.587655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.273682Z","time spent":"313.935421ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:31.58716Z","caller":"traceutil/trace.go:171","msg":"trace[1860357364] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"313.875289ms","start":"2026-03-13T09:54:31.27327Z","end":"2026-03-13T09:54:31.587146Z","steps":["trace[1860357364] 'process raft request' (duration: 313.611052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.587887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.267515Z","time spent":"320.005533ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:54:31.587916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.273258Z","time spent":"314.529157ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:31.587289Z","caller":"traceutil/trace.go:171","msg":"trace[2019167042] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1094; }","duration":"208.921914ms","start":"2026-03-13T09:54:31.378081Z","end":"2026-03-13T09:54:31.587003Z","steps":["trace[2019167042] 'read index received' (duration: 165.349716ms)","trace[2019167042] 'applied index is now lower than readState.Index' (duration: 43.571158ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:31.587395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.318705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:54:31.588212Z","caller":"traceutil/trace.go:171","msg":"trace[760814105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"210.158867ms","start":"2026-03-13T09:54:31.378035Z","end":"2026-03-13T09:54:31.588194Z","steps":["trace[760814105] 'agreement among raft nodes before linearized reading' (duration: 209.287943ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:54:31.587238Z","caller":"traceutil/trace.go:171","msg":"trace[1055994905] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"313.390646ms","start":"2026-03-13T09:54:31.273834Z","end":"2026-03-13T09:54:31.587224Z","steps":["trace[1055994905] 'process raft request' (duration: 313.138499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:31.58844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:54:31.273811Z","time spent":"314.586148ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:54:31.842936Z","caller":"traceutil/trace.go:171","msg":"trace[826237345] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"228.362904ms","start":"2026-03-13T09:54:31.614546Z","end":"2026-03-13T09:54:31.842909Z","steps":["trace[826237345] 'process raft request' (duration: 228.201421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:54:55.758785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.585882ms","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-13T09:54:55.758999Z","caller":"traceutil/trace.go:171","msg":"trace[930580670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"139.86824ms","start":"2026-03-13T09:54:55.619103Z","end":"2026-03-13T09:54:55.758971Z","steps":["trace[930580670] 'agreement among raft nodes before linearized reading' (duration: 44.345606ms)","trace[930580670] 'range keys from in-memory index tree' (duration: 95.145704ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:54:55.975761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.381747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T09:54:55.975994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.699452ms","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-13T09:54:55.976022Z","caller":"traceutil/trace.go:171","msg":"trace[1933575405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"136.671954ms","start":"2026-03-13T09:54:55.839323Z","end":"2026-03-13T09:54:55.975995Z","steps":["trace[1933575405] 'agreement among raft nodes before linearized reading' (duration: 19.540823ms)","trace[1933575405] 'range keys from in-memory index tree' (duration: 116.737731ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:54:55.976078Z","caller":"traceutil/trace.go:171","msg":"trace[688786223] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1154; }","duration":"137.834626ms","start":"2026-03-13T09:54:55.83822Z","end":"2026-03-13T09:54:55.976054Z","steps":["trace[688786223] 'agreement among raft nodes before linearized reading' (duration: 20.667103ms)","trace[688786223] 'range keys from in-memory index tree' (duration: 116.933717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:48.428523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.14784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:48.428625Z","caller":"traceutil/trace.go:171","msg":"trace[472874297] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"170.403453ms","start":"2026-03-13T09:55:48.258209Z","end":"2026-03-13T09:55:48.428612Z","steps":["trace[472874297] 'read index received' (duration: 53.044188ms)","trace[472874297] 'applied index is now lower than readState.Index' (duration: 117.358115ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:48.428697Z","caller":"traceutil/trace.go:171","msg":"trace[1249840217] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"233.534461ms","start":"2026-03-13T09:55:48.195118Z","end":"2026-03-13T09:55:48.428653Z","steps":["trace[1249840217] 'process raft request' (duration: 116.173075ms)","trace[1249840217] 'compare' (duration: 117.040227ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:48.428768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.560868ms","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-13T09:55:48.428791Z","caller":"traceutil/trace.go:171","msg":"trace[1662508042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1367; }","duration":"170.612708ms","start":"2026-03-13T09:55:48.258171Z","end":"2026-03-13T09:55:48.428784Z","steps":["trace[1662508042] 'agreement among raft nodes before linearized reading' (duration: 170.510865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.428843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.013469ms","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:530"} {"level":"info","ts":"2026-03-13T09:55:48.428932Z","caller":"traceutil/trace.go:171","msg":"trace[1055499469] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1367; }","duration":"168.107871ms","start":"2026-03-13T09:55:48.260805Z","end":"2026-03-13T09:55:48.428913Z","steps":["trace[1055499469] 'agreement among raft nodes before linearized reading' (duration: 167.933406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:48.823651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.811052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:48.823762Z","caller":"traceutil/trace.go:171","msg":"trace[123119541] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"390.796622ms","start":"2026-03-13T09:55:48.432945Z","end":"2026-03-13T09:55:48.823742Z","steps":["trace[123119541] 'process raft request' (duration: 185.805696ms)","trace[123119541] 'compare' (duration: 204.639547ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:48.823827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:48.432931Z","time spent":"390.868194ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.490832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.781603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:50.491023Z","caller":"traceutil/trace.go:171","msg":"trace[1156735965] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1428; }","duration":"296.217385ms","start":"2026-03-13T09:55:50.194787Z","end":"2026-03-13T09:55:50.491004Z","steps":["trace[1156735965] 'read index received' (duration: 6.256397ms)","trace[1156735965] 'applied index is now lower than readState.Index' (duration: 289.959718ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:50.491086Z","caller":"traceutil/trace.go:171","msg":"trace[277587478] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"370.588232ms","start":"2026-03-13T09:55:50.120468Z","end":"2026-03-13T09:55:50.491056Z","steps":["trace[277587478] 'process raft request' (duration: 370.479099ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.491189Z","caller":"traceutil/trace.go:171","msg":"trace[1881231179] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"371.710832ms","start":"2026-03-13T09:55:50.119294Z","end":"2026-03-13T09:55:50.491005Z","steps":["trace[1881231179] 'process raft request' (duration: 81.647081ms)","trace[1881231179] 'compare' (duration: 289.66468ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:50.491238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.149521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-13T09:55:50.491241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.120451Z","time spent":"370.706665ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:50.49127Z","caller":"traceutil/trace.go:171","msg":"trace[1727992576] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:1372; }","duration":"242.209221ms","start":"2026-03-13T09:55:50.24905Z","end":"2026-03-13T09:55:50.491259Z","steps":["trace[1727992576] 'agreement among raft nodes before linearized reading' (duration: 242.067038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.491287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.139124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-03-13T09:55:50.491346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.544782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T09:55:50.491437Z","caller":"traceutil/trace.go:171","msg":"trace[1435866614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"296.684246ms","start":"2026-03-13T09:55:50.194729Z","end":"2026-03-13T09:55:50.491413Z","steps":["trace[1435866614] 'agreement among raft nodes before linearized reading' (duration: 296.374779ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.49137Z","caller":"traceutil/trace.go:171","msg":"trace[1798185587] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"198.248967ms","start":"2026-03-13T09:55:50.293101Z","end":"2026-03-13T09:55:50.49135Z","steps":["trace[1798185587] 'agreement among raft nodes before linearized reading' (duration: 198.057682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.491508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.119263Z","time spent":"371.986909ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.95378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.911472ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-13T09:55:50.953941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.493266Z","time spent":"460.669838ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-13T09:55:50.986514Z","caller":"traceutil/trace.go:171","msg":"trace[1941349743] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1430; }","duration":"455.940922ms","start":"2026-03-13T09:55:50.530555Z","end":"2026-03-13T09:55:50.986496Z","steps":["trace[1941349743] 'read index received' (duration: 89.267366ms)","trace[1941349743] 'applied index is now lower than readState.Index' (duration: 366.672216ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:50.98661Z","caller":"traceutil/trace.go:171","msg":"trace[35057424] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"490.875675ms","start":"2026-03-13T09:55:50.495702Z","end":"2026-03-13T09:55:50.986577Z","steps":["trace[35057424] 'process raft request' (duration: 490.626729ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:50.986627Z","caller":"traceutil/trace.go:171","msg":"trace[1684555233] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"490.624389ms","start":"2026-03-13T09:55:50.495971Z","end":"2026-03-13T09:55:50.986596Z","steps":["trace[1684555233] 'process raft request' (duration: 490.485575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.986774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.495677Z","time spent":"491.007109ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.986783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.495962Z","time spent":"490.731961ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:50.986811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.23869ms","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-13T09:55:50.986849Z","caller":"traceutil/trace.go:171","msg":"trace[1507814040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"456.326194ms","start":"2026-03-13T09:55:50.530512Z","end":"2026-03-13T09:55:50.986838Z","steps":["trace[1507814040] 'agreement among raft nodes before linearized reading' (duration: 456.119988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:50.986879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.530495Z","time spent":"456.376575ms","remote":"127.0.0.1:46086","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-13T09:55:51.514636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.999622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:51.514848Z","caller":"traceutil/trace.go:171","msg":"trace[1723835682] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"559.95231ms","start":"2026-03-13T09:55:50.954866Z","end":"2026-03-13T09:55:51.514818Z","steps":["trace[1723835682] 'process raft request' (duration: 255.65913ms)","trace[1723835682] 'compare' (duration: 303.888149ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:51.514873Z","caller":"traceutil/trace.go:171","msg":"trace[583018536] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"522.436049ms","start":"2026-03-13T09:55:50.992405Z","end":"2026-03-13T09:55:51.514841Z","steps":["trace[583018536] 'process raft request' (duration: 522.358247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.514962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.992381Z","time spent":"522.532892ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:51.514965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.954849Z","time spent":"560.063314ms","remote":"127.0.0.1:45868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:51.515144Z","caller":"traceutil/trace.go:171","msg":"trace[1139103953] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"528.540323ms","start":"2026-03-13T09:55:50.986577Z","end":"2026-03-13T09:55:51.515117Z","steps":["trace[1139103953] 'read index received' (duration: 223.962245ms)","trace[1139103953] 'applied index is now lower than readState.Index' (duration: 304.575328ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:51.515257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.199092ms","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:530"} {"level":"info","ts":"2026-03-13T09:55:51.515307Z","caller":"traceutil/trace.go:171","msg":"trace[1972811186] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1376; }","duration":"687.278934ms","start":"2026-03-13T09:55:50.828015Z","end":"2026-03-13T09:55:51.515294Z","steps":["trace[1972811186] 'agreement among raft nodes before linearized reading' (duration: 687.146529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.515339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.827996Z","time spent":"687.336134ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T09:55:51.515415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.609921ms","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-13T09:55:51.515461Z","caller":"traceutil/trace.go:171","msg":"trace[136096304] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"785.714144ms","start":"2026-03-13T09:55:50.729731Z","end":"2026-03-13T09:55:51.515445Z","steps":["trace[136096304] 'agreement among raft nodes before linearized reading' (duration: 785.527969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.515461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.124778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T09:55:51.515499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.143264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:51.515519Z","caller":"traceutil/trace.go:171","msg":"trace[546915599] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1376; }","duration":"105.216891ms","start":"2026-03-13T09:55:51.410287Z","end":"2026-03-13T09:55:51.515504Z","steps":["trace[546915599] 'agreement among raft nodes before linearized reading' (duration: 105.078657ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:51.515556Z","caller":"traceutil/trace.go:171","msg":"trace[167426568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"137.231887ms","start":"2026-03-13T09:55:51.37831Z","end":"2026-03-13T09:55:51.515542Z","steps":["trace[167426568] 'agreement among raft nodes before linearized reading' (duration: 137.150475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:51.51551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:50.729688Z","time spent":"785.812026ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-13T09:55:51.515552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.532306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T09:55:51.515746Z","caller":"traceutil/trace.go:171","msg":"trace[6515686] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1376; }","duration":"225.917126ms","start":"2026-03-13T09:55:51.28981Z","end":"2026-03-13T09:55:51.515727Z","steps":["trace[6515686] 'agreement among raft nodes before linearized reading' (duration: 225.498315ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.438035Z","caller":"traceutil/trace.go:171","msg":"trace[949704172] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"160.376006ms","start":"2026-03-13T09:55:52.277635Z","end":"2026-03-13T09:55:52.438011Z","steps":["trace[949704172] 'read index received' (duration: 160.17209ms)","trace[949704172] 'applied index is now lower than readState.Index' (duration: 203.066µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:52.438171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.51672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T09:55:52.438253Z","caller":"traceutil/trace.go:171","msg":"trace[424853153] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"222.32298ms","start":"2026-03-13T09:55:52.215766Z","end":"2026-03-13T09:55:52.438089Z","steps":["trace[424853153] 'process raft request' (duration: 222.102054ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.438213Z","caller":"traceutil/trace.go:171","msg":"trace[1668461709] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1380; }","duration":"160.604051ms","start":"2026-03-13T09:55:52.277597Z","end":"2026-03-13T09:55:52.438201Z","steps":["trace[1668461709] 'agreement among raft nodes before linearized reading' (duration: 160.518918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:52.802321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.239282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:52.802458Z","caller":"traceutil/trace.go:171","msg":"trace[45495322] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"303.708855ms","start":"2026-03-13T09:55:52.498735Z","end":"2026-03-13T09:55:52.802444Z","steps":["trace[45495322] 'process raft request' (duration: 303.666234ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T09:55:52.802518Z","caller":"traceutil/trace.go:171","msg":"trace[1456144329] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"304.318992ms","start":"2026-03-13T09:55:52.498165Z","end":"2026-03-13T09:55:52.802484Z","steps":["trace[1456144329] 'process raft request' (duration: 82.837843ms)","trace[1456144329] 'compare' (duration: 221.128409ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:52.802562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:52.498711Z","time spent":"303.787047ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:52.802668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:52.49815Z","time spent":"304.429644ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T09:55:53.822307Z","caller":"traceutil/trace.go:171","msg":"trace[1289660239] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"303.54249ms","start":"2026-03-13T09:55:53.518741Z","end":"2026-03-13T09:55:53.822283Z","steps":["trace[1289660239] 'read index received' (duration: 303.431197ms)","trace[1289660239] 'applied index is now lower than readState.Index' (duration: 110.143µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T09:55:53.8224Z","caller":"traceutil/trace.go:171","msg":"trace[798193204] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"386.348643ms","start":"2026-03-13T09:55:53.435996Z","end":"2026-03-13T09:55:53.822345Z","steps":["trace[798193204] 'process raft request' (duration: 386.127937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.822582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.231749ms","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-13T09:55:53.822668Z","caller":"traceutil/trace.go:171","msg":"trace[470186642] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1384; }","duration":"255.373744ms","start":"2026-03-13T09:55:53.567272Z","end":"2026-03-13T09:55:53.822646Z","steps":["trace[470186642] 'agreement among raft nodes before linearized reading' (duration: 255.169109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.822713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:53.435978Z","time spent":"386.6019ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T09:55:53.822584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.804317ms","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-13T09:55:53.822808Z","caller":"traceutil/trace.go:171","msg":"trace[2071317441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1384; }","duration":"304.061444ms","start":"2026-03-13T09:55:53.518713Z","end":"2026-03-13T09:55:53.822775Z","steps":["trace[2071317441] 'agreement among raft nodes before linearized reading' (duration: 303.703464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T09:55:53.822876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:53.5187Z","time spent":"304.164468ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T09:55:54.376588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.151204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T09:55:54.37669Z","caller":"traceutil/trace.go:171","msg":"trace[587326852] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"548.257659ms","start":"2026-03-13T09:55:53.828413Z","end":"2026-03-13T09:55:54.376671Z","steps":["trace[587326852] 'process raft request' (duration: 358.94938ms)","trace[587326852] 'compare' (duration: 189.042661ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T09:55:54.37676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T09:55:53.828374Z","time spent":"548.34636ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}