{"level":"warn","ts":"2026-04-18T22:17:32.727702Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:17:32.727822Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T22:17:32.727912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:17:32.727927Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-18T22:17:32.727952Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:17:32.728779Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-18T22:17:32.728933Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T22:17:32.735673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.399623ms"} {"level":"info","ts":"2026-04-18T22:17:32.744722Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-18T22:17:32.744823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:17:32.744851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-18T22:17:32.744866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:17:32.74488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-18T22:17:32.744923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-18T22:17:32.757483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:17:32.759371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:17:32.760574Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T22:17:32.765373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:17:32.765579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:17:32.765717Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:17:32.765738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:17:32.765962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:17:32.768028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-18T22:17:32.768236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-18T22:17:32.771493Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:17:32.77187Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-18T22:17:32.771957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-18T22:17:32.772383Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:17:32.772447Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:17:33.74545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:17:33.745499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:17:33.745529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-18T22:17:33.745543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:17:33.74555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-18T22:17:33.745561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-18T22:17:33.74557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-18T22:17:33.746363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:17:33.746375Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:17:33.746352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:17:33.746333Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:17:33.74687Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:17:33.746884Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:17:33.747594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:17:33.747691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:17:33.747718Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:17:33.750321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-18T22:17:33.750637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-18T22:18:02.648754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.936355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:18:02.64888Z","caller":"traceutil/trace.go:171","msg":"trace[649605481] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"106.705278ms","start":"2026-04-18T22:18:02.542159Z","end":"2026-04-18T22:18:02.648864Z","steps":["trace[649605481] 'read index received' (duration: 107.663µs)","trace[649605481] 'applied index is now lower than readState.Index' (duration: 106.596665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:02.64897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.81234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:18:02.648994Z","caller":"traceutil/trace.go:171","msg":"trace[892807108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"106.892012ms","start":"2026-04-18T22:18:02.542095Z","end":"2026-04-18T22:18:02.648987Z","steps":["trace[892807108] 'agreement among raft nodes before linearized reading' (duration: 106.81693ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:02.649023Z","caller":"traceutil/trace.go:171","msg":"trace[1548364109] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"204.085552ms","start":"2026-04-18T22:18:02.444864Z","end":"2026-04-18T22:18:02.64895Z","steps":["trace[1548364109] 'process raft request' (duration: 36.541092ms)","trace[1548364109] 'compare' (duration: 166.735601ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:05.633898Z","caller":"traceutil/trace.go:171","msg":"trace[1228193001] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"115.325353ms","start":"2026-04-18T22:18:05.51855Z","end":"2026-04-18T22:18:05.633875Z","steps":["trace[1228193001] 'process raft request' (duration: 112.172968ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:05.644961Z","caller":"traceutil/trace.go:171","msg":"trace[672776749] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"112.580158ms","start":"2026-04-18T22:18:05.532357Z","end":"2026-04-18T22:18:05.644937Z","steps":["trace[672776749] 'process raft request' (duration: 112.306891ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:05.646956Z","caller":"traceutil/trace.go:171","msg":"trace[1580435735] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"106.284138ms","start":"2026-04-18T22:18:05.54066Z","end":"2026-04-18T22:18:05.646945Z","steps":["trace[1580435735] 'read index received' (duration: 90.146152ms)","trace[1580435735] 'applied index is now lower than readState.Index' (duration: 16.136876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:05.647099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.421531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:18:05.647316Z","caller":"traceutil/trace.go:171","msg":"trace[939082327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"106.704447ms","start":"2026-04-18T22:18:05.5406Z","end":"2026-04-18T22:18:05.647304Z","steps":["trace[939082327] 'agreement among raft nodes before linearized reading' (duration: 106.427891ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:12.025297Z","caller":"traceutil/trace.go:171","msg":"trace[1316649296] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"312.763736ms","start":"2026-04-18T22:18:11.712502Z","end":"2026-04-18T22:18:12.025266Z","steps":["trace[1316649296] 'process raft request' (duration: 312.52418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:12.025764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:11.71247Z","time spent":"312.92345ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:12.042811Z","caller":"traceutil/trace.go:171","msg":"trace[503973134] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"196.778769ms","start":"2026-04-18T22:18:11.846005Z","end":"2026-04-18T22:18:12.042784Z","steps":["trace[503973134] 'read index received' (duration: 178.989173ms)","trace[503973134] 'applied index is now lower than readState.Index' (duration: 17.788276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:12.043098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.056165ms","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-04-18T22:18:12.043179Z","caller":"traceutil/trace.go:171","msg":"trace[1441290281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"197.194509ms","start":"2026-04-18T22:18:11.845965Z","end":"2026-04-18T22:18:12.043159Z","steps":["trace[1441290281] 'agreement among raft nodes before linearized reading' (duration: 196.914552ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:13.01552Z","caller":"traceutil/trace.go:171","msg":"trace[573231888] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"249.65629ms","start":"2026-04-18T22:18:12.765828Z","end":"2026-04-18T22:18:13.015484Z","steps":["trace[573231888] 'process raft request' (duration: 249.442805ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:29.412229Z","caller":"traceutil/trace.go:171","msg":"trace[389339225] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"316.865205ms","start":"2026-04-18T22:18:29.09533Z","end":"2026-04-18T22:18:29.412195Z","steps":["trace[389339225] 'read index received' (duration: 257.497258ms)","trace[389339225] 'applied index is now lower than readState.Index' (duration: 59.366747ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:29.412288Z","caller":"traceutil/trace.go:171","msg":"trace[83960190] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"352.356912ms","start":"2026-04-18T22:18:29.059898Z","end":"2026-04-18T22:18:29.412255Z","steps":["trace[83960190] 'process raft request' (duration: 293.008826ms)","trace[83960190] 'compare' (duration: 59.153732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:29.412553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:29.059864Z","time spent":"352.578798ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:29.412564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.213563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-18T22:18:29.412633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.217304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:18:29.412653Z","caller":"traceutil/trace.go:171","msg":"trace[2076799676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"317.355366ms","start":"2026-04-18T22:18:29.095276Z","end":"2026-04-18T22:18:29.412631Z","steps":["trace[2076799676] 'agreement among raft nodes before linearized reading' (duration: 317.08675ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:29.412682Z","caller":"traceutil/trace.go:171","msg":"trace[139529547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"289.345557ms","start":"2026-04-18T22:18:29.123324Z","end":"2026-04-18T22:18:29.41267Z","steps":["trace[139529547] 'agreement among raft nodes before linearized reading' (duration: 289.135232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:29.412703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:29.095257Z","time spent":"317.436118ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:02.200422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.882388ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:02.200649Z","caller":"traceutil/trace.go:171","msg":"trace[935736946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:956; }","duration":"316.119123ms","start":"2026-04-18T22:19:01.884491Z","end":"2026-04-18T22:19:02.20061Z","steps":["trace[935736946] 'range keys from in-memory index tree' (duration: 315.856606ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:02.259567Z","caller":"traceutil/trace.go:171","msg":"trace[85704794] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"505.692822ms","start":"2026-04-18T22:19:01.753849Z","end":"2026-04-18T22:19:02.259541Z","steps":["trace[85704794] 'process raft request' (duration: 505.482027ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:02.259654Z","caller":"traceutil/trace.go:171","msg":"trace[1174364478] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"494.098645ms","start":"2026-04-18T22:19:01.765529Z","end":"2026-04-18T22:19:02.259628Z","steps":["trace[1174364478] 'read index received' (duration: 493.836998ms)","trace[1174364478] 'applied index is now lower than readState.Index' (duration: 259.577µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:02.259793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:01.753809Z","time spent":"505.827855ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:02.259885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.344361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:02.259928Z","caller":"traceutil/trace.go:171","msg":"trace[641854977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"494.411163ms","start":"2026-04-18T22:19:01.765505Z","end":"2026-04-18T22:19:02.259916Z","steps":["trace[641854977] 'agreement among raft nodes before linearized reading' (duration: 494.228248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:02.259967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:01.765494Z","time spent":"494.465174ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:02.673672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.287692ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T22:19:02.673913Z","caller":"traceutil/trace.go:171","msg":"trace[1538151369] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"414.190112ms","start":"2026-04-18T22:19:02.259706Z","end":"2026-04-18T22:19:02.673896Z","steps":["trace[1538151369] 'read index received' (duration: 229.747046ms)","trace[1538151369] 'applied index is now lower than readState.Index' (duration: 184.442346ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:02.674005Z","caller":"traceutil/trace.go:171","msg":"trace[2139538249] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"570.017794ms","start":"2026-04-18T22:19:02.10398Z","end":"2026-04-18T22:19:02.673998Z","steps":["trace[2139538249] 'process raft request' (duration: 569.784179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:02.67411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:02.103953Z","time spent":"570.095976ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:02.67412Z","caller":"traceutil/trace.go:171","msg":"trace[366308911] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"410.531153ms","start":"2026-04-18T22:19:02.263556Z","end":"2026-04-18T22:19:02.674087Z","steps":["trace[366308911] 'process raft request' (duration: 410.310068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:02.674285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:02.263527Z","time spent":"410.667807ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:02.67435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.507361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:19:02.67441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.716211ms","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-04-18T22:19:02.674695Z","caller":"traceutil/trace.go:171","msg":"trace[7398168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"307.037458ms","start":"2026-04-18T22:19:02.367645Z","end":"2026-04-18T22:19:02.674683Z","steps":["trace[7398168] 'agreement among raft nodes before linearized reading' (duration: 306.656858ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:02.674661Z","caller":"traceutil/trace.go:171","msg":"trace[2052979237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"887.808319ms","start":"2026-04-18T22:19:01.786801Z","end":"2026-04-18T22:19:02.674609Z","steps":["trace[2052979237] 'agreement among raft nodes before linearized reading' (duration: 887.280905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:02.674735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:02.367626Z","time spent":"307.101309ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:19:02.674461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.052473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-18T22:19:02.67478Z","caller":"traceutil/trace.go:171","msg":"trace[440759488] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:959; }","duration":"671.37447ms","start":"2026-04-18T22:19:02.003399Z","end":"2026-04-18T22:19:02.674774Z","steps":["trace[440759488] 'agreement among raft nodes before linearized reading' (duration: 671.009612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:02.674798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:02.003333Z","time spent":"671.460203ms","remote":"127.0.0.1:33328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-04-18T22:19:02.674798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:01.786775Z","time spent":"887.975782ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:19:03.065409Z","caller":"traceutil/trace.go:171","msg":"trace[1467031269] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"177.181233ms","start":"2026-04-18T22:19:02.88819Z","end":"2026-04-18T22:19:03.065371Z","steps":["trace[1467031269] 'process raft request' (duration: 96.525571ms)","trace[1467031269] 'compare' (duration: 80.473768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:04.437922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392781677613350,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:19:04.938254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392781677613350,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:19:05.083249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.324069065s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:19:05.083834Z","caller":"traceutil/trace.go:171","msg":"trace[315753736] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.324755752s","start":"2026-04-18T22:19:03.759047Z","end":"2026-04-18T22:19:05.083802Z","steps":["trace[315753736] 'process raft request' (duration: 1.324552137s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.084016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:03.759027Z","time spent":"1.324875714s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:05.100396Z","caller":"traceutil/trace.go:171","msg":"trace[1285006315] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"1.163366286s","start":"2026-04-18T22:19:03.937015Z","end":"2026-04-18T22:19:05.100382Z","steps":["trace[1285006315] 'read index received' (duration: 1.146561216s)","trace[1285006315] 'applied index is now lower than readState.Index' (duration: 16.80412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:05.100605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16355745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:19:05.100702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.657189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:19:05.100708Z","caller":"traceutil/trace.go:171","msg":"trace[348030443] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:967; }","duration":"1.163738865s","start":"2026-04-18T22:19:03.936946Z","end":"2026-04-18T22:19:05.100684Z","steps":["trace[348030443] 'agreement among raft nodes before linearized reading' (duration: 1.163558821s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:05.100736Z","caller":"traceutil/trace.go:171","msg":"trace[1860900490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"836.737371ms","start":"2026-04-18T22:19:04.263988Z","end":"2026-04-18T22:19:05.100725Z","steps":["trace[1860900490] 'agreement among raft nodes before linearized reading' (duration: 836.607668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.100762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.263956Z","time spent":"836.801533ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:05.100767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:03.936912Z","time spent":"1.163839407s","remote":"127.0.0.1:33430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:19:05.100917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.175329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:19:05.100936Z","caller":"traceutil/trace.go:171","msg":"trace[1728906057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"330.21663ms","start":"2026-04-18T22:19:04.770714Z","end":"2026-04-18T22:19:05.100931Z","steps":["trace[1728906057] 'agreement among raft nodes before linearized reading' (duration: 330.162799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.100953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.770703Z","time spent":"330.246231ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:19:05.101061Z","caller":"traceutil/trace.go:171","msg":"trace[1049029018] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"420.289116ms","start":"2026-04-18T22:19:04.680726Z","end":"2026-04-18T22:19:05.101015Z","steps":["trace[1049029018] 'process raft request' (duration: 419.573458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.101086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.913955ms","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-04-18T22:19:05.101108Z","caller":"traceutil/trace.go:171","msg":"trace[2116305912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"367.002656ms","start":"2026-04-18T22:19:04.7341Z","end":"2026-04-18T22:19:05.101102Z","steps":["trace[2116305912] 'agreement among raft nodes before linearized reading' (duration: 366.922454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.101126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.734076Z","time spent":"367.046298ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:19:05.101174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.680702Z","time spent":"420.392029ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:05.101241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.10497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:05.10127Z","caller":"traceutil/trace.go:171","msg":"trace[1211852610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"422.134391ms","start":"2026-04-18T22:19:04.679128Z","end":"2026-04-18T22:19:05.101263Z","steps":["trace[1211852610] 'agreement among raft nodes before linearized reading' (duration: 422.062519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.101295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.679042Z","time spent":"422.247684ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:05.101299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.253894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:05.101384Z","caller":"traceutil/trace.go:171","msg":"trace[250960248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"558.402117ms","start":"2026-04-18T22:19:04.542962Z","end":"2026-04-18T22:19:05.101365Z","steps":["trace[250960248] 'agreement among raft nodes before linearized reading' (duration: 558.212233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:05.101432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:04.542938Z","time spent":"558.48305ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:19:05.224746Z","caller":"traceutil/trace.go:171","msg":"trace[289204463] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"117.142552ms","start":"2026-04-18T22:19:05.107572Z","end":"2026-04-18T22:19:05.224714Z","steps":["trace[289204463] 'process raft request' (duration: 117.109131ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:05.224751Z","caller":"traceutil/trace.go:171","msg":"trace[44205258] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"118.557415ms","start":"2026-04-18T22:19:05.106152Z","end":"2026-04-18T22:19:05.224709Z","steps":["trace[44205258] 'process raft request' (duration: 118.376491ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:05.224805Z","caller":"traceutil/trace.go:171","msg":"trace[1622659260] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"117.545651ms","start":"2026-04-18T22:19:05.107214Z","end":"2026-04-18T22:19:05.22476Z","steps":["trace[1622659260] 'process raft request' (duration: 117.442599ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.465116Z","caller":"traceutil/trace.go:171","msg":"trace[408157308] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"714.052938ms","start":"2026-04-18T22:19:06.751025Z","end":"2026-04-18T22:19:07.465078Z","steps":["trace[408157308] 'process raft request' (duration: 711.827025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.4653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:06.751006Z","time spent":"714.198861ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:07.492414Z","caller":"traceutil/trace.go:171","msg":"trace[2059590595] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"404.064149ms","start":"2026-04-18T22:19:07.088329Z","end":"2026-04-18T22:19:07.492393Z","steps":["trace[2059590595] 'read index received' (duration: 374.537756ms)","trace[2059590595] 'applied index is now lower than readState.Index' (duration: 29.525543ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:07.492713Z","caller":"traceutil/trace.go:171","msg":"trace[1758486917] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"432.375954ms","start":"2026-04-18T22:19:07.060297Z","end":"2026-04-18T22:19:07.492673Z","steps":["trace[1758486917] 'process raft request' (duration: 431.947914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.492843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.490551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-18T22:19:07.492956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:07.060264Z","time spent":"432.552719ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:07.492963Z","caller":"traceutil/trace.go:171","msg":"trace[1198520476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"404.667685ms","start":"2026-04-18T22:19:07.088277Z","end":"2026-04-18T22:19:07.492945Z","steps":["trace[1198520476] 'agreement among raft nodes before linearized reading' (duration: 404.345956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.493003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.842438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:07.493032Z","caller":"traceutil/trace.go:171","msg":"trace[920514203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"263.8718ms","start":"2026-04-18T22:19:07.229147Z","end":"2026-04-18T22:19:07.493019Z","steps":["trace[920514203] 'agreement among raft nodes before linearized reading' (duration: 263.823789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.493019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:07.088257Z","time spent":"404.755387ms","remote":"127.0.0.1:33394","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-04-18T22:19:07.493137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.000732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:19:07.493151Z","caller":"traceutil/trace.go:171","msg":"trace[1695427545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"264.028183ms","start":"2026-04-18T22:19:07.229119Z","end":"2026-04-18T22:19:07.493147Z","steps":["trace[1695427545] 'agreement among raft nodes before linearized reading' (duration: 263.988402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.49277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.582627ms","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-04-18T22:19:07.493194Z","caller":"traceutil/trace.go:171","msg":"trace[817284391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"302.07658ms","start":"2026-04-18T22:19:07.191102Z","end":"2026-04-18T22:19:07.493179Z","steps":["trace[817284391] 'agreement among raft nodes before linearized reading' (duration: 301.554996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.493264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:07.191074Z","time spent":"302.175242ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:19:07.493228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.520615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:19:07.493343Z","caller":"traceutil/trace.go:171","msg":"trace[2079016197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"264.653247ms","start":"2026-04-18T22:19:07.228684Z","end":"2026-04-18T22:19:07.493338Z","steps":["trace[2079016197] 'agreement among raft nodes before linearized reading' (duration: 264.502904ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.493376Z","caller":"traceutil/trace.go:171","msg":"trace[1920120335] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"386.604614ms","start":"2026-04-18T22:19:07.106758Z","end":"2026-04-18T22:19:07.493362Z","steps":["trace[1920120335] 'process raft request' (duration: 385.609289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.493448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:07.106744Z","time spent":"386.665595ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:07.656776Z","caller":"traceutil/trace.go:171","msg":"trace[1117150517] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"159.01765ms","start":"2026-04-18T22:19:07.497728Z","end":"2026-04-18T22:19:07.656746Z","steps":["trace[1117150517] 'process raft request' (duration: 152.764061ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.659202Z","caller":"traceutil/trace.go:171","msg":"trace[306135184] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"161.155651ms","start":"2026-04-18T22:19:07.498036Z","end":"2026-04-18T22:19:07.659192Z","steps":["trace[306135184] 'process raft request' (duration: 161.081719ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.659345Z","caller":"traceutil/trace.go:171","msg":"trace[1969490653] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"161.264564ms","start":"2026-04-18T22:19:07.497981Z","end":"2026-04-18T22:19:07.659245Z","steps":["trace[1969490653] 'process raft request' (duration: 161.07916ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.659366Z","caller":"traceutil/trace.go:171","msg":"trace[1384574183] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1011; }","duration":"161.168831ms","start":"2026-04-18T22:19:07.498172Z","end":"2026-04-18T22:19:07.659341Z","steps":["trace[1384574183] 'read index received' (duration: 152.498655ms)","trace[1384574183] 'applied index is now lower than readState.Index' (duration: 8.669006ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:07.659407Z","caller":"traceutil/trace.go:171","msg":"trace[344633617] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"161.353465ms","start":"2026-04-18T22:19:07.49804Z","end":"2026-04-18T22:19:07.659393Z","steps":["trace[344633617] 'process raft request' (duration: 161.12188ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:07.659581Z","caller":"traceutil/trace.go:171","msg":"trace[1049209381] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"160.543546ms","start":"2026-04-18T22:19:07.49903Z","end":"2026-04-18T22:19:07.659574Z","steps":["trace[1049209381] 'process raft request' (duration: 160.226518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.659669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.971848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:07.659708Z","caller":"traceutil/trace.go:171","msg":"trace[1040141531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"117.06099ms","start":"2026-04-18T22:19:07.542635Z","end":"2026-04-18T22:19:07.659696Z","steps":["trace[1040141531] 'agreement among raft nodes before linearized reading' (duration: 116.955368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:07.659727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.520839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-18T22:19:07.659805Z","caller":"traceutil/trace.go:171","msg":"trace[993401079] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:979; }","duration":"161.640333ms","start":"2026-04-18T22:19:07.498146Z","end":"2026-04-18T22:19:07.659786Z","steps":["trace[993401079] 'agreement among raft nodes before linearized reading' (duration: 161.336115ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.906004Z","caller":"traceutil/trace.go:171","msg":"trace[1621548302] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"234.447817ms","start":"2026-04-18T22:19:09.671534Z","end":"2026-04-18T22:19:09.905982Z","steps":["trace[1621548302] 'process raft request' (duration: 234.423087ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.906276Z","caller":"traceutil/trace.go:171","msg":"trace[1460639220] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"236.359892ms","start":"2026-04-18T22:19:09.669911Z","end":"2026-04-18T22:19:09.906271Z","steps":["trace[1460639220] 'process raft request' (duration: 235.87403ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.90635Z","caller":"traceutil/trace.go:171","msg":"trace[789083272] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"235.041881ms","start":"2026-04-18T22:19:09.671304Z","end":"2026-04-18T22:19:09.906346Z","steps":["trace[789083272] 'process raft request' (duration: 234.60382ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:09.906413Z","caller":"traceutil/trace.go:171","msg":"trace[612470915] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"235.085182ms","start":"2026-04-18T22:19:09.671323Z","end":"2026-04-18T22:19:09.906408Z","steps":["trace[612470915] 'process raft request' (duration: 234.618261ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:16.072748Z","caller":"traceutil/trace.go:171","msg":"trace[1430044240] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"126.928615ms","start":"2026-04-18T22:19:15.945787Z","end":"2026-04-18T22:19:16.072715Z","steps":["trace[1430044240] 'process raft request' (duration: 126.739651ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:16.216104Z","caller":"traceutil/trace.go:171","msg":"trace[1832410651] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"269.880651ms","start":"2026-04-18T22:19:15.946187Z","end":"2026-04-18T22:19:16.216068Z","steps":["trace[1832410651] 'process raft request' (duration: 244.639609ms)","trace[1832410651] 'compare' (duration: 25.033127ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:16.216459Z","caller":"traceutil/trace.go:171","msg":"trace[2067444415] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"270.092797ms","start":"2026-04-18T22:19:15.946354Z","end":"2026-04-18T22:19:16.216447Z","steps":["trace[2067444415] 'process raft request' (duration: 269.675886ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:16.216403Z","caller":"traceutil/trace.go:171","msg":"trace[2045042273] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"268.039688ms","start":"2026-04-18T22:19:15.948331Z","end":"2026-04-18T22:19:16.216371Z","steps":["trace[2045042273] 'process raft request' (duration: 267.930546ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:18.327138Z","caller":"traceutil/trace.go:171","msg":"trace[147515523] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"102.663517ms","start":"2026-04-18T22:19:18.224449Z","end":"2026-04-18T22:19:18.327112Z","steps":["trace[147515523] 'process raft request' (duration: 70.4897ms)","trace[147515523] 'compare' (duration: 31.700666ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:18.327145Z","caller":"traceutil/trace.go:171","msg":"trace[1478618631] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"102.609516ms","start":"2026-04-18T22:19:18.224508Z","end":"2026-04-18T22:19:18.327117Z","steps":["trace[1478618631] 'process raft request' (duration: 102.512393ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:19.750643Z","caller":"traceutil/trace.go:171","msg":"trace[1364825394] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"166.625201ms","start":"2026-04-18T22:19:19.583982Z","end":"2026-04-18T22:19:19.750607Z","steps":["trace[1364825394] 'process raft request' (duration: 166.410016ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:26.526519Z","caller":"traceutil/trace.go:171","msg":"trace[927073837] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"112.136873ms","start":"2026-04-18T22:19:26.414342Z","end":"2026-04-18T22:19:26.526478Z","steps":["trace[927073837] 'process raft request' (duration: 111.852196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:30.869015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392781677613558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:19:31.370116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392781677613558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:19:31.81381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.516740167s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:19:31.814439Z","caller":"traceutil/trace.go:171","msg":"trace[1601829821] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.517552297s","start":"2026-04-18T22:19:30.296857Z","end":"2026-04-18T22:19:31.814409Z","steps":["trace[1601829821] 'process raft request' (duration: 1.517172627s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:31.814693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.296838Z","time spent":"1.51768789s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:32.054766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:32.054932Z","caller":"traceutil/trace.go:171","msg":"trace[1470418835] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.429343585s","start":"2026-04-18T22:19:30.625537Z","end":"2026-04-18T22:19:32.054881Z","steps":["trace[1470418835] 'process raft request' (duration: 1.322403526s)","trace[1470418835] 'compare' (duration: 106.58861ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:32.055068Z","caller":"traceutil/trace.go:171","msg":"trace[1943200932] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"184.285101ms","start":"2026-04-18T22:19:31.870751Z","end":"2026-04-18T22:19:32.055036Z","steps":["trace[1943200932] 'process raft request' (duration: 184.138988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.055165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.625498Z","time spent":"1.429542398s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:32.055096Z","caller":"traceutil/trace.go:171","msg":"trace[1928510425] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1088; }","duration":"1.686466733s","start":"2026-04-18T22:19:30.368541Z","end":"2026-04-18T22:19:32.055008Z","steps":["trace[1928510425] 'read index received' (duration: 1.445584072s)","trace[1928510425] 'applied index is now lower than readState.Index' (duration: 240.87876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:32.543738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000429114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-18T22:19:32.543865Z","caller":"traceutil/trace.go:171","msg":"trace[112744372] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000627699s","start":"2026-04-18T22:19:30.543209Z","end":"2026-04-18T22:19:32.543837Z","steps":["trace[112744372] 'agreement among raft nodes before linearized reading' (duration: 2.000468375s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.543932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.543184Z","time spent":"2.000735502s","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/18 22:19:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-18T22:19:32.608662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.695446ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T22:19:32.608832Z","caller":"traceutil/trace.go:171","msg":"trace[288582274] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"553.362277ms","start":"2026-04-18T22:19:32.055445Z","end":"2026-04-18T22:19:32.608808Z","steps":["trace[288582274] 'read index received' (duration: 104.862µs)","trace[288582274] 'applied index is now lower than readState.Index' (duration: 553.255315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:32.608853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240290791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:19:32.608984Z","caller":"traceutil/trace.go:171","msg":"trace[1135890519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"2.240477546s","start":"2026-04-18T22:19:30.368484Z","end":"2026-04-18T22:19:32.608962Z","steps":["trace[1135890519] 'agreement among raft nodes before linearized reading' (duration: 1.686953465s)","trace[1135890519] 'range keys from in-memory index tree' (duration: 553.214794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:32.60902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.897698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:19:32.609061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.368395Z","time spent":"2.24065434s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:19:32.60918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063437116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-18T22:19:32.609255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.902173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:19:32.609281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.037994489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:32.609277Z","caller":"traceutil/trace.go:171","msg":"trace[850775333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"2.06359255s","start":"2026-04-18T22:19:30.545662Z","end":"2026-04-18T22:19:32.609254Z","steps":["trace[850775333] 'agreement among raft nodes before linearized reading' (duration: 2.063302333s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:32.609314Z","caller":"traceutil/trace.go:171","msg":"trace[966531703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"2.03802781s","start":"2026-04-18T22:19:30.571278Z","end":"2026-04-18T22:19:32.609306Z","steps":["trace[966531703] 'agreement among raft nodes before linearized reading' (duration: 2.037857706s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.609331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.545641Z","time spent":"2.063681001s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:19:32.60936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:30.571203Z","time spent":"2.038148733s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-18T22:19:32.609326Z","caller":"traceutil/trace.go:171","msg":"trace[1022631432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"705.981495ms","start":"2026-04-18T22:19:31.903322Z","end":"2026-04-18T22:19:32.609304Z","steps":["trace[1022631432] 'agreement among raft nodes before linearized reading' (duration: 705.796711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.609423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085646103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:19:32.609067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402561516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:32.609481Z","caller":"traceutil/trace.go:171","msg":"trace[957088642] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1050; }","duration":"1.085757426s","start":"2026-04-18T22:19:31.52371Z","end":"2026-04-18T22:19:32.609467Z","steps":["trace[957088642] 'agreement among raft nodes before linearized reading' (duration: 1.085647663s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:32.609496Z","caller":"traceutil/trace.go:171","msg":"trace[390322033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1050; }","duration":"1.403076428s","start":"2026-04-18T22:19:31.206405Z","end":"2026-04-18T22:19:32.609481Z","steps":["trace[390322033] 'agreement among raft nodes before linearized reading' (duration: 1.402549156s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.60953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:31.523683Z","time spent":"1.085833617s","remote":"127.0.0.1:57288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:19:32.609539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:31.206379Z","time spent":"1.40314307s","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:19:32.609519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.972403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:19:32.609506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:31.903309Z","time spent":"706.15595ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:19:32.609619Z","caller":"traceutil/trace.go:171","msg":"trace[629824691] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1050; }","duration":"721.146327ms","start":"2026-04-18T22:19:31.888451Z","end":"2026-04-18T22:19:32.609598Z","steps":["trace[629824691] 'agreement among raft nodes before linearized reading' (duration: 720.86607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.609674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:31.888438Z","time spent":"721.225468ms","remote":"127.0.0.1:33664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":93,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:19:32.6091Z","caller":"traceutil/trace.go:171","msg":"trace[1916414867] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1050; }","duration":"608.088283ms","start":"2026-04-18T22:19:32.000999Z","end":"2026-04-18T22:19:32.609087Z","steps":["trace[1916414867] 'agreement among raft nodes before linearized reading' (duration: 607.95302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:32.609975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.000967Z","time spent":"608.989864ms","remote":"127.0.0.1:33606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:19:33.334243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.615199Z","time spent":"719.034227ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-18T22:19:33.334371Z","caller":"traceutil/trace.go:171","msg":"trace[102767726] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1092; }","duration":"715.387219ms","start":"2026-04-18T22:19:32.618955Z","end":"2026-04-18T22:19:33.334342Z","steps":["trace[102767726] 'read index received' (duration: 50.482624ms)","trace[102767726] 'applied index is now lower than readState.Index' (duration: 664.903655ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:33.334654Z","caller":"traceutil/trace.go:171","msg":"trace[1535518389] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"719.18401ms","start":"2026-04-18T22:19:32.615425Z","end":"2026-04-18T22:19:33.334609Z","steps":["trace[1535518389] 'process raft request' (duration: 718.881352ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:33.334754Z","caller":"traceutil/trace.go:171","msg":"trace[716594790] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"719.281142ms","start":"2026-04-18T22:19:32.615423Z","end":"2026-04-18T22:19:33.334705Z","steps":["trace[716594790] 'process raft request' (duration: 718.847012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:33.334854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.876131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:33.334905Z","caller":"traceutil/trace.go:171","msg":"trace[89600870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"715.974963ms","start":"2026-04-18T22:19:32.618919Z","end":"2026-04-18T22:19:33.334894Z","steps":["trace[89600870] 'agreement among raft nodes before linearized reading' (duration: 715.877861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:33.334967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.61891Z","time spent":"716.047855ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:19:33.334918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.615392Z","time spent":"719.392685ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:33.33503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.615417Z","time spent":"719.444967ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:33.335017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.523708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:19:33.335166Z","caller":"traceutil/trace.go:171","msg":"trace[1969300565] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1054; }","duration":"274.003669ms","start":"2026-04-18T22:19:33.061147Z","end":"2026-04-18T22:19:33.335151Z","steps":["trace[1969300565] 'agreement among raft nodes before linearized reading' (duration: 273.503788ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:33.334667Z","caller":"traceutil/trace.go:171","msg":"trace[859640439] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"719.295012ms","start":"2026-04-18T22:19:32.615346Z","end":"2026-04-18T22:19:33.334641Z","steps":["trace[859640439] 'process raft request' (duration: 718.79108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:33.335397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:32.615335Z","time spent":"720.02039ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:33.67811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.333206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:33.678488Z","caller":"traceutil/trace.go:171","msg":"trace[1352625895] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"341.697613ms","start":"2026-04-18T22:19:33.336581Z","end":"2026-04-18T22:19:33.678278Z","steps":["trace[1352625895] 'process raft request' (duration: 78.069661ms)","trace[1352625895] 'compare' (duration: 263.178892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:33.678723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:33.336553Z","time spent":"342.090283ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:19:33.934373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.175615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:19:33.934569Z","caller":"traceutil/trace.go:171","msg":"trace[510140997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"115.444311ms","start":"2026-04-18T22:19:33.819094Z","end":"2026-04-18T22:19:33.934539Z","steps":["trace[510140997] 'range keys from in-memory index tree' (duration: 114.823857ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:34.074706Z","caller":"traceutil/trace.go:171","msg":"trace[1452256513] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"135.888209ms","start":"2026-04-18T22:19:33.93878Z","end":"2026-04-18T22:19:34.074668Z","steps":["trace[1452256513] 'process raft request' (duration: 135.644773ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:35.502678Z","caller":"traceutil/trace.go:171","msg":"trace[1180189044] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"163.083727ms","start":"2026-04-18T22:19:35.339566Z","end":"2026-04-18T22:19:35.50265Z","steps":["trace[1180189044] 'read index received' (duration: 162.907663ms)","trace[1180189044] 'applied index is now lower than readState.Index' (duration: 174.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:35.502803Z","caller":"traceutil/trace.go:171","msg":"trace[545097779] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"824.543941ms","start":"2026-04-18T22:19:34.678247Z","end":"2026-04-18T22:19:35.502791Z","steps":["trace[545097779] 'process raft request' (duration: 824.260424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:35.503281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.923033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:19:35.503365Z","caller":"traceutil/trace.go:171","msg":"trace[325294999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"163.072106ms","start":"2026-04-18T22:19:35.340281Z","end":"2026-04-18T22:19:35.503353Z","steps":["trace[325294999] 'agreement among raft nodes before linearized reading' (duration: 162.82186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:35.503388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.800723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:19:35.503487Z","caller":"traceutil/trace.go:171","msg":"trace[315147773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"163.963707ms","start":"2026-04-18T22:19:35.339501Z","end":"2026-04-18T22:19:35.503465Z","steps":["trace[315147773] 'agreement among raft nodes before linearized reading' (duration: 163.638689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:35.503532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.686337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:19:35.503616Z","caller":"traceutil/trace.go:171","msg":"trace[1768483215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"162.836771ms","start":"2026-04-18T22:19:35.340765Z","end":"2026-04-18T22:19:35.503602Z","steps":["trace[1768483215] 'agreement among raft nodes before linearized reading' (duration: 162.635786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:35.502936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:34.678225Z","time spent":"824.604942ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:36.580357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392781677613585,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:19:36.841775Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.334038723s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:19:36.842633Z","caller":"traceutil/trace.go:171","msg":"trace[1038321065] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.334930234s","start":"2026-04-18T22:19:35.507644Z","end":"2026-04-18T22:19:36.842574Z","steps":["trace[1038321065] 'process raft request' (duration: 1.334636487s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:36.842887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:35.507622Z","time spent":"1.335100147s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:37.149593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.981056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:37.14983Z","caller":"traceutil/trace.go:171","msg":"trace[873073527] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1101; }","duration":"1.070049031s","start":"2026-04-18T22:19:36.07976Z","end":"2026-04-18T22:19:37.149809Z","steps":["trace[873073527] 'read index received' (duration: 762.42342ms)","trace[873073527] 'applied index is now lower than readState.Index' (duration: 307.624651ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:37.149939Z","caller":"traceutil/trace.go:171","msg":"trace[1984799889] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.018305988s","start":"2026-04-18T22:19:36.131622Z","end":"2026-04-18T22:19:37.149928Z","steps":["trace[1984799889] 'process raft request' (duration: 1.018145324s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:37.149898Z","caller":"traceutil/trace.go:171","msg":"trace[1943735234] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.640192509s","start":"2026-04-18T22:19:35.50966Z","end":"2026-04-18T22:19:37.149852Z","steps":["trace[1943735234] 'process raft request' (duration: 1.640047936s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:37.149958Z","caller":"traceutil/trace.go:171","msg":"trace[1685208091] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.640405214s","start":"2026-04-18T22:19:35.50954Z","end":"2026-04-18T22:19:37.149945Z","steps":["trace[1685208091] 'process raft request' (duration: 1.447963717s)","trace[1685208091] 'compare' (duration: 191.853853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:37.150007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:36.131592Z","time spent":"1.018379349s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:37.150049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:35.509523Z","time spent":"1.640455575s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:37.150051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:35.509571Z","time spent":"1.640407624s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:37.150129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070366199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:19:37.150192Z","caller":"traceutil/trace.go:171","msg":"trace[933953815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"1.070459722s","start":"2026-04-18T22:19:36.079715Z","end":"2026-04-18T22:19:37.150175Z","steps":["trace[933953815] 'agreement among raft nodes before linearized reading' (duration: 1.070258437s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:37.150246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:36.079689Z","time spent":"1.070544173s","remote":"127.0.0.1:33394","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-04-18T22:19:37.150562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.949913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T22:19:37.150607Z","caller":"traceutil/trace.go:171","msg":"trace[200420152] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1062; }","duration":"434.045285ms","start":"2026-04-18T22:19:36.716551Z","end":"2026-04-18T22:19:37.150596Z","steps":["trace[200420152] 'agreement among raft nodes before linearized reading' (duration: 433.898951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:37.150636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:36.716529Z","time spent":"434.101116ms","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-04-18T22:19:37.150788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.906902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:37.151103Z","caller":"traceutil/trace.go:171","msg":"trace[1681470954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"609.405315ms","start":"2026-04-18T22:19:36.541603Z","end":"2026-04-18T22:19:37.151009Z","steps":["trace[1681470954] 'agreement among raft nodes before linearized reading' (duration: 608.885521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:37.151415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:36.541576Z","time spent":"609.821984ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:19:37.417103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.886444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:37.417342Z","caller":"traceutil/trace.go:171","msg":"trace[32658486] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"239.043208ms","start":"2026-04-18T22:19:37.178278Z","end":"2026-04-18T22:19:37.417321Z","steps":["trace[32658486] 'process raft request' (duration: 238.965776ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:37.41741Z","caller":"traceutil/trace.go:171","msg":"trace[1670643768] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"263.04788ms","start":"2026-04-18T22:19:37.154291Z","end":"2026-04-18T22:19:37.417339Z","steps":["trace[1670643768] 'process raft request' (duration: 114.798896ms)","trace[1670643768] 'compare' (duration: 147.69421ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:37.636655Z","caller":"traceutil/trace.go:171","msg":"trace[1462261514] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"124.252181ms","start":"2026-04-18T22:19:37.512369Z","end":"2026-04-18T22:19:37.636621Z","steps":["trace[1462261514] 'process raft request' (duration: 124.045386ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:37.884534Z","caller":"traceutil/trace.go:171","msg":"trace[383311059] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"157.935304ms","start":"2026-04-18T22:19:37.726557Z","end":"2026-04-18T22:19:37.884492Z","steps":["trace[383311059] 'process raft request' (duration: 111.452067ms)","trace[383311059] 'compare' (duration: 46.308973ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:39.661275Z","caller":"traceutil/trace.go:171","msg":"trace[1683920930] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"505.59553ms","start":"2026-04-18T22:19:39.155656Z","end":"2026-04-18T22:19:39.661251Z","steps":["trace[1683920930] 'process raft request' (duration: 505.432546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:39.661464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.155617Z","time spent":"505.737273ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:39.942808Z","caller":"traceutil/trace.go:171","msg":"trace[1532413887] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1111; }","duration":"521.062808ms","start":"2026-04-18T22:19:39.421714Z","end":"2026-04-18T22:19:39.942776Z","steps":["trace[1532413887] 'read index received' (duration: 239.393486ms)","trace[1532413887] 'applied index is now lower than readState.Index' (duration: 281.668272ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:39.942879Z","caller":"traceutil/trace.go:171","msg":"trace[2131015739] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"783.555014ms","start":"2026-04-18T22:19:39.159289Z","end":"2026-04-18T22:19:39.942844Z","steps":["trace[2131015739] 'process raft request' (duration: 783.202136ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:39.942977Z","caller":"traceutil/trace.go:171","msg":"trace[632199244] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"781.499925ms","start":"2026-04-18T22:19:39.161431Z","end":"2026-04-18T22:19:39.942931Z","steps":["trace[632199244] 'process raft request' (duration: 781.29904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:39.943134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.392726ms","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-04-18T22:19:39.943173Z","caller":"traceutil/trace.go:171","msg":"trace[1048184376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"521.511879ms","start":"2026-04-18T22:19:39.421649Z","end":"2026-04-18T22:19:39.943161Z","steps":["trace[1048184376] 'agreement among raft nodes before linearized reading' (duration: 521.305064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:39.943204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.247913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-18T22:19:39.943226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.421627Z","time spent":"521.58479ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T22:19:39.943242Z","caller":"traceutil/trace.go:171","msg":"trace[2052911983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"302.325855ms","start":"2026-04-18T22:19:39.640903Z","end":"2026-04-18T22:19:39.943229Z","steps":["trace[2052911983] 'agreement among raft nodes before linearized reading' (duration: 302.177881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:39.943275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.640878Z","time spent":"302.390986ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:19:39.943048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.308751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:39.943333Z","caller":"traceutil/trace.go:171","msg":"trace[211916809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"400.649998ms","start":"2026-04-18T22:19:39.542662Z","end":"2026-04-18T22:19:39.943312Z","steps":["trace[211916809] 'agreement among raft nodes before linearized reading' (duration: 400.30913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:39.943366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.542628Z","time spent":"400.731001ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:19:39.943276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.161424Z","time spent":"781.67647ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:39.943052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.159274Z","time spent":"783.689587ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:40.403103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.23593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:40.403365Z","caller":"traceutil/trace.go:171","msg":"trace[691573179] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"454.682537ms","start":"2026-04-18T22:19:39.948661Z","end":"2026-04-18T22:19:40.403343Z","steps":["trace[691573179] 'process raft request' (duration: 454.572723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:40.403453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.948641Z","time spent":"454.773748ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:40.40346Z","caller":"traceutil/trace.go:171","msg":"trace[518721093] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"455.23949ms","start":"2026-04-18T22:19:39.948158Z","end":"2026-04-18T22:19:40.403398Z","steps":["trace[518721093] 'process raft request' (duration: 108.603039ms)","trace[518721093] 'compare' (duration: 345.892663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:40.403646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:39.948138Z","time spent":"455.409884ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:19:41.416381Z","caller":"traceutil/trace.go:171","msg":"trace[911620944] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"486.023202ms","start":"2026-04-18T22:19:40.930327Z","end":"2026-04-18T22:19:41.41635Z","steps":["trace[911620944] 'read index received' (duration: 485.821058ms)","trace[911620944] 'applied index is now lower than readState.Index' (duration: 200.644µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:41.416541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.192227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:41.416578Z","caller":"traceutil/trace.go:171","msg":"trace[2098920045] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1073; }","duration":"486.287628ms","start":"2026-04-18T22:19:40.930279Z","end":"2026-04-18T22:19:41.416567Z","steps":["trace[2098920045] 'agreement among raft nodes before linearized reading' (duration: 486.189376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:41.416617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:40.930264Z","time spent":"486.342019ms","remote":"127.0.0.1:37340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:19:41.416754Z","caller":"traceutil/trace.go:171","msg":"trace[1964731565] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"526.353814ms","start":"2026-04-18T22:19:40.89036Z","end":"2026-04-18T22:19:41.416714Z","steps":["trace[1964731565] 'process raft request' (duration: 525.76764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:41.416945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:40.890327Z","time spent":"526.521158ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:41.744096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.684196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:41.744215Z","caller":"traceutil/trace.go:171","msg":"trace[474586075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"201.879371ms","start":"2026-04-18T22:19:41.542311Z","end":"2026-04-18T22:19:41.74419Z","steps":["trace[474586075] 'range keys from in-memory index tree' (duration: 201.488862ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:45.120189Z","caller":"traceutil/trace.go:171","msg":"trace[906379464] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"577.467312ms","start":"2026-04-18T22:19:44.542688Z","end":"2026-04-18T22:19:45.120156Z","steps":["trace[906379464] 'read index received' (duration: 479.480607ms)","trace[906379464] 'applied index is now lower than readState.Index' (duration: 97.985095ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:45.12028Z","caller":"traceutil/trace.go:171","msg":"trace[1185603577] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"627.581057ms","start":"2026-04-18T22:19:44.492645Z","end":"2026-04-18T22:19:45.120226Z","steps":["trace[1185603577] 'process raft request' (duration: 529.568211ms)","trace[1185603577] 'compare' (duration: 97.7429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:19:45.120408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.698227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:45.120473Z","caller":"traceutil/trace.go:171","msg":"trace[759394135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"577.830971ms","start":"2026-04-18T22:19:44.542625Z","end":"2026-04-18T22:19:45.120456Z","steps":["trace[759394135] 'agreement among raft nodes before linearized reading' (duration: 577.710949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:45.12048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:44.492621Z","time spent":"627.74834ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:45.120523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:44.542601Z","time spent":"577.906363ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:19:45.120969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.645202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:19:45.121064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.395142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:19:45.121177Z","caller":"traceutil/trace.go:171","msg":"trace[674596305] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1085; }","duration":"319.620737ms","start":"2026-04-18T22:19:44.80153Z","end":"2026-04-18T22:19:45.121151Z","steps":["trace[674596305] 'agreement among raft nodes before linearized reading' (duration: 319.361641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:45.121242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:44.801505Z","time spent":"319.72553ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:19:45.121201Z","caller":"traceutil/trace.go:171","msg":"trace[2058353756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1085; }","duration":"573.859087ms","start":"2026-04-18T22:19:44.547264Z","end":"2026-04-18T22:19:45.121123Z","steps":["trace[2058353756] 'agreement among raft nodes before linearized reading' (duration: 573.616471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:45.121557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:44.547242Z","time spent":"574.285117ms","remote":"127.0.0.1:57266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:19:53.720359Z","caller":"traceutil/trace.go:171","msg":"trace[2139771548] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"100.455305ms","start":"2026-04-18T22:19:53.619872Z","end":"2026-04-18T22:19:53.720327Z","steps":["trace[2139771548] 'process raft request' (duration: 100.26157ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:54.01365Z","caller":"traceutil/trace.go:171","msg":"trace[1147963040] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"149.154735ms","start":"2026-04-18T22:19:53.864463Z","end":"2026-04-18T22:19:54.013618Z","steps":["trace[1147963040] 'process raft request' (duration: 148.809527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:56.648119Z","caller":"traceutil/trace.go:171","msg":"trace[1657348382] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"100.312119ms","start":"2026-04-18T22:19:56.54778Z","end":"2026-04-18T22:19:56.648092Z","steps":["trace[1657348382] 'process raft request' (duration: 100.182556ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:58.408145Z","caller":"traceutil/trace.go:171","msg":"trace[291304517] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"278.226001ms","start":"2026-04-18T22:19:58.12989Z","end":"2026-04-18T22:19:58.408116Z","steps":["trace[291304517] 'read index received' (duration: 278.052617ms)","trace[291304517] 'applied index is now lower than readState.Index' (duration: 172.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:58.40825Z","caller":"traceutil/trace.go:171","msg":"trace[1043989649] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"280.053605ms","start":"2026-04-18T22:19:58.12816Z","end":"2026-04-18T22:19:58.408213Z","steps":["trace[1043989649] 'process raft request' (duration: 279.800869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:58.408376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.463607ms","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-04-18T22:19:58.40843Z","caller":"traceutil/trace.go:171","msg":"trace[1768336320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1188; }","duration":"278.565919ms","start":"2026-04-18T22:19:58.12985Z","end":"2026-04-18T22:19:58.408416Z","steps":["trace[1768336320] 'agreement among raft nodes before linearized reading' (duration: 278.365175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:59.915262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234127673s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:59.915503Z","caller":"traceutil/trace.go:171","msg":"trace[1923548566] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"1.410637619s","start":"2026-04-18T22:19:58.504847Z","end":"2026-04-18T22:19:59.915485Z","steps":["trace[1923548566] 'process raft request' (duration: 1.410530526s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:59.915597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.504822Z","time spent":"1.410736711s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:19:59.915696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459897992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:19:59.915654Z","caller":"traceutil/trace.go:171","msg":"trace[1679500690] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"1.503365919s","start":"2026-04-18T22:19:58.412242Z","end":"2026-04-18T22:19:59.915608Z","steps":["trace[1679500690] 'process raft request' (duration: 268.778566ms)","trace[1679500690] 'compare' (duration: 1.233800704s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:59.915592Z","caller":"traceutil/trace.go:171","msg":"trace[1715152318] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"1.45978751s","start":"2026-04-18T22:19:58.455785Z","end":"2026-04-18T22:19:59.915572Z","steps":["trace[1715152318] 'read index received' (duration: 225.288809ms)","trace[1715152318] 'applied index is now lower than readState.Index' (duration: 1.234496231s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:59.915741Z","caller":"traceutil/trace.go:171","msg":"trace[1932156101] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1190; }","duration":"1.459983504s","start":"2026-04-18T22:19:58.455747Z","end":"2026-04-18T22:19:59.91573Z","steps":["trace[1932156101] 'agreement among raft nodes before linearized reading' (duration: 1.459878282s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:59.915776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.455729Z","time spent":"1.460035615s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:19:59.915863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.41222Z","time spent":"1.503533693s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:00.363745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.237153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:20:00.363898Z","caller":"traceutil/trace.go:171","msg":"trace[816626983] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"448.122674ms","start":"2026-04-18T22:19:59.91575Z","end":"2026-04-18T22:20:00.363873Z","steps":["trace[816626983] 'read index received' (duration: 254.738068ms)","trace[816626983] 'applied index is now lower than readState.Index' (duration: 193.382456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:00.36409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712428746s","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-04-18T22:20:00.364133Z","caller":"traceutil/trace.go:171","msg":"trace[604752528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"1.712510217s","start":"2026-04-18T22:19:58.651609Z","end":"2026-04-18T22:20:00.364119Z","steps":["trace[604752528] 'agreement among raft nodes before linearized reading' (duration: 1.712329193s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.364176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.65159Z","time spent":"1.712574459s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:20:00.364319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585680975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:00.36439Z","caller":"traceutil/trace.go:171","msg":"trace[1104166455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"1.585806928s","start":"2026-04-18T22:19:58.778567Z","end":"2026-04-18T22:20:00.364374Z","steps":["trace[1104166455] 'agreement among raft nodes before linearized reading' (duration: 1.585695625s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.364417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.778544Z","time spent":"1.585867759s","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:20:00.364413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60017262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:00.364427Z","caller":"traceutil/trace.go:171","msg":"trace[1852974920] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"473.08957ms","start":"2026-04-18T22:19:59.891306Z","end":"2026-04-18T22:20:00.364396Z","steps":["trace[1852974920] 'process raft request' (duration: 279.115889ms)","trace[1852974920] 'compare' (duration: 192.901345ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:00.364458Z","caller":"traceutil/trace.go:171","msg":"trace[1246659303] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1191; }","duration":"1.600296233s","start":"2026-04-18T22:19:58.76415Z","end":"2026-04-18T22:20:00.364446Z","steps":["trace[1246659303] 'agreement among raft nodes before linearized reading' (duration: 1.600210191s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.364493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:58.764125Z","time spent":"1.600360155s","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:20:00.36432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.97253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:20:00.364516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189003651s","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-04-18T22:20:00.364593Z","caller":"traceutil/trace.go:171","msg":"trace[158334111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"821.284628ms","start":"2026-04-18T22:19:59.543288Z","end":"2026-04-18T22:20:00.364573Z","steps":["trace[158334111] 'agreement among raft nodes before linearized reading' (duration: 820.983521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.364612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.601272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:20:00.364647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:59.543266Z","time spent":"821.3679ms","remote":"127.0.0.1:33120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:20:00.364601Z","caller":"traceutil/trace.go:171","msg":"trace[1346133436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.189150464s","start":"2026-04-18T22:19:59.175431Z","end":"2026-04-18T22:20:00.364582Z","steps":["trace[1346133436] 'agreement among raft nodes before linearized reading' (duration: 1.188892698s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:00.364669Z","caller":"traceutil/trace.go:171","msg":"trace[948945192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"815.674214ms","start":"2026-04-18T22:19:59.548978Z","end":"2026-04-18T22:20:00.364652Z","steps":["trace[948945192] 'agreement among raft nodes before linearized reading' (duration: 815.498909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.36471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:59.175409Z","time spent":"1.189288268s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:20:00.364731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:59.548972Z","time spent":"815.732646ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:20:00.364537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:19:59.891274Z","time spent":"473.208453ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:14.755885Z","caller":"traceutil/trace.go:171","msg":"trace[1632756023] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"213.071755ms","start":"2026-04-18T22:20:14.542792Z","end":"2026-04-18T22:20:14.755864Z","steps":["trace[1632756023] 'read index received' (duration: 212.909771ms)","trace[1632756023] 'applied index is now lower than readState.Index' (duration: 161.014µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:14.756334Z","caller":"traceutil/trace.go:171","msg":"trace[1406916971] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"308.462538ms","start":"2026-04-18T22:20:14.447859Z","end":"2026-04-18T22:20:14.756322Z","steps":["trace[1406916971] 'process raft request' (duration: 307.897415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:14.756516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:14.447828Z","time spent":"308.532289ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:14.759664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.298616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:20:14.759704Z","caller":"traceutil/trace.go:171","msg":"trace[1552140705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"209.379058ms","start":"2026-04-18T22:20:14.550315Z","end":"2026-04-18T22:20:14.759694Z","steps":["trace[1552140705] 'agreement among raft nodes before linearized reading' (duration: 206.425008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:14.85175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.938399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:14.851821Z","caller":"traceutil/trace.go:171","msg":"trace[1355769870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"309.066091ms","start":"2026-04-18T22:20:14.542737Z","end":"2026-04-18T22:20:14.851803Z","steps":["trace[1355769870] 'agreement among raft nodes before linearized reading' (duration: 214.08749ms)","trace[1355769870] 'range keys from in-memory index tree' (duration: 94.866039ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:14.851816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.062662ms","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-04-18T22:20:14.851855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:14.542714Z","time spent":"309.133694ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:20:14.851869Z","caller":"traceutil/trace.go:171","msg":"trace[740886378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"249.144385ms","start":"2026-04-18T22:20:14.602707Z","end":"2026-04-18T22:20:14.851852Z","steps":["trace[740886378] 'agreement among raft nodes before linearized reading' (duration: 154.136032ms)","trace[740886378] 'range keys from in-memory index tree' (duration: 94.852279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:14.851914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.577632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-18T22:20:14.851953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.083197ms","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-04-18T22:20:14.851999Z","caller":"traceutil/trace.go:171","msg":"trace[739681148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"243.707995ms","start":"2026-04-18T22:20:14.608266Z","end":"2026-04-18T22:20:14.851974Z","steps":["trace[739681148] 'agreement among raft nodes before linearized reading' (duration: 148.569189ms)","trace[739681148] 'range keys from in-memory index tree' (duration: 94.90422ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:14.852064Z","caller":"traceutil/trace.go:171","msg":"trace[1346553596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"242.23435ms","start":"2026-04-18T22:20:14.609803Z","end":"2026-04-18T22:20:14.852037Z","steps":["trace[1346553596] 'agreement among raft nodes before linearized reading' (duration: 146.998112ms)","trace[1346553596] 'range keys from in-memory index tree' (duration: 94.980761ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:14.946996Z","caller":"traceutil/trace.go:171","msg":"trace[787303948] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"183.002619ms","start":"2026-04-18T22:20:14.763972Z","end":"2026-04-18T22:20:14.946974Z","steps":["trace[787303948] 'process raft request' (duration: 182.855925ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:15.01962Z","caller":"traceutil/trace.go:171","msg":"trace[325076351] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"163.785671ms","start":"2026-04-18T22:20:14.855808Z","end":"2026-04-18T22:20:15.019594Z","steps":["trace[325076351] 'process raft request' (duration: 161.355304ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:15.019705Z","caller":"traceutil/trace.go:171","msg":"trace[123738309] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"162.760137ms","start":"2026-04-18T22:20:14.856934Z","end":"2026-04-18T22:20:15.019694Z","steps":["trace[123738309] 'process raft request' (duration: 162.649795ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:15.019634Z","caller":"traceutil/trace.go:171","msg":"trace[1731497930] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"163.228987ms","start":"2026-04-18T22:20:14.856388Z","end":"2026-04-18T22:20:15.019617Z","steps":["trace[1731497930] 'process raft request' (duration: 163.139095ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:18.949546Z","caller":"traceutil/trace.go:171","msg":"trace[18649174] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"406.473592ms","start":"2026-04-18T22:20:18.543055Z","end":"2026-04-18T22:20:18.949528Z","steps":["trace[18649174] 'read index received' (duration: 406.36213ms)","trace[18649174] 'applied index is now lower than readState.Index' (duration: 110.642µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:18.949593Z","caller":"traceutil/trace.go:171","msg":"trace[114639016] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"955.186902ms","start":"2026-04-18T22:20:17.994377Z","end":"2026-04-18T22:20:18.949564Z","steps":["trace[114639016] 'process raft request' (duration: 955.035148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:18.949631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.567144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:18.949654Z","caller":"traceutil/trace.go:171","msg":"trace[1946306408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"406.639306ms","start":"2026-04-18T22:20:18.543008Z","end":"2026-04-18T22:20:18.949647Z","steps":["trace[1946306408] 'agreement among raft nodes before linearized reading' (duration: 406.588715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:18.949676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:18.542986Z","time spent":"406.684027ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:20:18.949725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:17.994355Z","time spent":"955.296825ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:19.243807Z","caller":"traceutil/trace.go:171","msg":"trace[58802998] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"292.594359ms","start":"2026-04-18T22:20:18.951184Z","end":"2026-04-18T22:20:19.243779Z","steps":["trace[58802998] 'read index received' (duration: 276.519787ms)","trace[58802998] 'applied index is now lower than readState.Index' (duration: 16.073172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:19.244468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.1996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:20:19.244537Z","caller":"traceutil/trace.go:171","msg":"trace[1493445045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"156.302753ms","start":"2026-04-18T22:20:19.088217Z","end":"2026-04-18T22:20:19.24452Z","steps":["trace[1493445045] 'agreement among raft nodes before linearized reading' (duration: 156.111778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:19.244533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.936938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-18T22:20:19.244469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.360581ms","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-04-18T22:20:19.244609Z","caller":"traceutil/trace.go:171","msg":"trace[772872111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"157.068221ms","start":"2026-04-18T22:20:19.087527Z","end":"2026-04-18T22:20:19.244595Z","steps":["trace[772872111] 'agreement among raft nodes before linearized reading' (duration: 156.840405ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.244625Z","caller":"traceutil/trace.go:171","msg":"trace[1536427497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"155.593966ms","start":"2026-04-18T22:20:19.089017Z","end":"2026-04-18T22:20:19.244611Z","steps":["trace[1536427497] 'agreement among raft nodes before linearized reading' (duration: 155.333589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:19.244468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.035866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:20:19.244749Z","caller":"traceutil/trace.go:171","msg":"trace[1684751350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"284.355834ms","start":"2026-04-18T22:20:18.960386Z","end":"2026-04-18T22:20:19.244742Z","steps":["trace[1684751350] 'agreement among raft nodes before linearized reading' (duration: 283.933093ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.244748Z","caller":"traceutil/trace.go:171","msg":"trace[869659298] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"472.234189ms","start":"2026-04-18T22:20:18.772317Z","end":"2026-04-18T22:20:19.244551Z","steps":["trace[869659298] 'process raft request' (duration: 455.405488ms)","trace[869659298] 'compare' (duration: 15.937379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:19.244723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.423579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:20:19.24485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:18.772293Z","time spent":"472.488225ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:19.24491Z","caller":"traceutil/trace.go:171","msg":"trace[166105665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"293.745157ms","start":"2026-04-18T22:20:18.951144Z","end":"2026-04-18T22:20:19.244889Z","steps":["trace[166105665] 'agreement among raft nodes before linearized reading' (duration: 293.400609ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.474611Z","caller":"traceutil/trace.go:171","msg":"trace[669453157] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"226.325491ms","start":"2026-04-18T22:20:19.248259Z","end":"2026-04-18T22:20:19.474585Z","steps":["trace[669453157] 'process raft request' (duration: 198.57019ms)","trace[669453157] 'compare' (duration: 27.656789ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:19.520695Z","caller":"traceutil/trace.go:171","msg":"trace[664603050] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"271.62283ms","start":"2026-04-18T22:20:19.249052Z","end":"2026-04-18T22:20:19.520675Z","steps":["trace[664603050] 'process raft request' (duration: 271.578029ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.52118Z","caller":"traceutil/trace.go:171","msg":"trace[242274548] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"272.738306ms","start":"2026-04-18T22:20:19.248429Z","end":"2026-04-18T22:20:19.521167Z","steps":["trace[242274548] 'process raft request' (duration: 272.06221ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.521339Z","caller":"traceutil/trace.go:171","msg":"trace[2058844074] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"272.375308ms","start":"2026-04-18T22:20:19.248954Z","end":"2026-04-18T22:20:19.521329Z","steps":["trace[2058844074] 'process raft request' (duration: 271.631231ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:20.873892Z","caller":"traceutil/trace.go:171","msg":"trace[1963222360] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"334.447646ms","start":"2026-04-18T22:20:20.539422Z","end":"2026-04-18T22:20:20.873869Z","steps":["trace[1963222360] 'read index received' (duration: 334.287962ms)","trace[1963222360] 'applied index is now lower than readState.Index' (duration: 158.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:20.873949Z","caller":"traceutil/trace.go:171","msg":"trace[1227244934] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"375.771ms","start":"2026-04-18T22:20:20.498143Z","end":"2026-04-18T22:20:20.873914Z","steps":["trace[1227244934] 'process raft request' (duration: 375.586886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:20.873999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.559679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:20.87403Z","caller":"traceutil/trace.go:171","msg":"trace[1845289541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"334.60473ms","start":"2026-04-18T22:20:20.539416Z","end":"2026-04-18T22:20:20.87402Z","steps":["trace[1845289541] 'agreement among raft nodes before linearized reading' (duration: 334.538678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:20.874069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:20.539372Z","time spent":"334.687852ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:20:20.874112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:20.498127Z","time spent":"375.892454ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:21.558879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.568734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:20:21.558971Z","caller":"traceutil/trace.go:171","msg":"trace[2132463316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"605.705777ms","start":"2026-04-18T22:20:20.953237Z","end":"2026-04-18T22:20:21.558943Z","steps":["trace[2132463316] 'range keys from in-memory index tree' (duration: 605.369249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:21.559013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:20.953196Z","time spent":"605.80619ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-18T22:20:21.570426Z","caller":"traceutil/trace.go:171","msg":"trace[1304484243] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"320.888584ms","start":"2026-04-18T22:20:21.249523Z","end":"2026-04-18T22:20:21.570412Z","steps":["trace[1304484243] 'process raft request' (duration: 320.641758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:21.570511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.249499Z","time spent":"320.960815ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:23.246432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.570390076s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T22:20:23.393748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508588364s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:23.393853Z","caller":"traceutil/trace.go:171","msg":"trace[591486043] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1274; }","duration":"1.508712527s","start":"2026-04-18T22:20:21.885126Z","end":"2026-04-18T22:20:23.393839Z","steps":["trace[591486043] 'range keys from in-memory index tree' (duration: 1.508572374s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.39403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717831888s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:20:23.394221Z","caller":"traceutil/trace.go:171","msg":"trace[1105103727] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.818741262s","start":"2026-04-18T22:20:21.575463Z","end":"2026-04-18T22:20:23.394204Z","steps":["trace[1105103727] 'process raft request' (duration: 1.81865895s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.394282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.575438Z","time spent":"1.818814493s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:23.394443Z","caller":"traceutil/trace.go:171","msg":"trace[1163537771] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.83043404s","start":"2026-04-18T22:20:21.563979Z","end":"2026-04-18T22:20:23.394413Z","steps":["trace[1163537771] 'process raft request' (duration: 112.135741ms)","trace[1163537771] 'compare' (duration: 1.717348867s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:23.394466Z","caller":"traceutil/trace.go:171","msg":"trace[804351175] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"1.82412256s","start":"2026-04-18T22:20:21.570331Z","end":"2026-04-18T22:20:23.394454Z","steps":["trace[804351175] 'read index received' (duration: 105.7784ms)","trace[804351175] 'applied index is now lower than readState.Index' (duration: 1.71834316s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:23.394527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.563956Z","time spent":"1.830519662s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:23.394639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86777155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-18T22:20:23.394631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.867717248s","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-04-18T22:20:23.394672Z","caller":"traceutil/trace.go:171","msg":"trace[1633389354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1276; }","duration":"1.867821411s","start":"2026-04-18T22:20:21.526841Z","end":"2026-04-18T22:20:23.394663Z","steps":["trace[1633389354] 'agreement among raft nodes before linearized reading' (duration: 1.867680177s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.394685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852067895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:20:23.394702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.526834Z","time spent":"1.867861001s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T22:20:23.394697Z","caller":"traceutil/trace.go:171","msg":"trace[1482888933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"1.86780683s","start":"2026-04-18T22:20:21.526874Z","end":"2026-04-18T22:20:23.394681Z","steps":["trace[1482888933] 'agreement among raft nodes before linearized reading' (duration: 1.867631436s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:23.394733Z","caller":"traceutil/trace.go:171","msg":"trace[1350114797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"1.852146217s","start":"2026-04-18T22:20:21.542574Z","end":"2026-04-18T22:20:23.39472Z","steps":["trace[1350114797] 'agreement among raft nodes before linearized reading' (duration: 1.852078795s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.394744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.526869Z","time spent":"1.867864262s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:20:23.394764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.542558Z","time spent":"1.852199439s","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:20:23.394938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86862507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-18T22:20:23.395807Z","caller":"traceutil/trace.go:171","msg":"trace[464782574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"1.869533401s","start":"2026-04-18T22:20:21.526252Z","end":"2026-04-18T22:20:23.395785Z","steps":["trace[464782574] 'agreement among raft nodes before linearized reading' (duration: 1.868519107s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.395889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:21.526231Z","time spent":"1.869642224s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T22:20:23.52836Z","caller":"traceutil/trace.go:171","msg":"trace[419497549] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"717.427869ms","start":"2026-04-18T22:20:22.810905Z","end":"2026-04-18T22:20:23.528333Z","steps":["trace[419497549] 'process raft request' (duration: 717.273145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.528494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:22.81088Z","time spent":"717.542162ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:23.646482Z","caller":"traceutil/trace.go:171","msg":"trace[680754008] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1329; }","duration":"251.955561ms","start":"2026-04-18T22:20:23.394496Z","end":"2026-04-18T22:20:23.646451Z","steps":["trace[680754008] 'read index received' (duration: 76.078792ms)","trace[680754008] 'applied index is now lower than readState.Index' (duration: 175.875759ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:23.646531Z","caller":"traceutil/trace.go:171","msg":"trace[945778540] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"298.737167ms","start":"2026-04-18T22:20:23.347772Z","end":"2026-04-18T22:20:23.646509Z","steps":["trace[945778540] 'process raft request' (duration: 285.573113ms)","trace[945778540] 'compare' (duration: 12.839436ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:23.646593Z","caller":"traceutil/trace.go:171","msg":"trace[110857053] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"247.268839ms","start":"2026-04-18T22:20:23.399313Z","end":"2026-04-18T22:20:23.646582Z","steps":["trace[110857053] 'process raft request' (duration: 246.985573ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:23.646712Z","caller":"traceutil/trace.go:171","msg":"trace[1652113425] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"246.899131ms","start":"2026-04-18T22:20:23.399791Z","end":"2026-04-18T22:20:23.64669Z","steps":["trace[1652113425] 'process raft request' (duration: 246.611184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.646772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.519372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:23.646807Z","caller":"traceutil/trace.go:171","msg":"trace[1098857960] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1281; }","duration":"822.603864ms","start":"2026-04-18T22:20:22.824193Z","end":"2026-04-18T22:20:23.646797Z","steps":["trace[1098857960] 'agreement among raft nodes before linearized reading' (duration: 822.533883ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:23.646816Z","caller":"traceutil/trace.go:171","msg":"trace[651018182] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"247.399623ms","start":"2026-04-18T22:20:23.39941Z","end":"2026-04-18T22:20:23.646809Z","steps":["trace[651018182] 'process raft request' (duration: 246.943872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.646841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:22.82418Z","time spent":"822.650085ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:20:23.646903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314464989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:20:23.646977Z","caller":"traceutil/trace.go:171","msg":"trace[1413488696] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1281; }","duration":"1.314547522s","start":"2026-04-18T22:20:22.332382Z","end":"2026-04-18T22:20:23.646929Z","steps":["trace[1413488696] 'agreement among raft nodes before linearized reading' (duration: 1.314454169s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:23.64701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:22.332362Z","time spent":"1.314640304s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:20:23.673069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.06971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:23.673125Z","caller":"traceutil/trace.go:171","msg":"trace[877819374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"277.176452ms","start":"2026-04-18T22:20:23.395936Z","end":"2026-04-18T22:20:23.673112Z","steps":["trace[877819374] 'agreement among raft nodes before linearized reading' (duration: 277.009488ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.614481Z","caller":"traceutil/trace.go:171","msg":"trace[1341606603] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"131.288447ms","start":"2026-04-18T22:20:29.483163Z","end":"2026-04-18T22:20:29.614452Z","steps":["trace[1341606603] 'process raft request' (duration: 87.880604ms)","trace[1341606603] 'compare' (duration: 42.894871ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:29.889209Z","caller":"traceutil/trace.go:171","msg":"trace[1715419709] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"198.101008ms","start":"2026-04-18T22:20:29.691085Z","end":"2026-04-18T22:20:29.889186Z","steps":["trace[1715419709] 'process raft request' (duration: 198.058877ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.889589Z","caller":"traceutil/trace.go:171","msg":"trace[456212761] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"214.12821ms","start":"2026-04-18T22:20:29.675436Z","end":"2026-04-18T22:20:29.889564Z","steps":["trace[456212761] 'process raft request' (duration: 213.653829ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.889702Z","caller":"traceutil/trace.go:171","msg":"trace[1524998181] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"217.714716ms","start":"2026-04-18T22:20:29.671933Z","end":"2026-04-18T22:20:29.889648Z","steps":["trace[1524998181] 'process raft request' (duration: 123.813249ms)","trace[1524998181] 'compare' (duration: 93.171139ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:30.868019Z","caller":"traceutil/trace.go:171","msg":"trace[455404404] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"325.450392ms","start":"2026-04-18T22:20:30.542538Z","end":"2026-04-18T22:20:30.867988Z","steps":["trace[455404404] 'read index received' (duration: 325.163756ms)","trace[455404404] 'applied index is now lower than readState.Index' (duration: 284.856µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:30.868109Z","caller":"traceutil/trace.go:171","msg":"trace[806562708] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"445.423649ms","start":"2026-04-18T22:20:30.42265Z","end":"2026-04-18T22:20:30.868074Z","steps":["trace[806562708] 'process raft request' (duration: 445.153303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:30.868221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.670317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:30.86827Z","caller":"traceutil/trace.go:171","msg":"trace[2058569681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"325.77373ms","start":"2026-04-18T22:20:30.542482Z","end":"2026-04-18T22:20:30.868256Z","steps":["trace[2058569681] 'agreement among raft nodes before linearized reading' (duration: 325.668507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:30.868271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:30.422608Z","time spent":"445.572133ms","remote":"127.0.0.1:33238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:30.868314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:30.542458Z","time spent":"325.844231ms","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:20:30.868327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.760934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:20:30.868387Z","caller":"traceutil/trace.go:171","msg":"trace[1872671683] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1340; }","duration":"216.875816ms","start":"2026-04-18T22:20:30.651495Z","end":"2026-04-18T22:20:30.868371Z","steps":["trace[1872671683] 'agreement among raft nodes before linearized reading' (duration: 216.735583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:31.040842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.616444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:20:31.040958Z","caller":"traceutil/trace.go:171","msg":"trace[224810495] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"164.817775ms","start":"2026-04-18T22:20:30.876121Z","end":"2026-04-18T22:20:31.040939Z","steps":["trace[224810495] 'process raft request' (duration: 35.977676ms)","trace[224810495] 'compare' (duration: 128.493541ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:31.053394Z","caller":"traceutil/trace.go:171","msg":"trace[354027189] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"154.93432ms","start":"2026-04-18T22:20:30.898426Z","end":"2026-04-18T22:20:31.05336Z","steps":["trace[354027189] 'process raft request' (duration: 154.773386ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:32.015527Z","caller":"traceutil/trace.go:171","msg":"trace[876529153] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"105.151024ms","start":"2026-04-18T22:20:31.910356Z","end":"2026-04-18T22:20:32.015507Z","steps":["trace[876529153] 'process raft request' (duration: 105.120754ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:32.015563Z","caller":"traceutil/trace.go:171","msg":"trace[1768315672] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"116.891714ms","start":"2026-04-18T22:20:31.898639Z","end":"2026-04-18T22:20:32.015531Z","steps":["trace[1768315672] 'process raft request' (duration: 86.796208ms)","trace[1768315672] 'compare' (duration: 29.928572ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:32.015645Z","caller":"traceutil/trace.go:171","msg":"trace[1873151135] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"115.812848ms","start":"2026-04-18T22:20:31.899777Z","end":"2026-04-18T22:20:32.01559Z","steps":["trace[1873151135] 'process raft request' (duration: 115.669585ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:37.446141Z","caller":"traceutil/trace.go:171","msg":"trace[336666108] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"100.930134ms","start":"2026-04-18T22:20:37.34518Z","end":"2026-04-18T22:20:37.44611Z","steps":["trace[336666108] 'process raft request' (duration: 100.73527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:07.896619Z","caller":"traceutil/trace.go:171","msg":"trace[1798257822] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"136.812048ms","start":"2026-04-18T22:21:07.759766Z","end":"2026-04-18T22:21:07.896578Z","steps":["trace[1798257822] 'process raft request' (duration: 134.267697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.053085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.114309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:21:10.053245Z","caller":"traceutil/trace.go:171","msg":"trace[1577590506] linearizableReadLoop","detail":"{readStateIndex:1601; appliedIndex:1600; }","duration":"152.301096ms","start":"2026-04-18T22:21:09.900915Z","end":"2026-04-18T22:21:10.053216Z","steps":["trace[1577590506] 'read index received' (duration: 13.776477ms)","trace[1577590506] 'applied index is now lower than readState.Index' (duration: 138.522819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:10.053549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.619554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:21:10.053631Z","caller":"traceutil/trace.go:171","msg":"trace[844166214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1538; }","duration":"152.757878ms","start":"2026-04-18T22:21:09.900854Z","end":"2026-04-18T22:21:10.053612Z","steps":["trace[844166214] 'agreement among raft nodes before linearized reading' (duration: 152.546103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.053751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.181364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:21:10.053791Z","caller":"traceutil/trace.go:171","msg":"trace[929411839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1538; }","duration":"144.243495ms","start":"2026-04-18T22:21:09.909535Z","end":"2026-04-18T22:21:10.053778Z","steps":["trace[929411839] 'agreement among raft nodes before linearized reading' (duration: 144.074101ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:10.053872Z","caller":"traceutil/trace.go:171","msg":"trace[1043543854] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"289.78812ms","start":"2026-04-18T22:21:09.764066Z","end":"2026-04-18T22:21:10.053854Z","steps":["trace[1043543854] 'process raft request' (duration: 150.697128ms)","trace[1043543854] 'compare' (duration: 137.909704ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:10.456953Z","caller":"traceutil/trace.go:171","msg":"trace[740818606] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"399.175427ms","start":"2026-04-18T22:21:10.057748Z","end":"2026-04-18T22:21:10.456923Z","steps":["trace[740818606] 'process raft request' (duration: 399.056625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.457103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.057726Z","time spent":"399.28642ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:21:10.829678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.220537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:21:10.830045Z","caller":"traceutil/trace.go:171","msg":"trace[156426322] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"769.97827ms","start":"2026-04-18T22:21:10.060041Z","end":"2026-04-18T22:21:10.830019Z","steps":["trace[156426322] 'process raft request' (duration: 528.307623ms)","trace[156426322] 'compare' (duration: 241.120274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:10.830159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.060019Z","time spent":"770.080073ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:21:10.830162Z","caller":"traceutil/trace.go:171","msg":"trace[2067013526] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"591.736368ms","start":"2026-04-18T22:21:10.238403Z","end":"2026-04-18T22:21:10.83014Z","steps":["trace[2067013526] 'process raft request' (duration: 591.641306ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:10.830172Z","caller":"traceutil/trace.go:171","msg":"trace[886785802] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"769.781056ms","start":"2026-04-18T22:21:10.06035Z","end":"2026-04-18T22:21:10.830131Z","steps":["trace[886785802] 'process raft request' (duration: 769.588911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.830231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.238373Z","time spent":"591.82618ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:21:10.830303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.588037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:21:10.830337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.060327Z","time spent":"769.927359ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:21:10.830165Z","caller":"traceutil/trace.go:171","msg":"trace[1716303537] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1602; }","duration":"522.442854ms","start":"2026-04-18T22:21:10.307706Z","end":"2026-04-18T22:21:10.830149Z","steps":["trace[1716303537] 'read index received' (duration: 149.230234ms)","trace[1716303537] 'applied index is now lower than readState.Index' (duration: 373.21104ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:10.830399Z","caller":"traceutil/trace.go:171","msg":"trace[602614445] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1542; }","duration":"522.7092ms","start":"2026-04-18T22:21:10.30765Z","end":"2026-04-18T22:21:10.83036Z","steps":["trace[602614445] 'agreement among raft nodes before linearized reading' (duration: 522.582867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.830492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.30763Z","time spent":"522.844024ms","remote":"127.0.0.1:33658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:21:10.830536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.318676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:21:10.830559Z","caller":"traceutil/trace.go:171","msg":"trace[408689047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1542; }","duration":"454.380918ms","start":"2026-04-18T22:21:10.376172Z","end":"2026-04-18T22:21:10.830553Z","steps":["trace[408689047] 'agreement among raft nodes before linearized reading' (duration: 454.293516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.830577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.376153Z","time spent":"454.419308ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:21:10.830636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.297575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:21:10.830666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.448624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:21:10.830699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.05977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:21:10.830707Z","caller":"traceutil/trace.go:171","msg":"trace[1884939454] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1542; }","duration":"272.415628ms","start":"2026-04-18T22:21:10.558277Z","end":"2026-04-18T22:21:10.830693Z","steps":["trace[1884939454] 'agreement among raft nodes before linearized reading' (duration: 272.303335ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:10.830719Z","caller":"traceutil/trace.go:171","msg":"trace[1703337520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1542; }","duration":"406.176303ms","start":"2026-04-18T22:21:10.424537Z","end":"2026-04-18T22:21:10.830713Z","steps":["trace[1703337520] 'agreement among raft nodes before linearized reading' (duration: 406.112622ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:10.830723Z","caller":"traceutil/trace.go:171","msg":"trace[1586076054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1542; }","duration":"287.553627ms","start":"2026-04-18T22:21:10.543152Z","end":"2026-04-18T22:21:10.830705Z","steps":["trace[1586076054] 'agreement among raft nodes before linearized reading' (duration: 287.441115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:10.830742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:21:10.424489Z","time spent":"406.248635ms","remote":"127.0.0.1:33394","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-04-18T22:21:10.990799Z","caller":"traceutil/trace.go:171","msg":"trace[145185830] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"155.87066ms","start":"2026-04-18T22:21:10.834885Z","end":"2026-04-18T22:21:10.990756Z","steps":["trace[145185830] 'process raft request' (duration: 152.733005ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:48.756816Z","caller":"traceutil/trace.go:171","msg":"trace[1626180436] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1804; }","duration":"212.805393ms","start":"2026-04-18T22:21:48.543985Z","end":"2026-04-18T22:21:48.75679Z","steps":["trace[1626180436] 'read index received' (duration: 212.572566ms)","trace[1626180436] 'applied index is now lower than readState.Index' (duration: 231.837µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:48.756944Z","caller":"traceutil/trace.go:171","msg":"trace[2014177364] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"229.774925ms","start":"2026-04-18T22:21:48.527158Z","end":"2026-04-18T22:21:48.756933Z","steps":["trace[2014177364] 'process raft request' (duration: 229.477327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:48.757163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.159371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:21:48.75722Z","caller":"traceutil/trace.go:171","msg":"trace[9282478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1734; }","duration":"213.261584ms","start":"2026-04-18T22:21:48.543944Z","end":"2026-04-18T22:21:48.757205Z","steps":["trace[9282478] 'agreement among raft nodes before linearized reading' (duration: 213.165541ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:49.365323Z","caller":"traceutil/trace.go:171","msg":"trace[1450428501] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"155.073963ms","start":"2026-04-18T22:21:49.210219Z","end":"2026-04-18T22:21:49.365293Z","steps":["trace[1450428501] 'process raft request' (duration: 148.091677ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:49.365795Z","caller":"traceutil/trace.go:171","msg":"trace[135149448] linearizableReadLoop","detail":"{readStateIndex:1810; appliedIndex:1808; }","duration":"139.204576ms","start":"2026-04-18T22:21:49.226562Z","end":"2026-04-18T22:21:49.365767Z","steps":["trace[135149448] 'read index received' (duration: 131.731798ms)","trace[135149448] 'applied index is now lower than readState.Index' (duration: 7.471388ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:49.366075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.491832ms","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-04-18T22:21:49.366118Z","caller":"traceutil/trace.go:171","msg":"trace[1547660009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1739; }","duration":"139.583175ms","start":"2026-04-18T22:21:49.226522Z","end":"2026-04-18T22:21:49.366105Z","steps":["trace[1547660009] 'agreement among raft nodes before linearized reading' (duration: 139.344739ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:49.366204Z","caller":"traceutil/trace.go:171","msg":"trace[989096692] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"154.061668ms","start":"2026-04-18T22:21:49.212114Z","end":"2026-04-18T22:21:49.366176Z","steps":["trace[989096692] 'process raft request' (duration: 153.502135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:55.629452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.531692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:21:55.629748Z","caller":"traceutil/trace.go:171","msg":"trace[410819281] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"192.921841ms","start":"2026-04-18T22:21:55.436803Z","end":"2026-04-18T22:21:55.629725Z","steps":["trace[410819281] 'process raft request' (duration: 192.803948ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:55.62987Z","caller":"traceutil/trace.go:171","msg":"trace[1587280471] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"192.797067ms","start":"2026-04-18T22:21:55.437036Z","end":"2026-04-18T22:21:55.629833Z","steps":["trace[1587280471] 'process raft request' (duration: 192.637674ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:55.629741Z","caller":"traceutil/trace.go:171","msg":"trace[706122239] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"193.112005ms","start":"2026-04-18T22:21:55.43658Z","end":"2026-04-18T22:21:55.629692Z","steps":["trace[706122239] 'process raft request' (duration: 72.197205ms)","trace[706122239] 'compare' (duration: 120.372117ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:17.408565Z","caller":"traceutil/trace.go:171","msg":"trace[320329991] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"139.024582ms","start":"2026-04-18T22:22:17.269468Z","end":"2026-04-18T22:22:17.408493Z","steps":["trace[320329991] 'process raft request' (duration: 138.832488ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:17.953256Z","caller":"traceutil/trace.go:171","msg":"trace[1479417704] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"215.072535ms","start":"2026-04-18T22:22:17.73816Z","end":"2026-04-18T22:22:17.953233Z","steps":["trace[1479417704] 'read index received' (duration: 214.925892ms)","trace[1479417704] 'applied index is now lower than readState.Index' (duration: 145.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:17.953363Z","caller":"traceutil/trace.go:171","msg":"trace[1201031303] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"295.326938ms","start":"2026-04-18T22:22:17.65801Z","end":"2026-04-18T22:22:17.953337Z","steps":["trace[1201031303] 'process raft request' (duration: 295.069622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:17.953558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.363162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-18T22:22:17.953656Z","caller":"traceutil/trace.go:171","msg":"trace[1554252903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"215.527376ms","start":"2026-04-18T22:22:17.73811Z","end":"2026-04-18T22:22:17.953637Z","steps":["trace[1554252903] 'agreement among raft nodes before linearized reading' (duration: 215.236729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:17.953791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.533176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T22:22:17.953883Z","caller":"traceutil/trace.go:171","msg":"trace[224073201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1882; }","duration":"215.649239ms","start":"2026-04-18T22:22:17.738211Z","end":"2026-04-18T22:22:17.95386Z","steps":["trace[224073201] 'agreement among raft nodes before linearized reading' (duration: 215.395103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:17.953915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.637478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-18T22:22:17.953949Z","caller":"traceutil/trace.go:171","msg":"trace[1694670858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"215.671939ms","start":"2026-04-18T22:22:17.738267Z","end":"2026-04-18T22:22:17.953939Z","steps":["trace[1694670858] 'agreement among raft nodes before linearized reading' (duration: 215.577016ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:18.148491Z","caller":"traceutil/trace.go:171","msg":"trace[331655751] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"190.5211ms","start":"2026-04-18T22:22:17.957944Z","end":"2026-04-18T22:22:18.148465Z","steps":["trace[331655751] 'process raft request' (duration: 190.488029ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:18.148544Z","caller":"traceutil/trace.go:171","msg":"trace[1514413542] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"190.56062ms","start":"2026-04-18T22:22:17.957939Z","end":"2026-04-18T22:22:18.1485Z","steps":["trace[1514413542] 'process raft request' (duration: 190.383856ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:18.192598Z","caller":"traceutil/trace.go:171","msg":"trace[1906809471] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"234.372865ms","start":"2026-04-18T22:22:17.958208Z","end":"2026-04-18T22:22:18.192581Z","steps":["trace[1906809471] 'process raft request' (duration: 234.251272ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:27.45821Z","caller":"traceutil/trace.go:171","msg":"trace[326973277] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"133.626735ms","start":"2026-04-18T22:22:27.324553Z","end":"2026-04-18T22:22:27.45818Z","steps":["trace[326973277] 'process raft request' (duration: 50.132595ms)","trace[326973277] 'compare' (duration: 83.318246ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:27.627432Z","caller":"traceutil/trace.go:171","msg":"trace[947002216] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"164.874739ms","start":"2026-04-18T22:22:27.462537Z","end":"2026-04-18T22:22:27.627412Z","steps":["trace[947002216] 'process raft request' (duration: 127.075339ms)","trace[947002216] 'compare' (duration: 37.585265ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:27.627442Z","caller":"traceutil/trace.go:171","msg":"trace[1341936006] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"112.555682ms","start":"2026-04-18T22:22:27.514857Z","end":"2026-04-18T22:22:27.627412Z","steps":["trace[1341936006] 'read index received' (duration: 74.773302ms)","trace[1341936006] 'applied index is now lower than readState.Index' (duration: 37.78112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:22:27.627692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.823768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:22:27.627793Z","caller":"traceutil/trace.go:171","msg":"trace[2144936113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1929; }","duration":"112.928231ms","start":"2026-04-18T22:22:27.514805Z","end":"2026-04-18T22:22:27.627734Z","steps":["trace[2144936113] 'agreement among raft nodes before linearized reading' (duration: 112.699125ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:22:28.58923Z","caller":"traceutil/trace.go:171","msg":"trace[1940738323] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"397.516092ms","start":"2026-04-18T22:22:28.191691Z","end":"2026-04-18T22:22:28.589207Z","steps":["trace[1940738323] 'process raft request' (duration: 397.283077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:28.589374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:28.191676Z","time spent":"397.617724ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:22:28.590071Z","caller":"traceutil/trace.go:171","msg":"trace[1968428599] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2012; }","duration":"360.0943ms","start":"2026-04-18T22:22:28.22996Z","end":"2026-04-18T22:22:28.590055Z","steps":["trace[1968428599] 'read index received' (duration: 359.130367ms)","trace[1968428599] 'applied index is now lower than readState.Index' (duration: 963.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:28.590132Z","caller":"traceutil/trace.go:171","msg":"trace[1731226846] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"396.742664ms","start":"2026-04-18T22:22:28.193342Z","end":"2026-04-18T22:22:28.590085Z","steps":["trace[1731226846] 'process raft request' (duration: 396.646272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:28.590236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.277115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:22:28.590269Z","caller":"traceutil/trace.go:171","msg":"trace[969705923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1935; }","duration":"360.413548ms","start":"2026-04-18T22:22:28.229846Z","end":"2026-04-18T22:22:28.59026Z","steps":["trace[969705923] 'agreement among raft nodes before linearized reading' (duration: 360.278735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:22:28.590293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:28.229836Z","time spent":"360.452169ms","remote":"127.0.0.1:33394","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-04-18T22:22:28.590351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:22:28.193325Z","time spent":"396.901917ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:22:28.774178Z","caller":"traceutil/trace.go:171","msg":"trace[921270788] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"169.348546ms","start":"2026-04-18T22:22:28.593995Z","end":"2026-04-18T22:22:28.763343Z","steps":["trace[921270788] 'process raft request' (duration: 116.916946ms)","trace[921270788] 'compare' (duration: 52.118751ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:48.026927Z","caller":"traceutil/trace.go:171","msg":"trace[1475492177] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"140.844875ms","start":"2026-04-18T22:22:47.886048Z","end":"2026-04-18T22:22:48.026893Z","steps":["trace[1475492177] 'process raft request' (duration: 95.711021ms)","trace[1475492177] 'compare' (duration: 44.828207ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:23:25.621648Z","caller":"traceutil/trace.go:171","msg":"trace[1133207393] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"125.24874ms","start":"2026-04-18T22:23:25.496376Z","end":"2026-04-18T22:23:25.621625Z","steps":["trace[1133207393] 'process raft request' (duration: 124.912812ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:23:48.890231Z","caller":"traceutil/trace.go:171","msg":"trace[196458888] linearizableReadLoop","detail":"{readStateIndex:2461; appliedIndex:2460; }","duration":"109.285397ms","start":"2026-04-18T22:23:48.780922Z","end":"2026-04-18T22:23:48.890208Z","steps":["trace[196458888] 'read index received' (duration: 109.175655ms)","trace[196458888] 'applied index is now lower than readState.Index' (duration: 108.612µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:23:48.890356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.42001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:23:48.890387Z","caller":"traceutil/trace.go:171","msg":"trace[72445317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2365; }","duration":"109.505162ms","start":"2026-04-18T22:23:48.780873Z","end":"2026-04-18T22:23:48.890378Z","steps":["trace[72445317] 'agreement among raft nodes before linearized reading' (duration: 109.43456ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:23:48.890355Z","caller":"traceutil/trace.go:171","msg":"trace[1275444123] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"220.348706ms","start":"2026-04-18T22:23:48.669974Z","end":"2026-04-18T22:23:48.890323Z","steps":["trace[1275444123] 'process raft request' (duration: 220.08373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:23:48.999663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.186563ms","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:529"} {"level":"info","ts":"2026-04-18T22:23:48.999764Z","caller":"traceutil/trace.go:171","msg":"trace[784955855] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2365; }","duration":"106.304376ms","start":"2026-04-18T22:23:48.893436Z","end":"2026-04-18T22:23:48.99974Z","steps":["trace[784955855] 'range keys from in-memory index tree' (duration: 106.002959ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:23:58.02951Z","caller":"traceutil/trace.go:171","msg":"trace[2061173428] linearizableReadLoop","detail":"{readStateIndex:2516; appliedIndex:2515; }","duration":"207.056679ms","start":"2026-04-18T22:23:57.822414Z","end":"2026-04-18T22:23:58.02947Z","steps":["trace[2061173428] 'read index received' (duration: 202.472459ms)","trace[2061173428] 'applied index is now lower than readState.Index' (duration: 4.58253ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:23:58.029568Z","caller":"traceutil/trace.go:171","msg":"trace[2127579656] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"233.8043ms","start":"2026-04-18T22:23:57.79572Z","end":"2026-04-18T22:23:58.029524Z","steps":["trace[2127579656] 'process raft request' (duration: 229.2382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:23:58.029784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.329625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:23:58.029835Z","caller":"traceutil/trace.go:171","msg":"trace[722098041] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2418; }","duration":"207.457998ms","start":"2026-04-18T22:23:57.822362Z","end":"2026-04-18T22:23:58.02982Z","steps":["trace[722098041] 'agreement among raft nodes before linearized reading' (duration: 207.335245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:23:58.030319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.381473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-18T22:23:58.030402Z","caller":"traceutil/trace.go:171","msg":"trace[1847710568] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2418; }","duration":"206.518886ms","start":"2026-04-18T22:23:57.823858Z","end":"2026-04-18T22:23:58.030377Z","steps":["trace[1847710568] 'agreement among raft nodes before linearized reading' (duration: 206.138057ms)"],"step_count":1}