{"level":"warn","ts":"2026-04-12T20:48:45.615934Z","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-12T20:48:45.61609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-12T20:48:45.616174Z","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-12T20:48:45.61619Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-12T20:48:45.616211Z","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-12T20:48:45.616928Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-12T20:48:45.617029Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-12T20:48:45.635227Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.881008ms"} {"level":"info","ts":"2026-04-12T20:48:45.667703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-12T20:48:45.667822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:48:45.667973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-12T20:48:45.668035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:48:45.66805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-12T20:48:45.668123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-12T20:48:45.698331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:48:45.701492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:48:45.70344Z","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-12T20:48:45.706589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:48:45.706736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:48:45.706845Z","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-12T20:48:45.707859Z","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-12T20:48:45.707909Z","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-12T20:48:45.70816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-12T20:48:45.711131Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-12T20:48:45.713709Z","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-12T20:48:45.714042Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:48:45.714104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:48:45.71474Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-12T20:48:45.714786Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-12T20:48:46.169377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:48:46.16943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:48:46.169465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-12T20:48:46.169483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:48:46.169495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-12T20:48:46.169512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-12T20:48:46.169526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-12T20:48:46.291523Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:48:46.291566Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:46.29175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:48:46.291814Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:48:46.292204Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:48:46.292246Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:48:46.295235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-12T20:48:46.297461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:46.297663Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:46.297726Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:48:46.298697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-12T20:48:49.420299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406562324s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-12T20:48:49.420536Z","caller":"traceutil/trace.go:171","msg":"trace[1335084729] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"1.437829599s","start":"2026-04-12T20:48:47.982677Z","end":"2026-04-12T20:48:49.420507Z","steps":["trace[1335084729] 'process raft request' (duration: 30.323966ms)","trace[1335084729] 'compare' (duration: 1.406413761s)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:49.42057Z","caller":"traceutil/trace.go:171","msg":"trace[973857286] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:73; }","duration":"838.837115ms","start":"2026-04-12T20:48:48.581715Z","end":"2026-04-12T20:48:49.420552Z","steps":["trace[973857286] 'read index received' (duration: 417.04954ms)","trace[973857286] 'applied index is now lower than readState.Index' (duration: 421.786535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:49.420675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:47.982658Z","time spent":"1.437950202s","remote":"127.0.0.1:51304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:48:49.420632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:48.487861Z","time spent":"932.765781ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-12T20:48:49.420735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.012958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:48:49.420861Z","caller":"traceutil/trace.go:171","msg":"trace[1501068341] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:70; }","duration":"839.11085ms","start":"2026-04-12T20:48:48.581666Z","end":"2026-04-12T20:48:49.420777Z","steps":["trace[1501068341] 'agreement among raft nodes before linearized reading' (duration: 839.023339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:49.420867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.811394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-12T20:48:49.420926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.622004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:48:49.420952Z","caller":"traceutil/trace.go:171","msg":"trace[2066557469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"708.678805ms","start":"2026-04-12T20:48:48.712265Z","end":"2026-04-12T20:48:49.420944Z","steps":["trace[2066557469] 'agreement among raft nodes before linearized reading' (duration: 708.624804ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:49.420955Z","caller":"traceutil/trace.go:171","msg":"trace[1774920531] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:70; }","duration":"819.960507ms","start":"2026-04-12T20:48:48.600972Z","end":"2026-04-12T20:48:49.420932Z","steps":["trace[1774920531] 'agreement among raft nodes before linearized reading' (duration: 819.766683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:49.421154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:48.712248Z","time spent":"708.90283ms","remote":"127.0.0.1:50878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:48:49.421163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.353045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-12T20:48:49.421201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:48.581645Z","time spent":"839.263133ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-04-12T20:48:49.421243Z","caller":"traceutil/trace.go:171","msg":"trace[806968207] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:70; }","duration":"817.663112ms","start":"2026-04-12T20:48:48.603563Z","end":"2026-04-12T20:48:49.421226Z","steps":["trace[806968207] 'agreement among raft nodes before linearized reading' (duration: 817.347015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:49.421297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:48.603533Z","time spent":"817.751244ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-12T20:48:49.42118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:48.600945Z","time spent":"820.223454ms","remote":"127.0.0.1:51252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-12T20:48:49.924748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835608278,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:48:49.995392Z","caller":"traceutil/trace.go:171","msg":"trace[347597163] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"571.22872ms","start":"2026-04-12T20:48:49.424102Z","end":"2026-04-12T20:48:49.99533Z","steps":["trace[347597163] 'process raft request' (duration: 571.099278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:49.995515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:49.424089Z","time spent":"571.366292ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:48:50.410137Z","caller":"traceutil/trace.go:171","msg":"trace[160673109] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"985.039415ms","start":"2026-04-12T20:48:49.425053Z","end":"2026-04-12T20:48:50.410092Z","steps":["trace[160673109] 'process raft request' (duration: 983.686708ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:50.410201Z","caller":"traceutil/trace.go:171","msg":"trace[466258252] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"984.136077ms","start":"2026-04-12T20:48:49.426046Z","end":"2026-04-12T20:48:50.410182Z","steps":["trace[466258252] 'process raft request' (duration: 983.888982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:50.410306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:49.426038Z","time spent":"984.208859ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:48:50.410313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:49.425032Z","time spent":"985.215109ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:48:50.410303Z","caller":"traceutil/trace.go:171","msg":"trace[532029893] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:75; }","duration":"986.023965ms","start":"2026-04-12T20:48:49.424254Z","end":"2026-04-12T20:48:50.410278Z","steps":["trace[532029893] 'read index received' (duration: 571.013016ms)","trace[532029893] 'applied index is now lower than readState.Index' (duration: 415.009539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:50.410466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.213928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:48:50.410497Z","caller":"traceutil/trace.go:171","msg":"trace[1634859028] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:73; }","duration":"986.265869ms","start":"2026-04-12T20:48:49.424224Z","end":"2026-04-12T20:48:50.41049Z","steps":["trace[1634859028] 'agreement among raft nodes before linearized reading' (duration: 986.124686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:50.410522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:49.424143Z","time spent":"986.373061ms","remote":"127.0.0.1:51268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-12T20:48:50.410872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.534325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:48:50.410904Z","caller":"traceutil/trace.go:171","msg":"trace[1492973719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"598.567537ms","start":"2026-04-12T20:48:49.81233Z","end":"2026-04-12T20:48:50.410898Z","steps":["trace[1492973719] 'agreement among raft nodes before linearized reading' (duration: 598.500515ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:50.410952Z","caller":"traceutil/trace.go:171","msg":"trace[1573072834] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"410.736975ms","start":"2026-04-12T20:48:50.000195Z","end":"2026-04-12T20:48:50.410932Z","steps":["trace[1573072834] 'process raft request' (duration: 410.04737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:50.411076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:49.812275Z","time spent":"598.793041ms","remote":"127.0.0.1:50878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:48:50.411092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:50.00017Z","time spent":"410.866778ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:48:57.358634Z","caller":"traceutil/trace.go:171","msg":"trace[1650933122] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"134.453255ms","start":"2026-04-12T20:48:57.224154Z","end":"2026-04-12T20:48:57.358608Z","steps":["trace[1650933122] 'process raft request' (duration: 134.310553ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.360345Z","caller":"traceutil/trace.go:171","msg":"trace[2097732778] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"133.378823ms","start":"2026-04-12T20:48:57.226932Z","end":"2026-04-12T20:48:57.360311Z","steps":["trace[2097732778] 'process raft request' (duration: 133.113838ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.837389Z","caller":"traceutil/trace.go:171","msg":"trace[1729169305] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"176.71055ms","start":"2026-04-12T20:48:57.660643Z","end":"2026-04-12T20:48:57.837354Z","steps":["trace[1729169305] 'read index received' (duration: 174.75416ms)","trace[1729169305] 'applied index is now lower than readState.Index' (duration: 1.95463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:57.837591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.935545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4300"} {"level":"info","ts":"2026-04-12T20:48:57.837635Z","caller":"traceutil/trace.go:171","msg":"trace[1821401876] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:346; }","duration":"177.036817ms","start":"2026-04-12T20:48:57.660585Z","end":"2026-04-12T20:48:57.837622Z","steps":["trace[1821401876] 'agreement among raft nodes before linearized reading' (duration: 176.905705ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:57.837432Z","caller":"traceutil/trace.go:171","msg":"trace[1770899416] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"176.795452ms","start":"2026-04-12T20:48:57.66061Z","end":"2026-04-12T20:48:57.837406Z","steps":["trace[1770899416] 'process raft request' (duration: 174.776231ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.01451Z","caller":"traceutil/trace.go:171","msg":"trace[692349752] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:358; }","duration":"177.019136ms","start":"2026-04-12T20:48:57.837466Z","end":"2026-04-12T20:48:58.014485Z","steps":["trace[692349752] 'read index received' (duration: 160.062017ms)","trace[692349752] 'applied index is now lower than readState.Index' (duration: 16.955629ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:58.014684Z","caller":"traceutil/trace.go:171","msg":"trace[1342450038] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"348.94701ms","start":"2026-04-12T20:48:57.665687Z","end":"2026-04-12T20:48:58.014634Z","steps":["trace[1342450038] 'process raft request' (duration: 332.000991ms)","trace[1342450038] 'compare' (duration: 16.52684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:58.015072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:57.66567Z","time spent":"349.046461ms","remote":"127.0.0.1:51252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:48:58.015278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.499613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-12T20:48:58.015338Z","caller":"traceutil/trace.go:171","msg":"trace[2037042547] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:349; }","duration":"292.590855ms","start":"2026-04-12T20:48:57.722731Z","end":"2026-04-12T20:48:58.015322Z","steps":["trace[2037042547] 'agreement among raft nodes before linearized reading' (duration: 292.487423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.015261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.688078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-12T20:48:58.0154Z","caller":"traceutil/trace.go:171","msg":"trace[1066380281] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"241.867452ms","start":"2026-04-12T20:48:57.77351Z","end":"2026-04-12T20:48:58.015377Z","steps":["trace[1066380281] 'agreement among raft nodes before linearized reading' (duration: 241.627317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.015409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.473586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-12T20:48:58.01551Z","caller":"traceutil/trace.go:171","msg":"trace[1199422099] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:349; }","duration":"141.615439ms","start":"2026-04-12T20:48:57.873862Z","end":"2026-04-12T20:48:58.015477Z","steps":["trace[1199422099] 'agreement among raft nodes before linearized reading' (duration: 141.475656ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.015675Z","caller":"traceutil/trace.go:171","msg":"trace[1093701737] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"349.855958ms","start":"2026-04-12T20:48:57.665781Z","end":"2026-04-12T20:48:58.015637Z","steps":["trace[1093701737] 'process raft request' (duration: 348.585932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.015714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.120018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-12T20:48:58.015812Z","caller":"traceutil/trace.go:171","msg":"trace[1241321501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:349; }","duration":"192.271311ms","start":"2026-04-12T20:48:57.823519Z","end":"2026-04-12T20:48:58.01579Z","steps":["trace[1241321501] 'agreement among raft nodes before linearized reading' (duration: 192.095027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.015762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:57.665733Z","time spent":"349.97871ms","remote":"127.0.0.1:51252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:48:58.015862Z","caller":"traceutil/trace.go:171","msg":"trace[443649562] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"350.047162ms","start":"2026-04-12T20:48:57.665802Z","end":"2026-04-12T20:48:58.015849Z","steps":["trace[443649562] 'process raft request' (duration: 348.635504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.015686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.611913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-12T20:48:58.016023Z","caller":"traceutil/trace.go:171","msg":"trace[48951564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:349; }","duration":"343.98742ms","start":"2026-04-12T20:48:57.672022Z","end":"2026-04-12T20:48:58.01601Z","steps":["trace[48951564] 'agreement among raft nodes before linearized reading' (duration: 343.628783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.016038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:57.665791Z","time spent":"350.165424ms","remote":"127.0.0.1:51252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:48:58.016061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:57.671996Z","time spent":"344.045512ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-12T20:48:58.187589Z","caller":"traceutil/trace.go:171","msg":"trace[1322708828] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"164.276082ms","start":"2026-04-12T20:48:58.023254Z","end":"2026-04-12T20:48:58.18753Z","steps":["trace[1322708828] 'process raft request' (duration: 128.65162ms)","trace[1322708828] 'compare' (duration: 35.144902ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:58.187824Z","caller":"traceutil/trace.go:171","msg":"trace[699371799] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:362; }","duration":"115.316563ms","start":"2026-04-12T20:48:58.072482Z","end":"2026-04-12T20:48:58.187799Z","steps":["trace[699371799] 'read index received' (duration: 79.443266ms)","trace[699371799] 'applied index is now lower than readState.Index' (duration: 35.872507ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:58.187957Z","caller":"traceutil/trace.go:171","msg":"trace[1331337493] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"160.371494ms","start":"2026-04-12T20:48:58.027559Z","end":"2026-04-12T20:48:58.187931Z","steps":["trace[1331337493] 'process raft request' (duration: 160.065758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.187986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.477646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-12T20:48:58.188018Z","caller":"traceutil/trace.go:171","msg":"trace[1422853436] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:360; }","duration":"115.577858ms","start":"2026-04-12T20:48:58.072433Z","end":"2026-04-12T20:48:58.188011Z","steps":["trace[1422853436] 'agreement among raft nodes before linearized reading' (duration: 115.466206ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.188066Z","caller":"traceutil/trace.go:171","msg":"trace[106386595] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"161.563147ms","start":"2026-04-12T20:48:58.026459Z","end":"2026-04-12T20:48:58.188022Z","steps":["trace[106386595] 'process raft request' (duration: 161.014526ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.188189Z","caller":"traceutil/trace.go:171","msg":"trace[1229403103] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"137.963515ms","start":"2026-04-12T20:48:58.050209Z","end":"2026-04-12T20:48:58.188173Z","steps":["trace[1229403103] 'process raft request' (duration: 137.518696ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.18824Z","caller":"traceutil/trace.go:171","msg":"trace[1517380570] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"160.752461ms","start":"2026-04-12T20:48:58.027449Z","end":"2026-04-12T20:48:58.188202Z","steps":["trace[1517380570] 'process raft request' (duration: 160.134249ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.187973Z","caller":"traceutil/trace.go:171","msg":"trace[925923833] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"159.894273ms","start":"2026-04-12T20:48:58.028057Z","end":"2026-04-12T20:48:58.187951Z","steps":["trace[925923833] 'process raft request' (duration: 159.633178ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.188192Z","caller":"traceutil/trace.go:171","msg":"trace[740564567] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"163.573988ms","start":"2026-04-12T20:48:58.024553Z","end":"2026-04-12T20:48:58.188127Z","steps":["trace[740564567] 'process raft request' (duration: 162.666609ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.188394Z","caller":"traceutil/trace.go:171","msg":"trace[941983146] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"161.774381ms","start":"2026-04-12T20:48:58.026612Z","end":"2026-04-12T20:48:58.188386Z","steps":["trace[941983146] 'process raft request' (duration: 160.926944ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.188139Z","caller":"traceutil/trace.go:171","msg":"trace[605086296] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"160.382173ms","start":"2026-04-12T20:48:58.027745Z","end":"2026-04-12T20:48:58.188127Z","steps":["trace[605086296] 'process raft request' (duration: 159.918954ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.39974Z","caller":"traceutil/trace.go:171","msg":"trace[1139188870] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:378; }","duration":"130.400165ms","start":"2026-04-12T20:48:58.269313Z","end":"2026-04-12T20:48:58.399713Z","steps":["trace[1139188870] 'read index received' (duration: 9.928259ms)","trace[1139188870] 'applied index is now lower than readState.Index' (duration: 120.470626ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:58.399851Z","caller":"traceutil/trace.go:171","msg":"trace[1299669370] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"134.569038ms","start":"2026-04-12T20:48:58.265242Z","end":"2026-04-12T20:48:58.399811Z","steps":["trace[1299669370] 'process raft request' (duration: 132.510067ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.400051Z","caller":"traceutil/trace.go:171","msg":"trace[1551642734] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"131.64842ms","start":"2026-04-12T20:48:58.268277Z","end":"2026-04-12T20:48:58.399926Z","steps":["trace[1551642734] 'process raft request' (duration: 131.371785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.400283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.842954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-12T20:48:58.400441Z","caller":"traceutil/trace.go:171","msg":"trace[1509288845] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:369; }","duration":"131.081078ms","start":"2026-04-12T20:48:58.269274Z","end":"2026-04-12T20:48:58.400355Z","steps":["trace[1509288845] 'agreement among raft nodes before linearized reading' (duration: 130.569747ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.589806Z","caller":"traceutil/trace.go:171","msg":"trace[1353079954] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"181.71676ms","start":"2026-04-12T20:48:58.408055Z","end":"2026-04-12T20:48:58.589772Z","steps":["trace[1353079954] 'process raft request' (duration: 178.727051ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.58984Z","caller":"traceutil/trace.go:171","msg":"trace[1779541662] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"181.685239ms","start":"2026-04-12T20:48:58.40812Z","end":"2026-04-12T20:48:58.589805Z","steps":["trace[1779541662] 'process raft request' (duration: 181.520256ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.590007Z","caller":"traceutil/trace.go:171","msg":"trace[271874915] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:383; }","duration":"114.428556ms","start":"2026-04-12T20:48:58.475552Z","end":"2026-04-12T20:48:58.589981Z","steps":["trace[271874915] 'read index received' (duration: 111.248513ms)","trace[271874915] 'applied index is now lower than readState.Index' (duration: 3.179263ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:58.59022Z","caller":"traceutil/trace.go:171","msg":"trace[2074622446] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"180.990184ms","start":"2026-04-12T20:48:58.409048Z","end":"2026-04-12T20:48:58.590038Z","steps":["trace[2074622446] 'process raft request' (duration: 180.791561ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.590208Z","caller":"traceutil/trace.go:171","msg":"trace[1337467310] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"181.414193ms","start":"2026-04-12T20:48:58.408775Z","end":"2026-04-12T20:48:58.59019Z","steps":["trace[1337467310] 'process raft request' (duration: 180.997825ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.590289Z","caller":"traceutil/trace.go:171","msg":"trace[1394898483] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"114.435785ms","start":"2026-04-12T20:48:58.475824Z","end":"2026-04-12T20:48:58.59026Z","steps":["trace[1394898483] 'process raft request' (duration: 114.113289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:58.590599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.025929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-2xdd4\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-12T20:48:58.590647Z","caller":"traceutil/trace.go:171","msg":"trace[502627092] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-2xdd4; range_end:; response_count:1; response_revision:378; }","duration":"115.141681ms","start":"2026-04-12T20:48:58.475492Z","end":"2026-04-12T20:48:58.590634Z","steps":["trace[502627092] 'agreement among raft nodes before linearized reading' (duration: 114.567429ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.590099Z","caller":"traceutil/trace.go:171","msg":"trace[25666270] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"169.380573ms","start":"2026-04-12T20:48:58.420706Z","end":"2026-04-12T20:48:58.590086Z","steps":["trace[25666270] 'process raft request' (duration: 169.17947ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:58.590012Z","caller":"traceutil/trace.go:171","msg":"trace[949519663] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"181.742711ms","start":"2026-04-12T20:48:58.408158Z","end":"2026-04-12T20:48:58.589901Z","steps":["trace[949519663] 'process raft request' (duration: 181.568287ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:19.169485Z","caller":"traceutil/trace.go:171","msg":"trace[349790681] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"219.949258ms","start":"2026-04-12T20:49:18.949511Z","end":"2026-04-12T20:49:19.16946Z","steps":["trace[349790681] 'read index received' (duration: 214.692278ms)","trace[349790681] 'applied index is now lower than readState.Index' (duration: 5.25543ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:19.169574Z","caller":"traceutil/trace.go:171","msg":"trace[882028585] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"236.06771ms","start":"2026-04-12T20:49:18.933474Z","end":"2026-04-12T20:49:19.169542Z","steps":["trace[882028585] 'process raft request' (duration: 230.710927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:19.169669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.149843ms","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-12T20:49:19.169759Z","caller":"traceutil/trace.go:171","msg":"trace[1371877334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"220.282597ms","start":"2026-04-12T20:49:18.949455Z","end":"2026-04-12T20:49:19.169737Z","steps":["trace[1371877334] 'agreement among raft nodes before linearized reading' (duration: 220.095822ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:19.410007Z","caller":"traceutil/trace.go:171","msg":"trace[1884275941] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"106.494826ms","start":"2026-04-12T20:49:19.303492Z","end":"2026-04-12T20:49:19.409987Z","steps":["trace[1884275941] 'process raft request' (duration: 103.85106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.447789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.057772ms","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-12T20:49:21.447951Z","caller":"traceutil/trace.go:171","msg":"trace[902766306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:517; }","duration":"475.243296ms","start":"2026-04-12T20:49:20.972689Z","end":"2026-04-12T20:49:21.447932Z","steps":["trace[902766306] 'range keys from in-memory index tree' (duration: 475.036832ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:21.448307Z","caller":"traceutil/trace.go:171","msg":"trace[1836966768] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"424.88583ms","start":"2026-04-12T20:49:21.023384Z","end":"2026-04-12T20:49:21.44827Z","steps":["trace[1836966768] 'process raft request' (duration: 424.046882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.448482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.023363Z","time spent":"425.034763ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.457991Z","caller":"traceutil/trace.go:171","msg":"trace[827692840] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"433.128704ms","start":"2026-04-12T20:49:21.024835Z","end":"2026-04-12T20:49:21.457964Z","steps":["trace[827692840] 'process raft request' (duration: 432.783637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.458141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.024808Z","time spent":"433.257546ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.45864Z","caller":"traceutil/trace.go:171","msg":"trace[1023397250] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:535; }","duration":"262.855616ms","start":"2026-04-12T20:49:21.195753Z","end":"2026-04-12T20:49:21.458609Z","steps":["trace[1023397250] 'read index received' (duration: 251.950746ms)","trace[1023397250] 'applied index is now lower than readState.Index' (duration: 10.90374ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:21.458734Z","caller":"traceutil/trace.go:171","msg":"trace[1899956400] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"417.611415ms","start":"2026-04-12T20:49:21.041107Z","end":"2026-04-12T20:49:21.458719Z","steps":["trace[1899956400] 'process raft request' (duration: 416.91083ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:21.45876Z","caller":"traceutil/trace.go:171","msg":"trace[212269437] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"416.045181ms","start":"2026-04-12T20:49:21.042704Z","end":"2026-04-12T20:49:21.458749Z","steps":["trace[212269437] 'process raft request' (duration: 415.348727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.4588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.041097Z","time spent":"417.671036ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:49:21.45887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.04267Z","time spent":"416.118413ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.45891Z","caller":"traceutil/trace.go:171","msg":"trace[1813197931] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"421.542798ms","start":"2026-04-12T20:49:21.037357Z","end":"2026-04-12T20:49:21.458899Z","steps":["trace[1813197931] 'process raft request' (duration: 420.551967ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:21.458926Z","caller":"traceutil/trace.go:171","msg":"trace[1188577771] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"379.055639ms","start":"2026-04-12T20:49:21.079851Z","end":"2026-04-12T20:49:21.458907Z","steps":["trace[1188577771] 'process raft request' (duration: 378.412004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.458955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.037324Z","time spent":"421.61ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.458951Z","caller":"traceutil/trace.go:171","msg":"trace[1507826812] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"421.726222ms","start":"2026-04-12T20:49:21.037162Z","end":"2026-04-12T20:49:21.458888Z","steps":["trace[1507826812] 'process raft request' (duration: 420.624179ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:21.45906Z","caller":"traceutil/trace.go:171","msg":"trace[241617445] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"410.125927ms","start":"2026-04-12T20:49:21.048925Z","end":"2026-04-12T20:49:21.45905Z","steps":["trace[241617445] 'process raft request' (duration: 409.206777ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:21.459081Z","caller":"traceutil/trace.go:171","msg":"trace[184746691] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"420.730411ms","start":"2026-04-12T20:49:21.038341Z","end":"2026-04-12T20:49:21.459071Z","steps":["trace[184746691] 'process raft request' (duration: 419.640108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.459097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.048891Z","time spent":"410.187847ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.459111Z","caller":"traceutil/trace.go:171","msg":"trace[1296358219] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"236.773555ms","start":"2026-04-12T20:49:21.222328Z","end":"2026-04-12T20:49:21.459102Z","steps":["trace[1296358219] 'process raft request' (duration: 236.193312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.459139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.038329Z","time spent":"420.788743ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:49:21.459162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.037134Z","time spent":"421.934847ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.459244Z","caller":"traceutil/trace.go:171","msg":"trace[964303567] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"400.927562ms","start":"2026-04-12T20:49:21.058306Z","end":"2026-04-12T20:49:21.459233Z","steps":["trace[964303567] 'process raft request' (duration: 399.903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.459253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.4919ms","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-12T20:49:21.459282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.058267Z","time spent":"400.997334ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.459297Z","caller":"traceutil/trace.go:171","msg":"trace[206419806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"263.567132ms","start":"2026-04-12T20:49:21.195707Z","end":"2026-04-12T20:49:21.459274Z","steps":["trace[206419806] 'agreement among raft nodes before linearized reading' (duration: 263.46903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.459022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.079808Z","time spent":"379.155821ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:21.459439Z","caller":"traceutil/trace.go:171","msg":"trace[1148976010] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"434.172465ms","start":"2026-04-12T20:49:21.025252Z","end":"2026-04-12T20:49:21.459425Z","steps":["trace[1148976010] 'process raft request' (duration: 432.4951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:21.459488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.025246Z","time spent":"434.218626ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:49:22.152941Z","caller":"traceutil/trace.go:171","msg":"trace[1930179658] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"520.568835ms","start":"2026-04-12T20:49:21.632336Z","end":"2026-04-12T20:49:22.152905Z","steps":["trace[1930179658] 'process raft request' (duration: 520.365661ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:22.153018Z","caller":"traceutil/trace.go:171","msg":"trace[466018102] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"520.849171ms","start":"2026-04-12T20:49:21.632132Z","end":"2026-04-12T20:49:22.152981Z","steps":["trace[466018102] 'process raft request' (duration: 517.209495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:22.153141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.632313Z","time spent":"520.725329ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:22.153192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.632123Z","time spent":"520.972503ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:22.161047Z","caller":"traceutil/trace.go:171","msg":"trace[522893216] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"514.715042ms","start":"2026-04-12T20:49:21.646307Z","end":"2026-04-12T20:49:22.161022Z","steps":["trace[522893216] 'process raft request' (duration: 514.452576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:22.16119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.646276Z","time spent":"514.835364ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:22.161372Z","caller":"traceutil/trace.go:171","msg":"trace[1096307025] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"478.037034ms","start":"2026-04-12T20:49:21.683287Z","end":"2026-04-12T20:49:22.161324Z","steps":["trace[1096307025] 'process raft request' (duration: 477.684117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:22.161566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:21.683264Z","time spent":"478.217488ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:49:23.884266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.25132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-12T20:49:23.88437Z","caller":"traceutil/trace.go:171","msg":"trace[630568487] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:593; }","duration":"817.404543ms","start":"2026-04-12T20:49:23.06694Z","end":"2026-04-12T20:49:23.884345Z","steps":["trace[630568487] 'range keys from in-memory index tree' (duration: 816.935973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:23.884418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.066914Z","time spent":"817.493255ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-12T20:49:23.939415Z","caller":"traceutil/trace.go:171","msg":"trace[1583779076] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"872.257455ms","start":"2026-04-12T20:49:23.06704Z","end":"2026-04-12T20:49:23.939298Z","steps":["trace[1583779076] 'process raft request' (duration: 871.378516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:23.940097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.067018Z","time spent":"872.595642ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:23.961021Z","caller":"traceutil/trace.go:171","msg":"trace[380247338] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"469.78274ms","start":"2026-04-12T20:49:23.491175Z","end":"2026-04-12T20:49:23.960958Z","steps":["trace[380247338] 'read index received' (duration: 447.189871ms)","trace[380247338] 'applied index is now lower than readState.Index' (duration: 22.591479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:23.961842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.631598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:49:23.961949Z","caller":"traceutil/trace.go:171","msg":"trace[1026525540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"470.790211ms","start":"2026-04-12T20:49:23.491138Z","end":"2026-04-12T20:49:23.961928Z","steps":["trace[1026525540] 'agreement among raft nodes before linearized reading' (duration: 470.459624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:23.961973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.52511ms","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-12T20:49:23.962026Z","caller":"traceutil/trace.go:171","msg":"trace[954849215] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"826.418013ms","start":"2026-04-12T20:49:23.1356Z","end":"2026-04-12T20:49:23.962018Z","steps":["trace[954849215] 'process raft request' (duration: 820.661191ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:23.962029Z","caller":"traceutil/trace.go:171","msg":"trace[1114831607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"431.592381ms","start":"2026-04-12T20:49:23.53042Z","end":"2026-04-12T20:49:23.962013Z","steps":["trace[1114831607] 'agreement among raft nodes before linearized reading' (duration: 431.372686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:23.962072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.530351Z","time spent":"431.710773ms","remote":"127.0.0.1:51154","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-12T20:49:23.962118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.135572Z","time spent":"826.476595ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:23.962257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.066952ms","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-12T20:49:23.962274Z","caller":"traceutil/trace.go:171","msg":"trace[1992950585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"365.107892ms","start":"2026-04-12T20:49:23.59716Z","end":"2026-04-12T20:49:23.962268Z","steps":["trace[1992950585] 'agreement among raft nodes before linearized reading' (duration: 365.034551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:23.962289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.597145Z","time spent":"365.141102ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:49:23.961988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:23.491121Z","time spent":"470.860462ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:49:29.006679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.563138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:29.006802Z","caller":"traceutil/trace.go:171","msg":"trace[308564912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"327.795433ms","start":"2026-04-12T20:49:28.678984Z","end":"2026-04-12T20:49:29.00678Z","steps":["trace[308564912] 'range keys from in-memory index tree' (duration: 327.444136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:29.006822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.375342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hzqrb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-12T20:49:29.006916Z","caller":"traceutil/trace.go:171","msg":"trace[1077757088] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hzqrb; range_end:; response_count:1; response_revision:626; }","duration":"377.512895ms","start":"2026-04-12T20:49:28.629376Z","end":"2026-04-12T20:49:29.006889Z","steps":["trace[1077757088] 'range keys from in-memory index tree' (duration: 377.219409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:29.006958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:28.629357Z","time spent":"377.592116ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hzqrb\" "} {"level":"warn","ts":"2026-04-12T20:49:29.006853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:28.678958Z","time spent":"327.883974ms","remote":"127.0.0.1:50878","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-12T20:49:29.098571Z","caller":"traceutil/trace.go:171","msg":"trace[1520181300] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"103.314158ms","start":"2026-04-12T20:49:28.995206Z","end":"2026-04-12T20:49:29.098521Z","steps":["trace[1520181300] 'read index received' (duration: 100.708963ms)","trace[1520181300] 'applied index is now lower than readState.Index' (duration: 2.603415ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:29.098652Z","caller":"traceutil/trace.go:171","msg":"trace[783382266] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"185.443139ms","start":"2026-04-12T20:49:28.913146Z","end":"2026-04-12T20:49:29.098589Z","steps":["trace[783382266] 'process raft request' (duration: 183.461126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:29.099107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.89641ms","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-12T20:49:29.099172Z","caller":"traceutil/trace.go:171","msg":"trace[1176864827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"104.037003ms","start":"2026-04-12T20:49:28.995119Z","end":"2026-04-12T20:49:29.099156Z","steps":["trace[1176864827] 'agreement among raft nodes before linearized reading' (duration: 103.605604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:29.599229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835609448,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:49:30.729432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019167795s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:49:30.729689Z","caller":"traceutil/trace.go:171","msg":"trace[2130262076] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:652; }","duration":"1.630993915s","start":"2026-04-12T20:49:29.098679Z","end":"2026-04-12T20:49:30.729673Z","steps":["trace[2130262076] 'read index received' (duration: 611.42035ms)","trace[2130262076] 'applied index is now lower than readState.Index' (duration: 1.019572615s)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:30.729777Z","caller":"traceutil/trace.go:171","msg":"trace[1963876858] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.70592808s","start":"2026-04-12T20:49:29.023837Z","end":"2026-04-12T20:49:30.729766Z","steps":["trace[1963876858] 'process raft request' (duration: 686.330766ms)","trace[1963876858] 'compare' (duration: 1.018201405s)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:30.729832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.023794Z","time spent":"1.706007963s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:49:30.729839Z","caller":"traceutil/trace.go:171","msg":"trace[57408072] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.570577255s","start":"2026-04-12T20:49:29.159208Z","end":"2026-04-12T20:49:30.729785Z","steps":["trace[57408072] 'process raft request' (duration: 1.570389661s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:30.729936Z","caller":"traceutil/trace.go:171","msg":"trace[1903966657] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.477053894s","start":"2026-04-12T20:49:29.252873Z","end":"2026-04-12T20:49:30.729927Z","steps":["trace[1903966657] 'process raft request' (duration: 1.476760308s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.729951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.15917Z","time spent":"1.570727838s","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:49:30.729988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.252844Z","time spent":"1.477116494s","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:30.730165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721442729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:30.7302Z","caller":"traceutil/trace.go:171","msg":"trace[607510868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.72152009s","start":"2026-04-12T20:49:29.008671Z","end":"2026-04-12T20:49:30.730191Z","steps":["trace[607510868] 'agreement among raft nodes before linearized reading' (duration: 1.721441479s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.730228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.008644Z","time spent":"1.721575072s","remote":"127.0.0.1:50894","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-12T20:49:30.730243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.237868ms","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-12T20:49:30.730323Z","caller":"traceutil/trace.go:171","msg":"trace[574651476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"731.353819ms","start":"2026-04-12T20:49:29.998948Z","end":"2026-04-12T20:49:30.730302Z","steps":["trace[574651476] 'agreement among raft nodes before linearized reading' (duration: 731.222587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.730371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.998924Z","time spent":"731.437732ms","remote":"127.0.0.1:51154","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-12T20:49:30.730407Z","caller":"traceutil/trace.go:171","msg":"trace[980253598] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.626083499s","start":"2026-04-12T20:49:29.104303Z","end":"2026-04-12T20:49:30.730386Z","steps":["trace[980253598] 'process raft request' (duration: 1.625229062s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.730481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.460881ms","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-12T20:49:30.730517Z","caller":"traceutil/trace.go:171","msg":"trace[1632521317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"730.504602ms","start":"2026-04-12T20:49:30.000003Z","end":"2026-04-12T20:49:30.730507Z","steps":["trace[1632521317] 'agreement among raft nodes before linearized reading' (duration: 730.43944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.730533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.104273Z","time spent":"1.626194552s","remote":"127.0.0.1:51154","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-12T20:49:30.73055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.999996Z","time spent":"730.546452ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:49:30.73076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.774018ms","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-12T20:49:30.733048Z","caller":"traceutil/trace.go:171","msg":"trace[1437684201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"730.906421ms","start":"2026-04-12T20:49:29.999914Z","end":"2026-04-12T20:49:30.73082Z","steps":["trace[1437684201] 'agreement among raft nodes before linearized reading' (duration: 730.558633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:30.733138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:29.999893Z","time spent":"733.221669ms","remote":"127.0.0.1:51154","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-12T20:49:31.262334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.678666ms","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-12T20:49:31.262485Z","caller":"traceutil/trace.go:171","msg":"trace[453590108] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"289.846799ms","start":"2026-04-12T20:49:30.972611Z","end":"2026-04-12T20:49:31.262458Z","steps":["trace[453590108] 'range keys from in-memory index tree' (duration: 289.660086ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:31.262624Z","caller":"traceutil/trace.go:171","msg":"trace[1088193901] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"524.827845ms","start":"2026-04-12T20:49:30.737777Z","end":"2026-04-12T20:49:31.262605Z","steps":["trace[1088193901] 'read index received' (duration: 476.219107ms)","trace[1088193901] 'applied index is now lower than readState.Index' (duration: 48.607548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:31.262958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.167863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:49:31.263033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.114347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:31.263059Z","caller":"traceutil/trace.go:171","msg":"trace[1519276823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"525.288515ms","start":"2026-04-12T20:49:30.737754Z","end":"2026-04-12T20:49:31.263043Z","steps":["trace[1519276823] 'agreement among raft nodes before linearized reading' (duration: 525.155103ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:31.263087Z","caller":"traceutil/trace.go:171","msg":"trace[373112908] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:634; }","duration":"521.211279ms","start":"2026-04-12T20:49:30.741859Z","end":"2026-04-12T20:49:31.26307Z","steps":["trace[373112908] 'agreement among raft nodes before linearized reading' (duration: 521.101437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:31.263112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:30.737746Z","time spent":"525.352356ms","remote":"127.0.0.1:50878","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-12T20:49:31.26313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:30.741851Z","time spent":"521.26703ms","remote":"127.0.0.1:51136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-12T20:49:31.263361Z","caller":"traceutil/trace.go:171","msg":"trace[2050202779] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"528.058884ms","start":"2026-04-12T20:49:30.735281Z","end":"2026-04-12T20:49:31.26334Z","steps":["trace[2050202779] 'process raft request' (duration: 478.72778ms)","trace[2050202779] 'compare' (duration: 48.234831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:49:31.263481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:30.73526Z","time spent":"528.144256ms","remote":"127.0.0.1:51154","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-12T20:49:31.263484Z","caller":"traceutil/trace.go:171","msg":"trace[1514724240] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"526.632154ms","start":"2026-04-12T20:49:30.736833Z","end":"2026-04-12T20:49:31.263465Z","steps":["trace[1514724240] 'process raft request' (duration: 525.672233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:31.263576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:30.73681Z","time spent":"526.716786ms","remote":"127.0.0.1:51154","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-12T20:49:31.263801Z","caller":"traceutil/trace.go:171","msg":"trace[1065592424] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"527.672276ms","start":"2026-04-12T20:49:30.73611Z","end":"2026-04-12T20:49:31.263782Z","steps":["trace[1065592424] 'process raft request' (duration: 526.295357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:31.2639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:30.736074Z","time spent":"527.779519ms","remote":"127.0.0.1:51154","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-12T20:49:33.045865Z","caller":"traceutil/trace.go:171","msg":"trace[127793066] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"211.224394ms","start":"2026-04-12T20:49:32.834602Z","end":"2026-04-12T20:49:33.045826Z","steps":["trace[127793066] 'process raft request' (duration: 211.082831ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:35.979687Z","caller":"traceutil/trace.go:171","msg":"trace[199573873] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"119.034531ms","start":"2026-04-12T20:49:35.860628Z","end":"2026-04-12T20:49:35.979662Z","steps":["trace[199573873] 'process raft request' (duration: 118.830297ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:43.428178Z","caller":"traceutil/trace.go:171","msg":"trace[801209841] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"103.87977ms","start":"2026-04-12T20:49:43.324263Z","end":"2026-04-12T20:49:43.428142Z","steps":["trace[801209841] 'process raft request' (duration: 103.673426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:47.086484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.348025291s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:49:47.087898Z","caller":"traceutil/trace.go:171","msg":"trace[1598224375] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"408.193625ms","start":"2026-04-12T20:49:46.679683Z","end":"2026-04-12T20:49:47.087877Z","steps":["trace[1598224375] 'read index received' (duration: 407.107291ms)","trace[1598224375] 'applied index is now lower than readState.Index' (duration: 1.085524ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:47.087997Z","caller":"traceutil/trace.go:171","msg":"trace[279939418] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.349676176s","start":"2026-04-12T20:49:45.738315Z","end":"2026-04-12T20:49:47.087992Z","steps":["trace[279939418] 'process raft request' (duration: 1.34847689s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:47.088076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:45.738301Z","time spent":"1.349712866s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:49:47.088283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.551392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:47.08837Z","caller":"traceutil/trace.go:171","msg":"trace[1003734927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"408.740945ms","start":"2026-04-12T20:49:46.679606Z","end":"2026-04-12T20:49:47.088347Z","steps":["trace[1003734927] 'agreement among raft nodes before linearized reading' (duration: 408.522531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:47.088442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:46.679573Z","time spent":"408.858469ms","remote":"127.0.0.1:50878","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-12T20:49:47.095276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.834207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:49:47.095313Z","caller":"traceutil/trace.go:171","msg":"trace[697948896] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:794; }","duration":"110.913619ms","start":"2026-04-12T20:49:46.984392Z","end":"2026-04-12T20:49:47.095306Z","steps":["trace[697948896] 'agreement among raft nodes before linearized reading' (duration: 110.851438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:47.095558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.697733ms","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-12T20:49:47.095889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.320056ms","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-12T20:49:47.095951Z","caller":"traceutil/trace.go:171","msg":"trace[1543366007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"288.132142ms","start":"2026-04-12T20:49:46.807785Z","end":"2026-04-12T20:49:47.095917Z","steps":["trace[1543366007] 'agreement among raft nodes before linearized reading' (duration: 287.529559ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:47.095991Z","caller":"traceutil/trace.go:171","msg":"trace[966960797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"398.488849ms","start":"2026-04-12T20:49:46.697476Z","end":"2026-04-12T20:49:47.095965Z","steps":["trace[966960797] 'agreement among raft nodes before linearized reading' (duration: 397.984089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:47.096047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:49:46.697464Z","time spent":"398.572612ms","remote":"127.0.0.1:51154","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-12T20:49:55.014277Z","caller":"traceutil/trace.go:171","msg":"trace[1492570737] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"144.304186ms","start":"2026-04-12T20:49:54.869941Z","end":"2026-04-12T20:49:55.014246Z","steps":["trace[1492570737] 'process raft request' (duration: 144.087012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:23.797295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:50:24.298272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:50:24.632278Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.343488946s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:50:24.632626Z","caller":"traceutil/trace.go:171","msg":"trace[1756049947] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.344788613s","start":"2026-04-12T20:50:23.287813Z","end":"2026-04-12T20:50:24.632602Z","steps":["trace[1756049947] 'process raft request' (duration: 1.3446401s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:24.632759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:23.287784Z","time spent":"1.344888514s","remote":"127.0.0.1:51154","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-12T20:50:24.66312Z","caller":"traceutil/trace.go:171","msg":"trace[2008329184] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"1.365860348s","start":"2026-04-12T20:50:23.297238Z","end":"2026-04-12T20:50:24.663098Z","steps":["trace[2008329184] 'read index received' (duration: 1.335295122s)","trace[2008329184] 'applied index is now lower than readState.Index' (duration: 30.563896ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:24.663195Z","caller":"traceutil/trace.go:171","msg":"trace[130955247] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"618.997692ms","start":"2026-04-12T20:50:24.044172Z","end":"2026-04-12T20:50:24.663169Z","steps":["trace[130955247] 'process raft request' (duration: 618.773598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:24.663373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028616637s","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-12T20:50:24.663372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366130403s","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-12T20:50:24.66342Z","caller":"traceutil/trace.go:171","msg":"trace[1202636620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"1.028716379s","start":"2026-04-12T20:50:23.634688Z","end":"2026-04-12T20:50:24.663405Z","steps":["trace[1202636620] 'agreement among raft nodes before linearized reading' (duration: 1.028538925s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:24.663428Z","caller":"traceutil/trace.go:171","msg":"trace[924228292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.366213945s","start":"2026-04-12T20:50:23.297201Z","end":"2026-04-12T20:50:24.663414Z","steps":["trace[924228292] 'agreement among raft nodes before linearized reading' (duration: 1.366008871s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:24.66342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:24.044143Z","time spent":"619.210097ms","remote":"127.0.0.1:51154","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-12T20:50:24.663559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011012375s","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-12T20:50:24.663658Z","caller":"traceutil/trace.go:171","msg":"trace[2085788983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.011145688s","start":"2026-04-12T20:50:23.652488Z","end":"2026-04-12T20:50:24.663634Z","steps":["trace[2085788983] 'agreement among raft nodes before linearized reading' (duration: 1.010843192s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:24.663454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:23.634661Z","time spent":"1.02878455s","remote":"127.0.0.1:51154","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-12T20:50:24.663719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:23.652472Z","time spent":"1.011237169s","remote":"127.0.0.1:51154","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-12T20:50:24.663462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:23.297187Z","time spent":"1.366267566s","remote":"127.0.0.1:51154","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-12T20:50:24.66351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010174977s","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-12T20:50:24.663946Z","caller":"traceutil/trace.go:171","msg":"trace[1784737827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.010659018s","start":"2026-04-12T20:50:23.653271Z","end":"2026-04-12T20:50:24.66393Z","steps":["trace[1784737827] 'agreement among raft nodes before linearized reading' (duration: 1.010006844s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:24.664008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:23.653262Z","time spent":"1.010732229s","remote":"127.0.0.1:51154","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-12T20:50:49.435757Z","caller":"traceutil/trace.go:171","msg":"trace[586553191] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"492.871301ms","start":"2026-04-12T20:50:48.94286Z","end":"2026-04-12T20:50:49.435732Z","steps":["trace[586553191] 'read index received' (duration: 443.730464ms)","trace[586553191] 'applied index is now lower than readState.Index' (duration: 49.139747ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:49.435827Z","caller":"traceutil/trace.go:171","msg":"trace[780322495] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"611.618397ms","start":"2026-04-12T20:50:48.824157Z","end":"2026-04-12T20:50:49.435775Z","steps":["trace[780322495] 'process raft request' (duration: 562.554251ms)","trace[780322495] 'compare' (duration: 48.524525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:49.436052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:48.824128Z","time spent":"611.786129ms","remote":"127.0.0.1:51154","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-12T20:50:49.436437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.056222ms","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-12T20:50:49.436499Z","caller":"traceutil/trace.go:171","msg":"trace[1342455733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"491.146554ms","start":"2026-04-12T20:50:48.945339Z","end":"2026-04-12T20:50:49.436486Z","steps":["trace[1342455733] 'agreement among raft nodes before linearized reading' (duration: 490.539571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:49.436535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:48.945322Z","time spent":"491.204865ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:50:49.436593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.996194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-12T20:50:49.436652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.488499ms","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-12T20:50:49.436687Z","caller":"traceutil/trace.go:171","msg":"trace[1145021821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"493.196468ms","start":"2026-04-12T20:50:48.943467Z","end":"2026-04-12T20:50:49.436664Z","steps":["trace[1145021821] 'agreement among raft nodes before linearized reading' (duration: 492.413121ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:49.436707Z","caller":"traceutil/trace.go:171","msg":"trace[1141391833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"489.55713ms","start":"2026-04-12T20:50:48.947135Z","end":"2026-04-12T20:50:49.436692Z","steps":["trace[1141391833] 'agreement among raft nodes before linearized reading' (duration: 488.740893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:49.436675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.215238ms","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-12T20:50:49.436802Z","caller":"traceutil/trace.go:171","msg":"trace[1432717313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"493.957344ms","start":"2026-04-12T20:50:48.9428Z","end":"2026-04-12T20:50:49.436757Z","steps":["trace[1432717313] 'agreement among raft nodes before linearized reading' (duration: 493.007793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:49.436752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:48.943437Z","time spent":"493.29908ms","remote":"127.0.0.1:51154","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-12T20:50:49.436753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:48.947129Z","time spent":"489.612902ms","remote":"127.0.0.1:51154","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-12T20:50:49.436855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:48.94277Z","time spent":"494.065295ms","remote":"127.0.0.1:51154","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-12T20:50:49.717048Z","caller":"traceutil/trace.go:171","msg":"trace[943651270] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"275.81003ms","start":"2026-04-12T20:50:49.441206Z","end":"2026-04-12T20:50:49.717016Z","steps":["trace[943651270] 'process raft request' (duration: 275.618977ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:49.722155Z","caller":"traceutil/trace.go:171","msg":"trace[837281246] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"277.612759ms","start":"2026-04-12T20:50:49.4445Z","end":"2026-04-12T20:50:49.722113Z","steps":["trace[837281246] 'process raft request' (duration: 277.517266ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:49.722197Z","caller":"traceutil/trace.go:171","msg":"trace[1302394215] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"280.485499ms","start":"2026-04-12T20:50:49.441675Z","end":"2026-04-12T20:50:49.722161Z","steps":["trace[1302394215] 'process raft request' (duration: 280.168522ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:49.722317Z","caller":"traceutil/trace.go:171","msg":"trace[789296287] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"279.454867ms","start":"2026-04-12T20:50:49.44285Z","end":"2026-04-12T20:50:49.722305Z","steps":["trace[789296287] 'process raft request' (duration: 279.12595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:49.955688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.521558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:49.955797Z","caller":"traceutil/trace.go:171","msg":"trace[1394212049] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1096; }","duration":"106.688491ms","start":"2026-04-12T20:50:49.849071Z","end":"2026-04-12T20:50:49.95576Z","steps":["trace[1394212049] 'count revisions from in-memory index tree' (duration: 106.305053ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:51.259726Z","caller":"traceutil/trace.go:171","msg":"trace[290675672] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"368.515558ms","start":"2026-04-12T20:50:50.89118Z","end":"2026-04-12T20:50:51.259695Z","steps":["trace[290675672] 'process raft request' (duration: 368.278403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.259918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:50.891157Z","time spent":"368.65422ms","remote":"127.0.0.1:51154","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-12T20:50:51.592885Z","caller":"traceutil/trace.go:171","msg":"trace[950012613] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"133.17885ms","start":"2026-04-12T20:50:51.459668Z","end":"2026-04-12T20:50:51.592847Z","steps":["trace[950012613] 'read index received' (duration: 132.882593ms)","trace[950012613] 'applied index is now lower than readState.Index' (duration: 294.807µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:51.593068Z","caller":"traceutil/trace.go:171","msg":"trace[97073373] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"150.212091ms","start":"2026-04-12T20:50:51.442838Z","end":"2026-04-12T20:50:51.59305Z","steps":["trace[97073373] 'process raft request' (duration: 149.813432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.593532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.819454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:50:51.593656Z","caller":"traceutil/trace.go:171","msg":"trace[1686157345] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1098; }","duration":"134.030829ms","start":"2026-04-12T20:50:51.459599Z","end":"2026-04-12T20:50:51.59363Z","steps":["trace[1686157345] 'agreement among raft nodes before linearized reading' (duration: 133.735323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.978669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.363524ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-12T20:50:51.978786Z","caller":"traceutil/trace.go:171","msg":"trace[330047981] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"299.449019ms","start":"2026-04-12T20:50:51.679315Z","end":"2026-04-12T20:50:51.978764Z","steps":["trace[330047981] 'read index received' (duration: 156.959212ms)","trace[330047981] 'applied index is now lower than readState.Index' (duration: 142.488157ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:51.978901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:51.594411Z","time spent":"384.486292ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-12T20:50:51.979221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.971566ms","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-12T20:50:51.979263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.393246ms","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-12T20:50:51.979284Z","caller":"traceutil/trace.go:171","msg":"trace[1892738476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"251.068099ms","start":"2026-04-12T20:50:51.7282Z","end":"2026-04-12T20:50:51.979269Z","steps":["trace[1892738476] 'agreement among raft nodes before linearized reading' (duration: 250.925376ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:51.979297Z","caller":"traceutil/trace.go:171","msg":"trace[1893899246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"251.479697ms","start":"2026-04-12T20:50:51.727807Z","end":"2026-04-12T20:50:51.979286Z","steps":["trace[1893899246] 'agreement among raft nodes before linearized reading' (duration: 251.390266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.979219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.393161ms","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-12T20:50:51.979328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.007042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:51.97938Z","caller":"traceutil/trace.go:171","msg":"trace[608790513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"300.116894ms","start":"2026-04-12T20:50:51.679251Z","end":"2026-04-12T20:50:51.979368Z","steps":["trace[608790513] 'agreement among raft nodes before linearized reading' (duration: 300.010402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.979415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:50:51.679221Z","time spent":"300.186675ms","remote":"127.0.0.1:50878","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-12T20:50:51.979411Z","caller":"traceutil/trace.go:171","msg":"trace[1468299078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"257.626346ms","start":"2026-04-12T20:50:51.721743Z","end":"2026-04-12T20:50:51.97937Z","steps":["trace[1468299078] 'agreement among raft nodes before linearized reading' (duration: 257.197916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:51.979677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.238992ms","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-12T20:50:51.979942Z","caller":"traceutil/trace.go:171","msg":"trace[409800593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"252.477698ms","start":"2026-04-12T20:50:51.727371Z","end":"2026-04-12T20:50:51.979849Z","steps":["trace[409800593] 'agreement among raft nodes before linearized reading' (duration: 251.865615ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.13096Z","caller":"traceutil/trace.go:171","msg":"trace[1777201711] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"149.73582ms","start":"2026-04-12T20:50:51.981186Z","end":"2026-04-12T20:50:52.130922Z","steps":["trace[1777201711] 'read index received' (duration: 139.181347ms)","trace[1777201711] 'applied index is now lower than readState.Index' (duration: 10.552073ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:52.130977Z","caller":"traceutil/trace.go:171","msg":"trace[1740850126] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"150.133228ms","start":"2026-04-12T20:50:51.980806Z","end":"2026-04-12T20:50:52.13094Z","steps":["trace[1740850126] 'process raft request' (duration: 139.868382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:52.131184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.966454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:52.131226Z","caller":"traceutil/trace.go:171","msg":"trace[1907274349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"150.061147ms","start":"2026-04-12T20:50:51.981154Z","end":"2026-04-12T20:50:52.131215Z","steps":["trace[1907274349] 'agreement among raft nodes before linearized reading' (duration: 149.913514ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.151026Z","caller":"traceutil/trace.go:171","msg":"trace[2129226172] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"167.128377ms","start":"2026-04-12T20:50:51.983845Z","end":"2026-04-12T20:50:52.150974Z","steps":["trace[2129226172] 'process raft request' (duration: 167.047705ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.151048Z","caller":"traceutil/trace.go:171","msg":"trace[1050787878] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"167.376252ms","start":"2026-04-12T20:50:51.983652Z","end":"2026-04-12T20:50:52.151028Z","steps":["trace[1050787878] 'process raft request' (duration: 167.120936ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.151518Z","caller":"traceutil/trace.go:171","msg":"trace[1434139920] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"166.33214ms","start":"2026-04-12T20:50:51.98515Z","end":"2026-04-12T20:50:52.151482Z","steps":["trace[1434139920] 'process raft request' (duration: 165.787778ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.15159Z","caller":"traceutil/trace.go:171","msg":"trace[383715238] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"165.679477ms","start":"2026-04-12T20:50:51.985892Z","end":"2026-04-12T20:50:52.151571Z","steps":["trace[383715238] 'process raft request' (duration: 165.463152ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:52.15188Z","caller":"traceutil/trace.go:171","msg":"trace[1065083380] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"135.093732ms","start":"2026-04-12T20:50:52.016769Z","end":"2026-04-12T20:50:52.151862Z","steps":["trace[1065083380] 'process raft request' (duration: 134.755265ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:53.556349Z","caller":"traceutil/trace.go:171","msg":"trace[735114793] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"265.85763ms","start":"2026-04-12T20:50:53.290465Z","end":"2026-04-12T20:50:53.556322Z","steps":["trace[735114793] 'read index received' (duration: 265.665126ms)","trace[735114793] 'applied index is now lower than readState.Index' (duration: 191.124µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:53.556404Z","caller":"traceutil/trace.go:171","msg":"trace[1738561499] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"285.605768ms","start":"2026-04-12T20:50:53.270757Z","end":"2026-04-12T20:50:53.556363Z","steps":["trace[1738561499] 'process raft request' (duration: 285.392314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:53.556615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.117076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:53.556692Z","caller":"traceutil/trace.go:171","msg":"trace[1136662428] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1105; }","duration":"266.270659ms","start":"2026-04-12T20:50:53.290403Z","end":"2026-04-12T20:50:53.556673Z","steps":["trace[1136662428] 'agreement among raft nodes before linearized reading' (duration: 266.121546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:50:53.827629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.534893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:50:53.827759Z","caller":"traceutil/trace.go:171","msg":"trace[1709003541] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"147.922672ms","start":"2026-04-12T20:50:53.679815Z","end":"2026-04-12T20:50:53.827738Z","steps":["trace[1709003541] 'read index received' (duration: 27.140153ms)","trace[1709003541] 'applied index is now lower than readState.Index' (duration: 120.781149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:50:53.82813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.289999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:50:53.828221Z","caller":"traceutil/trace.go:171","msg":"trace[2119740358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"148.445412ms","start":"2026-04-12T20:50:53.679758Z","end":"2026-04-12T20:50:53.828203Z","steps":["trace[2119740358] 'agreement among raft nodes before linearized reading' (duration: 148.052984ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:53.828423Z","caller":"traceutil/trace.go:171","msg":"trace[1383844731] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"229.417022ms","start":"2026-04-12T20:50:53.59896Z","end":"2026-04-12T20:50:53.828377Z","steps":["trace[1383844731] 'process raft request' (duration: 108.028621ms)","trace[1383844731] 'compare' (duration: 120.35542ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:50:54.274324Z","caller":"traceutil/trace.go:171","msg":"trace[1485322272] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"113.508025ms","start":"2026-04-12T20:50:54.160781Z","end":"2026-04-12T20:50:54.274289Z","steps":["trace[1485322272] 'process raft request' (duration: 113.23813ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:54.311256Z","caller":"traceutil/trace.go:171","msg":"trace[1367715409] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"149.946434ms","start":"2026-04-12T20:50:54.161227Z","end":"2026-04-12T20:50:54.311173Z","steps":["trace[1367715409] 'process raft request' (duration: 149.289951ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:54.311283Z","caller":"traceutil/trace.go:171","msg":"trace[1574299093] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"150.076907ms","start":"2026-04-12T20:50:54.16118Z","end":"2026-04-12T20:50:54.311257Z","steps":["trace[1574299093] 'process raft request' (duration: 149.086986ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:50:54.311337Z","caller":"traceutil/trace.go:171","msg":"trace[151469326] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"150.129198ms","start":"2026-04-12T20:50:54.161183Z","end":"2026-04-12T20:50:54.311313Z","steps":["trace[151469326] 'process raft request' (duration: 149.287181ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:06.073119Z","caller":"traceutil/trace.go:171","msg":"trace[805093273] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"195.327902ms","start":"2026-04-12T20:51:05.877758Z","end":"2026-04-12T20:51:06.073086Z","steps":["trace[805093273] 'process raft request' (duration: 168.863913ms)","trace[805093273] 'compare' (duration: 26.307486ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:06.695382Z","caller":"traceutil/trace.go:171","msg":"trace[1125461207] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"288.230903ms","start":"2026-04-12T20:51:06.407129Z","end":"2026-04-12T20:51:06.69536Z","steps":["trace[1125461207] 'read index received' (duration: 280.095501ms)","trace[1125461207] 'applied index is now lower than readState.Index' (duration: 8.134252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:06.695435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.079239ms","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-12T20:51:06.695505Z","caller":"traceutil/trace.go:171","msg":"trace[1364888980] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"300.722466ms","start":"2026-04-12T20:51:06.394729Z","end":"2026-04-12T20:51:06.695452Z","steps":["trace[1364888980] 'process raft request' (duration: 292.575744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:06.695609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.471198ms","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-12T20:51:06.695637Z","caller":"traceutil/trace.go:171","msg":"trace[1679078913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"288.55524ms","start":"2026-04-12T20:51:06.407074Z","end":"2026-04-12T20:51:06.695629Z","steps":["trace[1679078913] 'agreement among raft nodes before linearized reading' (duration: 288.424657ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:06.695628Z","caller":"traceutil/trace.go:171","msg":"trace[1283961902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"315.317034ms","start":"2026-04-12T20:51:06.38028Z","end":"2026-04-12T20:51:06.695597Z","steps":["trace[1283961902] 'range keys from in-memory index tree' (duration: 314.601529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:06.695691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:06.380245Z","time spent":"315.428917ms","remote":"127.0.0.1:51154","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-12T20:51:06.695721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:06.3947Z","time spent":"300.92221ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:06.695768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.009598ms","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-12T20:51:06.695859Z","caller":"traceutil/trace.go:171","msg":"trace[1740140393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"234.142041ms","start":"2026-04-12T20:51:06.461695Z","end":"2026-04-12T20:51:06.695837Z","steps":["trace[1740140393] 'agreement among raft nodes before linearized reading' (duration: 233.936237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:06.696007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.164209ms","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-12T20:51:06.696223Z","caller":"traceutil/trace.go:171","msg":"trace[1215670839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"232.397404ms","start":"2026-04-12T20:51:06.463804Z","end":"2026-04-12T20:51:06.696201Z","steps":["trace[1215670839] 'agreement among raft nodes before linearized reading' (duration: 232.077637ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:08.253336Z","caller":"traceutil/trace.go:171","msg":"trace[858438087] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"540.304842ms","start":"2026-04-12T20:51:07.712914Z","end":"2026-04-12T20:51:08.253219Z","steps":["trace[858438087] 'process raft request' (duration: 540.126018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.253503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:07.712881Z","time spent":"540.515586ms","remote":"127.0.0.1:51154","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-12T20:51:08.848693Z","caller":"traceutil/trace.go:171","msg":"trace[1364646236] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"356.360291ms","start":"2026-04-12T20:51:08.492298Z","end":"2026-04-12T20:51:08.848659Z","steps":["trace[1364646236] 'read index received' (duration: 352.737684ms)","trace[1364646236] 'applied index is now lower than readState.Index' (duration: 3.620847ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:08.848762Z","caller":"traceutil/trace.go:171","msg":"trace[219732770] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"769.879876ms","start":"2026-04-12T20:51:08.078838Z","end":"2026-04-12T20:51:08.848718Z","steps":["trace[219732770] 'process raft request' (duration: 766.235199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.848893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.566425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:08.84894Z","caller":"traceutil/trace.go:171","msg":"trace[671942670] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1203; }","duration":"356.656536ms","start":"2026-04-12T20:51:08.49227Z","end":"2026-04-12T20:51:08.848926Z","steps":["trace[671942670] 'agreement among raft nodes before linearized reading' (duration: 356.534484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.848942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.211403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:51:08.848982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:08.49225Z","time spent":"356.722868ms","remote":"127.0.0.1:51136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:51:08.849005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:08.07881Z","time spent":"770.06722ms","remote":"127.0.0.1:51154","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-12T20:51:08.849187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.151614ms","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-12T20:51:08.849245Z","caller":"traceutil/trace.go:171","msg":"trace[985381686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"138.260627ms","start":"2026-04-12T20:51:08.710971Z","end":"2026-04-12T20:51:08.849232Z","steps":["trace[985381686] 'agreement among raft nodes before linearized reading' (duration: 138.076913ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:08.849023Z","caller":"traceutil/trace.go:171","msg":"trace[1756601134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"171.335205ms","start":"2026-04-12T20:51:08.677664Z","end":"2026-04-12T20:51:08.848999Z","steps":["trace[1756601134] 'agreement among raft nodes before linearized reading' (duration: 171.208372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.849629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.577005ms","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-12T20:51:08.849684Z","caller":"traceutil/trace.go:171","msg":"trace[1086162398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1203; }","duration":"139.647828ms","start":"2026-04-12T20:51:08.710023Z","end":"2026-04-12T20:51:08.849671Z","steps":["trace[1086162398] 'agreement among raft nodes before linearized reading' (duration: 139.522235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.849919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.807073ms","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-12T20:51:08.849981Z","caller":"traceutil/trace.go:171","msg":"trace[845934626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"142.912566ms","start":"2026-04-12T20:51:08.707055Z","end":"2026-04-12T20:51:08.849968Z","steps":["trace[845934626] 'agreement among raft nodes before linearized reading' (duration: 142.763293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:08.849927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.496055ms","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-12T20:51:08.850076Z","caller":"traceutil/trace.go:171","msg":"trace[592393848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"140.666127ms","start":"2026-04-12T20:51:08.709393Z","end":"2026-04-12T20:51:08.850059Z","steps":["trace[592393848] 'agreement among raft nodes before linearized reading' (duration: 140.402532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:21.23771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.138405ms","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-12T20:51:21.237842Z","caller":"traceutil/trace.go:171","msg":"trace[459189579] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1243; }","duration":"265.278339ms","start":"2026-04-12T20:51:20.972541Z","end":"2026-04-12T20:51:21.237819Z","steps":["trace[459189579] 'range keys from in-memory index tree' (duration: 265.120485ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:21.312641Z","caller":"traceutil/trace.go:171","msg":"trace[1962108846] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"390.783737ms","start":"2026-04-12T20:51:20.921832Z","end":"2026-04-12T20:51:21.312616Z","steps":["trace[1962108846] 'process raft request' (duration: 390.738706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:21.312793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:20.92182Z","time spent":"390.896389ms","remote":"127.0.0.1:51154","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-12T20:51:21.312959Z","caller":"traceutil/trace.go:171","msg":"trace[2141337111] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"392.85439ms","start":"2026-04-12T20:51:20.920094Z","end":"2026-04-12T20:51:21.312949Z","steps":["trace[2141337111] 'process raft request' (duration: 391.680845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:21.313021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:20.920072Z","time spent":"392.918762ms","remote":"127.0.0.1:51154","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-12T20:51:21.313132Z","caller":"traceutil/trace.go:171","msg":"trace[1867326212] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"391.43234ms","start":"2026-04-12T20:51:20.921682Z","end":"2026-04-12T20:51:21.313114Z","steps":["trace[1867326212] 'process raft request' (duration: 390.775427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:21.313217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:20.921674Z","time spent":"391.504861ms","remote":"127.0.0.1:51154","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-12T20:51:22.177419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610809,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:51:22.272163Z","caller":"traceutil/trace.go:171","msg":"trace[394932351] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"731.032176ms","start":"2026-04-12T20:51:21.541091Z","end":"2026-04-12T20:51:22.272123Z","steps":["trace[394932351] 'process raft request' (duration: 667.462865ms)","trace[394932351] 'compare' (duration: 63.115912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:22.272361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:21.541078Z","time spent":"731.177219ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:22.272541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.002597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:22.272622Z","caller":"traceutil/trace.go:171","msg":"trace[1522675263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"596.12713ms","start":"2026-04-12T20:51:21.676473Z","end":"2026-04-12T20:51:22.2726Z","steps":["trace[1522675263] 'agreement among raft nodes before linearized reading' (duration: 595.924975ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:22.272126Z","caller":"traceutil/trace.go:171","msg":"trace[1169047645] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"595.581918ms","start":"2026-04-12T20:51:21.676525Z","end":"2026-04-12T20:51:22.272107Z","steps":["trace[1169047645] 'read index received' (duration: 531.966716ms)","trace[1169047645] 'applied index is now lower than readState.Index' (duration: 63.613842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:22.272671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:21.676452Z","time spent":"596.208091ms","remote":"127.0.0.1:50878","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-12T20:51:22.272735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.752473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:22.273588Z","caller":"traceutil/trace.go:171","msg":"trace[765233905] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1247; }","duration":"179.828675ms","start":"2026-04-12T20:51:22.092928Z","end":"2026-04-12T20:51:22.272757Z","steps":["trace[765233905] 'agreement among raft nodes before linearized reading' (duration: 179.759794ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:22.796037Z","caller":"traceutil/trace.go:171","msg":"trace[1176583972] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"202.914732ms","start":"2026-04-12T20:51:22.593095Z","end":"2026-04-12T20:51:22.796009Z","steps":["trace[1176583972] 'process raft request' (duration: 202.759059ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:23.029605Z","caller":"traceutil/trace.go:171","msg":"trace[1909882469] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"350.646589ms","start":"2026-04-12T20:51:22.678936Z","end":"2026-04-12T20:51:23.029583Z","steps":["trace[1909882469] 'read index received' (duration: 116.925817ms)","trace[1909882469] 'applied index is now lower than readState.Index' (duration: 233.720142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:23.029738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.794593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:23.029762Z","caller":"traceutil/trace.go:171","msg":"trace[1620557909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"350.869683ms","start":"2026-04-12T20:51:22.678884Z","end":"2026-04-12T20:51:23.029754Z","steps":["trace[1620557909] 'agreement among raft nodes before linearized reading' (duration: 350.800862ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:23.029732Z","caller":"traceutil/trace.go:171","msg":"trace[295100125] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"430.927643ms","start":"2026-04-12T20:51:22.598758Z","end":"2026-04-12T20:51:23.029686Z","steps":["trace[295100125] 'process raft request' (duration: 378.967336ms)","trace[295100125] 'compare' (duration: 51.769523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:23.029792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:22.678862Z","time spent":"350.926325ms","remote":"127.0.0.1:50878","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-12T20:51:23.02992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:22.598736Z","time spent":"431.091007ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:23.043228Z","caller":"traceutil/trace.go:171","msg":"trace[11561822] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"132.318193ms","start":"2026-04-12T20:51:22.910885Z","end":"2026-04-12T20:51:23.043203Z","steps":["trace[11561822] 'process raft request' (duration: 132.165209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:23.822125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.475068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:23.822218Z","caller":"traceutil/trace.go:171","msg":"trace[1507419080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"143.60993ms","start":"2026-04-12T20:51:23.67858Z","end":"2026-04-12T20:51:23.82219Z","steps":["trace[1507419080] 'range keys from in-memory index tree' (duration: 143.357975ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:25.220378Z","caller":"traceutil/trace.go:171","msg":"trace[1003506454] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"103.713878ms","start":"2026-04-12T20:51:25.116611Z","end":"2026-04-12T20:51:25.220324Z","steps":["trace[1003506454] 'process raft request' (duration: 46.838998ms)","trace[1003506454] 'compare' (duration: 56.570064ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:25.521759Z","caller":"traceutil/trace.go:171","msg":"trace[881213477] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"141.890514ms","start":"2026-04-12T20:51:25.379828Z","end":"2026-04-12T20:51:25.521718Z","steps":["trace[881213477] 'process raft request' (duration: 141.67849ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:25.55322Z","caller":"traceutil/trace.go:171","msg":"trace[321045141] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"172.808737ms","start":"2026-04-12T20:51:25.380374Z","end":"2026-04-12T20:51:25.553183Z","steps":["trace[321045141] 'process raft request' (duration: 172.4755ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:25.553308Z","caller":"traceutil/trace.go:171","msg":"trace[1815350236] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"172.776496ms","start":"2026-04-12T20:51:25.380505Z","end":"2026-04-12T20:51:25.553281Z","steps":["trace[1815350236] 'process raft request' (duration: 172.715395ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:27.683197Z","caller":"traceutil/trace.go:171","msg":"trace[222346525] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"125.761401ms","start":"2026-04-12T20:51:27.557409Z","end":"2026-04-12T20:51:27.683171Z","steps":["trace[222346525] 'read index received' (duration: 125.505577ms)","trace[222346525] 'applied index is now lower than readState.Index' (duration: 254.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:27.68327Z","caller":"traceutil/trace.go:171","msg":"trace[1166915674] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"150.710118ms","start":"2026-04-12T20:51:27.532516Z","end":"2026-04-12T20:51:27.683226Z","steps":["trace[1166915674] 'process raft request' (duration: 150.475403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:27.683434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.006326ms","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-12T20:51:27.683476Z","caller":"traceutil/trace.go:171","msg":"trace[1483206539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"126.104238ms","start":"2026-04-12T20:51:27.557361Z","end":"2026-04-12T20:51:27.683466Z","steps":["trace[1483206539] 'agreement among raft nodes before linearized reading' (duration: 125.944775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:27.683658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.448863ms","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-12T20:51:27.683843Z","caller":"traceutil/trace.go:171","msg":"trace[1462239558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"125.637687ms","start":"2026-04-12T20:51:27.558133Z","end":"2026-04-12T20:51:27.683771Z","steps":["trace[1462239558] 'agreement among raft nodes before linearized reading' (duration: 125.25728ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:27.896856Z","caller":"traceutil/trace.go:171","msg":"trace[768465846] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"154.496342ms","start":"2026-04-12T20:51:27.742328Z","end":"2026-04-12T20:51:27.896824Z","steps":["trace[768465846] 'process raft request' (duration: 59.437472ms)","trace[768465846] 'compare' (duration: 94.903146ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:28.080931Z","caller":"traceutil/trace.go:171","msg":"trace[1758549651] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"176.0612ms","start":"2026-04-12T20:51:27.90484Z","end":"2026-04-12T20:51:28.080901Z","steps":["trace[1758549651] 'process raft request' (duration: 130.056975ms)","trace[1758549651] 'compare' (duration: 45.887152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:28.455962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.488023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:28.456053Z","caller":"traceutil/trace.go:171","msg":"trace[1436014220] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"307.769888ms","start":"2026-04-12T20:51:28.148267Z","end":"2026-04-12T20:51:28.456037Z","steps":["trace[1436014220] 'process raft request' (duration: 172.138382ms)","trace[1436014220] 'compare' (duration: 135.280679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:28.456097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.148251Z","time spent":"307.826439ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:29.069812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:51:29.570827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:51:29.632974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.169616547s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-12T20:51:29.972637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.338904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:29.972763Z","caller":"traceutil/trace.go:171","msg":"trace[510690854] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.509488681s","start":"2026-04-12T20:51:28.463246Z","end":"2026-04-12T20:51:29.972735Z","steps":["trace[510690854] 'process raft request' (duration: 1.169944303s)","trace[510690854] 'compare' (duration: 338.893636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:29.972825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.463221Z","time spent":"1.509575024s","remote":"127.0.0.1:51058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:30.505222Z","caller":"traceutil/trace.go:171","msg":"trace[614405172] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1340; }","duration":"1.935546639s","start":"2026-04-12T20:51:28.569657Z","end":"2026-04-12T20:51:30.505204Z","steps":["trace[614405172] 'read index received' (duration: 1.063548379s)","trace[614405172] 'applied index is now lower than readState.Index' (duration: 871.99762ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:30.505488Z","caller":"traceutil/trace.go:171","msg":"trace[1442746003] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"2.039797332s","start":"2026-04-12T20:51:28.465679Z","end":"2026-04-12T20:51:30.505477Z","steps":["trace[1442746003] 'process raft request' (duration: 1.995221786s)","trace[1442746003] 'compare' (duration: 43.725459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:30.505572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.465673Z","time spent":"2.039846753s","remote":"127.0.0.1:51166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:30.505782Z","caller":"traceutil/trace.go:171","msg":"trace[608077753] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"2.03517911s","start":"2026-04-12T20:51:28.470594Z","end":"2026-04-12T20:51:30.505773Z","steps":["trace[608077753] 'process raft request' (duration: 2.034514886s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.505825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.470588Z","time spent":"2.035216511s","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:30.50597Z","caller":"traceutil/trace.go:171","msg":"trace[41197034] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"1.382040469s","start":"2026-04-12T20:51:29.12392Z","end":"2026-04-12T20:51:30.50596Z","steps":["trace[41197034] 'process raft request' (duration: 1.381245522s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.123886Z","time spent":"1.38209989s","remote":"127.0.0.1:51154","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-12T20:51:30.50606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84652643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:30.506132Z","caller":"traceutil/trace.go:171","msg":"trace[1077552154] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1289; }","duration":"1.846607671s","start":"2026-04-12T20:51:28.659505Z","end":"2026-04-12T20:51:30.506113Z","steps":["trace[1077552154] 'agreement among raft nodes before linearized reading' (duration: 1.846493189s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.65945Z","time spent":"1.846709754s","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:51:30.506379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.477764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:30.506398Z","caller":"traceutil/trace.go:171","msg":"trace[1236799664] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1289; }","duration":"601.498835ms","start":"2026-04-12T20:51:29.904895Z","end":"2026-04-12T20:51:30.506394Z","steps":["trace[1236799664] 'agreement among raft nodes before linearized reading' (duration: 601.467144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.904803Z","time spent":"601.605026ms","remote":"127.0.0.1:51024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:51:30.506459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217470507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-12T20:51:30.506489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830781127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:30.506491Z","caller":"traceutil/trace.go:171","msg":"trace[1491844188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"1.217542209s","start":"2026-04-12T20:51:29.288941Z","end":"2026-04-12T20:51:30.506483Z","steps":["trace[1491844188] 'agreement among raft nodes before linearized reading' (duration: 1.217438317s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:30.506508Z","caller":"traceutil/trace.go:171","msg":"trace[1342622731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"1.830823308s","start":"2026-04-12T20:51:28.67568Z","end":"2026-04-12T20:51:30.506503Z","steps":["trace[1342622731] 'agreement among raft nodes before linearized reading' (duration: 1.830794597s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.675665Z","time spent":"1.830854018s","remote":"127.0.0.1:50878","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-12T20:51:30.506521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.288919Z","time spent":"1.217595809s","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-12T20:51:30.506656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.894983ms","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-12T20:51:30.506679Z","caller":"traceutil/trace.go:171","msg":"trace[338036892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"768.949164ms","start":"2026-04-12T20:51:29.73772Z","end":"2026-04-12T20:51:30.506669Z","steps":["trace[338036892] 'agreement among raft nodes before linearized reading' (duration: 768.890722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.5067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.737699Z","time spent":"768.996975ms","remote":"127.0.0.1:51154","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-12T20:51:30.506807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.39238ms","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-12T20:51:30.506824Z","caller":"traceutil/trace.go:171","msg":"trace[1488727045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"789.442791ms","start":"2026-04-12T20:51:29.717376Z","end":"2026-04-12T20:51:30.506819Z","steps":["trace[1488727045] 'agreement among raft nodes before linearized reading' (duration: 789.39115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.717356Z","time spent":"789.480181ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:51:30.50693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.813365ms","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-12T20:51:30.506951Z","caller":"traceutil/trace.go:171","msg":"trace[1822878295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"819.866065ms","start":"2026-04-12T20:51:29.687081Z","end":"2026-04-12T20:51:30.506947Z","steps":["trace[1822878295] 'agreement among raft nodes before linearized reading' (duration: 819.783174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.506966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:29.687061Z","time spent":"819.901997ms","remote":"127.0.0.1:51154","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-12T20:51:30.507055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937273484s","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-12T20:51:30.507153Z","caller":"traceutil/trace.go:171","msg":"trace[106515446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"1.937512399s","start":"2026-04-12T20:51:28.569621Z","end":"2026-04-12T20:51:30.507133Z","steps":["trace[106515446] 'agreement among raft nodes before linearized reading' (duration: 1.936348316s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.507231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:28.569601Z","time spent":"1.937617741s","remote":"127.0.0.1:51154","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":"info","ts":"2026-04-12T20:51:30.699811Z","caller":"traceutil/trace.go:171","msg":"trace[578030448] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"187.839293ms","start":"2026-04-12T20:51:30.511944Z","end":"2026-04-12T20:51:30.699783Z","steps":["trace[578030448] 'process raft request' (duration: 187.65228ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:30.700105Z","caller":"traceutil/trace.go:171","msg":"trace[27543596] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1345; }","duration":"183.090419ms","start":"2026-04-12T20:51:30.516991Z","end":"2026-04-12T20:51:30.700082Z","steps":["trace[27543596] 'read index received' (duration: 66.196315ms)","trace[27543596] 'applied index is now lower than readState.Index' (duration: 116.893254ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:30.700153Z","caller":"traceutil/trace.go:171","msg":"trace[383911275] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"188.111909ms","start":"2026-04-12T20:51:30.512029Z","end":"2026-04-12T20:51:30.700141Z","steps":["trace[383911275] 'process raft request' (duration: 187.719481ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:30.700175Z","caller":"traceutil/trace.go:171","msg":"trace[1547402241] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"187.321183ms","start":"2026-04-12T20:51:30.512805Z","end":"2026-04-12T20:51:30.700126Z","steps":["trace[1547402241] 'process raft request' (duration: 187.15896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:30.70032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.327114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:30.700241Z","caller":"traceutil/trace.go:171","msg":"trace[1459579929] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"185.284534ms","start":"2026-04-12T20:51:30.514943Z","end":"2026-04-12T20:51:30.700228Z","steps":["trace[1459579929] 'process raft request' (duration: 185.09797ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:30.700361Z","caller":"traceutil/trace.go:171","msg":"trace[1078688470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"183.405165ms","start":"2026-04-12T20:51:30.516945Z","end":"2026-04-12T20:51:30.70035Z","steps":["trace[1078688470] 'agreement among raft nodes before linearized reading' (duration: 183.313203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.176762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.295812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:32.176869Z","caller":"traceutil/trace.go:171","msg":"trace[1060711968] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1295; }","duration":"107.474496ms","start":"2026-04-12T20:51:32.069371Z","end":"2026-04-12T20:51:32.176845Z","steps":["trace[1060711968] 'count revisions from in-memory index tree' (duration: 107.16386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.176887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.713552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:32.176993Z","caller":"traceutil/trace.go:171","msg":"trace[2042668545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"497.877355ms","start":"2026-04-12T20:51:31.679094Z","end":"2026-04-12T20:51:32.176971Z","steps":["trace[2042668545] 'range keys from in-memory index tree' (duration: 497.62246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.177045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:31.679073Z","time spent":"497.961927ms","remote":"127.0.0.1:50878","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-12T20:51:32.687715Z","caller":"traceutil/trace.go:171","msg":"trace[2072413123] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"100.422565ms","start":"2026-04-12T20:51:32.587263Z","end":"2026-04-12T20:51:32.687686Z","steps":["trace[2072413123] 'read index received' (duration: 100.284162ms)","trace[2072413123] 'applied index is now lower than readState.Index' (duration: 137.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:32.687835Z","caller":"traceutil/trace.go:171","msg":"trace[1773897322] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"175.238463ms","start":"2026-04-12T20:51:32.512561Z","end":"2026-04-12T20:51:32.687799Z","steps":["trace[1773897322] 'process raft request' (duration: 174.952667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:32.688033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.753061ms","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-12T20:51:32.688087Z","caller":"traceutil/trace.go:171","msg":"trace[69315351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"100.850945ms","start":"2026-04-12T20:51:32.587225Z","end":"2026-04-12T20:51:32.688076Z","steps":["trace[69315351] 'agreement among raft nodes before linearized reading' (duration: 100.562779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.205177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:51:33.706205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741383835610914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:51:33.814768Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.123043601s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:51:33.871403Z","caller":"traceutil/trace.go:171","msg":"trace[778441044] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.179743598s","start":"2026-04-12T20:51:32.691633Z","end":"2026-04-12T20:51:33.871377Z","steps":["trace[778441044] 'process raft request' (duration: 1.123321986s)","trace[778441044] 'compare' (duration: 55.717747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:33.871555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.691612Z","time spent":"1.179874611s","remote":"127.0.0.1:51154","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-12T20:51:33.871606Z","caller":"traceutil/trace.go:171","msg":"trace[723855401] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"1.166455114s","start":"2026-04-12T20:51:32.705084Z","end":"2026-04-12T20:51:33.871539Z","steps":["trace[723855401] 'read index received' (duration: 1.109884299s)","trace[723855401] 'applied index is now lower than readState.Index' (duration: 56.568235ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:33.871636Z","caller":"traceutil/trace.go:171","msg":"trace[763020655] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"642.372247ms","start":"2026-04-12T20:51:33.229239Z","end":"2026-04-12T20:51:33.871611Z","steps":["trace[763020655] 'process raft request' (duration: 642.062271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.871791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.229208Z","time spent":"642.51028ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:33.871829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.125578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:33.871851Z","caller":"traceutil/trace.go:171","msg":"trace[1615276767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"193.17828ms","start":"2026-04-12T20:51:33.678665Z","end":"2026-04-12T20:51:33.871843Z","steps":["trace[1615276767] 'agreement among raft nodes before linearized reading' (duration: 193.146679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.871791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.380619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T20:51:33.87185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166743859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-12T20:51:33.871916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165354762s","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-12T20:51:33.871924Z","caller":"traceutil/trace.go:171","msg":"trace[2009152419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.166855381s","start":"2026-04-12T20:51:32.70505Z","end":"2026-04-12T20:51:33.871905Z","steps":["trace[2009152419] 'agreement among raft nodes before linearized reading' (duration: 1.166638307s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.871941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166737499s","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-12T20:51:33.871964Z","caller":"traceutil/trace.go:171","msg":"trace[1622408868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"1.165449454s","start":"2026-04-12T20:51:32.7065Z","end":"2026-04-12T20:51:33.871949Z","steps":["trace[1622408868] 'agreement among raft nodes before linearized reading' (duration: 1.165329062s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.871968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.705033Z","time spent":"1.166926113s","remote":"127.0.0.1:51154","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-12T20:51:33.871949Z","caller":"traceutil/trace.go:171","msg":"trace[1962868677] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1298; }","duration":"714.528961ms","start":"2026-04-12T20:51:33.157379Z","end":"2026-04-12T20:51:33.871908Z","steps":["trace[1962868677] 'agreement among raft nodes before linearized reading' (duration: 714.384658ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:33.871992Z","caller":"traceutil/trace.go:171","msg":"trace[1872566524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"1.166841631s","start":"2026-04-12T20:51:32.705134Z","end":"2026-04-12T20:51:33.871976Z","steps":["trace[1872566524] 'agreement among raft nodes before linearized reading' (duration: 1.166634877s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.872005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.706476Z","time spent":"1.165520755s","remote":"127.0.0.1:51154","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-12T20:51:33.872043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.705086Z","time spent":"1.166944893s","remote":"127.0.0.1:51154","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-12T20:51:33.872064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:33.157365Z","time spent":"714.659663ms","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:51:33.872079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165585956s","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-12T20:51:33.872109Z","caller":"traceutil/trace.go:171","msg":"trace[842049800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"1.165636947s","start":"2026-04-12T20:51:32.706464Z","end":"2026-04-12T20:51:33.872101Z","steps":["trace[842049800] 'agreement among raft nodes before linearized reading' (duration: 1.165537895s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:33.87213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:32.706454Z","time spent":"1.165671158s","remote":"127.0.0.1:51154","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-12T20:51:36.070568Z","caller":"traceutil/trace.go:171","msg":"trace[871028940] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"157.766385ms","start":"2026-04-12T20:51:35.912776Z","end":"2026-04-12T20:51:36.070542Z","steps":["trace[871028940] 'read index received' (duration: 157.595752ms)","trace[871028940] 'applied index is now lower than readState.Index' (duration: 169.583µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:36.070695Z","caller":"traceutil/trace.go:171","msg":"trace[1190430045] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"185.792233ms","start":"2026-04-12T20:51:35.884891Z","end":"2026-04-12T20:51:36.070683Z","steps":["trace[1190430045] 'process raft request' (duration: 185.497037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:36.070931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.148453ms","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-12T20:51:36.070978Z","caller":"traceutil/trace.go:171","msg":"trace[101256037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"158.247615ms","start":"2026-04-12T20:51:35.912717Z","end":"2026-04-12T20:51:36.070965Z","steps":["trace[101256037] 'agreement among raft nodes before linearized reading' (duration: 158.101592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:36.465028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.407625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:36.465132Z","caller":"traceutil/trace.go:171","msg":"trace[603299270] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"389.45633ms","start":"2026-04-12T20:51:36.075654Z","end":"2026-04-12T20:51:36.46511Z","steps":["trace[603299270] 'process raft request' (duration: 42.882503ms)","trace[603299270] 'compare' (duration: 346.140479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:36.465221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:36.075634Z","time spent":"389.530031ms","remote":"127.0.0.1:51154","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-12T20:51:36.495875Z","caller":"traceutil/trace.go:171","msg":"trace[159254254] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"419.745303ms","start":"2026-04-12T20:51:36.076098Z","end":"2026-04-12T20:51:36.495843Z","steps":["trace[159254254] 'process raft request' (duration: 419.492208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:36.496047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:36.07607Z","time spent":"419.890005ms","remote":"127.0.0.1:51154","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-12T20:51:36.496172Z","caller":"traceutil/trace.go:171","msg":"trace[2118291292] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"419.835314ms","start":"2026-04-12T20:51:36.076312Z","end":"2026-04-12T20:51:36.496147Z","steps":["trace[2118291292] 'process raft request' (duration: 419.725572ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:36.49621Z","caller":"traceutil/trace.go:171","msg":"trace[2006404128] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"420.053269ms","start":"2026-04-12T20:51:36.076137Z","end":"2026-04-12T20:51:36.49619Z","steps":["trace[2006404128] 'process raft request' (duration: 419.60907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:36.496286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:36.076113Z","time spent":"420.13245ms","remote":"127.0.0.1:51154","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-12T20:51:36.4963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:36.07626Z","time spent":"419.971776ms","remote":"127.0.0.1:51154","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-12T20:51:37.256428Z","caller":"traceutil/trace.go:171","msg":"trace[692108950] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"257.14753ms","start":"2026-04-12T20:51:36.999246Z","end":"2026-04-12T20:51:37.256393Z","steps":["trace[692108950] 'read index received' (duration: 249.173282ms)","trace[692108950] 'applied index is now lower than readState.Index' (duration: 7.973128ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:37.256485Z","caller":"traceutil/trace.go:171","msg":"trace[285931153] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"303.213986ms","start":"2026-04-12T20:51:36.953226Z","end":"2026-04-12T20:51:37.25644Z","steps":["trace[285931153] 'process raft request' (duration: 295.178827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:37.256686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:36.953195Z","time spent":"303.366908ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:37.25789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.323193ms","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-12T20:51:37.257988Z","caller":"traceutil/trace.go:171","msg":"trace[1967501880] 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:1310; }","duration":"258.752552ms","start":"2026-04-12T20:51:36.99919Z","end":"2026-04-12T20:51:37.257942Z","steps":["trace[1967501880] 'agreement among raft nodes before linearized reading' (duration: 257.335854ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:38.94548Z","caller":"traceutil/trace.go:171","msg":"trace[1892363894] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"124.190079ms","start":"2026-04-12T20:51:38.821265Z","end":"2026-04-12T20:51:38.945455Z","steps":["trace[1892363894] 'process raft request' (duration: 124.059176ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:40.898391Z","caller":"traceutil/trace.go:171","msg":"trace[896571445] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"417.851315ms","start":"2026-04-12T20:51:40.480513Z","end":"2026-04-12T20:51:40.898364Z","steps":["trace[896571445] 'read index received' (duration: 417.664951ms)","trace[896571445] 'applied index is now lower than readState.Index' (duration: 184.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:40.898508Z","caller":"traceutil/trace.go:171","msg":"trace[33654167] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"808.743225ms","start":"2026-04-12T20:51:40.089732Z","end":"2026-04-12T20:51:40.898475Z","steps":["trace[33654167] 'process raft request' (duration: 808.474499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:40.898707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.089716Z","time spent":"808.881187ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:40.898895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.369365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-12T20:51:40.898895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.92983ms","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-12T20:51:40.898956Z","caller":"traceutil/trace.go:171","msg":"trace[616282290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"418.491078ms","start":"2026-04-12T20:51:40.480451Z","end":"2026-04-12T20:51:40.898942Z","steps":["trace[616282290] 'agreement among raft nodes before linearized reading' (duration: 418.06998ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:40.898981Z","caller":"traceutil/trace.go:171","msg":"trace[981658336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"390.089564ms","start":"2026-04-12T20:51:40.508872Z","end":"2026-04-12T20:51:40.898961Z","steps":["trace[981658336] 'agreement among raft nodes before linearized reading' (duration: 389.842089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:40.899033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.48043Z","time spent":"418.5629ms","remote":"127.0.0.1:51154","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-12T20:51:40.89906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.508841Z","time spent":"390.202996ms","remote":"127.0.0.1:51154","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-12T20:51:40.899142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.316998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:40.8992Z","caller":"traceutil/trace.go:171","msg":"trace[939624317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"220.405351ms","start":"2026-04-12T20:51:40.678782Z","end":"2026-04-12T20:51:40.899187Z","steps":["trace[939624317] 'agreement among raft nodes before linearized reading' (duration: 220.317749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:40.899313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.38473ms","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-12T20:51:40.899352Z","caller":"traceutil/trace.go:171","msg":"trace[1564649191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"387.460381ms","start":"2026-04-12T20:51:40.511881Z","end":"2026-04-12T20:51:40.899341Z","steps":["trace[1564649191] 'agreement among raft nodes before linearized reading' (duration: 387.36396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:40.899386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.511859Z","time spent":"387.520982ms","remote":"127.0.0.1:51154","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-12T20:51:40.899304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.859689ms","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-12T20:51:40.899479Z","caller":"traceutil/trace.go:171","msg":"trace[127075028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"387.062563ms","start":"2026-04-12T20:51:40.512396Z","end":"2026-04-12T20:51:40.899458Z","steps":["trace[127075028] 'agreement among raft nodes before linearized reading' (duration: 386.653775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:40.899611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.512384Z","time spent":"387.212755ms","remote":"127.0.0.1:51154","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-12T20:51:41.174162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.420683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-12T20:51:41.174094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.860402ms","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-12T20:51:41.174268Z","caller":"traceutil/trace.go:171","msg":"trace[1267137277] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1331; }","duration":"202.220559ms","start":"2026-04-12T20:51:40.972013Z","end":"2026-04-12T20:51:41.174233Z","steps":["trace[1267137277] 'range keys from in-memory index tree' (duration: 201.842772ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:41.174339Z","caller":"traceutil/trace.go:171","msg":"trace[470529686] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"270.734041ms","start":"2026-04-12T20:51:40.903589Z","end":"2026-04-12T20:51:41.174323Z","steps":["trace[470529686] 'process raft request' (duration: 69.085504ms)","trace[470529686] 'compare' (duration: 201.019774ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:41.174397Z","caller":"traceutil/trace.go:171","msg":"trace[1974169802] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"270.769053ms","start":"2026-04-12T20:51:40.903614Z","end":"2026-04-12T20:51:41.174383Z","steps":["trace[1974169802] 'process raft request' (duration: 270.645609ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:41.319723Z","caller":"traceutil/trace.go:171","msg":"trace[1565489938] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"415.881616ms","start":"2026-04-12T20:51:40.903822Z","end":"2026-04-12T20:51:41.319704Z","steps":["trace[1565489938] 'process raft request' (duration: 415.801774ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:41.319733Z","caller":"traceutil/trace.go:171","msg":"trace[168745525] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"415.922617ms","start":"2026-04-12T20:51:40.903776Z","end":"2026-04-12T20:51:41.319698Z","steps":["trace[168745525] 'process raft request' (duration: 415.497018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:41.319863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.903806Z","time spent":"415.977397ms","remote":"127.0.0.1:51154","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-12T20:51:41.319879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.90377Z","time spent":"416.027099ms","remote":"127.0.0.1:51154","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-12T20:51:41.319946Z","caller":"traceutil/trace.go:171","msg":"trace[87790069] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"368.499285ms","start":"2026-04-12T20:51:40.95142Z","end":"2026-04-12T20:51:41.31992Z","steps":["trace[87790069] 'process raft request' (duration: 368.245819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:41.32018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:40.951404Z","time spent":"368.686228ms","remote":"127.0.0.1:51154","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-12T20:51:42.753224Z","caller":"traceutil/trace.go:171","msg":"trace[2012548842] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"201.585716ms","start":"2026-04-12T20:51:42.551614Z","end":"2026-04-12T20:51:42.753199Z","steps":["trace[2012548842] 'read index received' (duration: 201.518465ms)","trace[2012548842] 'applied index is now lower than readState.Index' (duration: 66.281µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:42.753416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.775121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:42.753487Z","caller":"traceutil/trace.go:171","msg":"trace[1849839950] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1338; }","duration":"201.907362ms","start":"2026-04-12T20:51:42.551562Z","end":"2026-04-12T20:51:42.753469Z","steps":["trace[1849839950] 'agreement among raft nodes before linearized reading' (duration: 201.772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:42.75373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.843382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:42.75378Z","caller":"traceutil/trace.go:171","msg":"trace[617030036] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1338; }","duration":"126.27833ms","start":"2026-04-12T20:51:42.627489Z","end":"2026-04-12T20:51:42.753767Z","steps":["trace[617030036] 'agreement among raft nodes before linearized reading' (duration: 125.860641ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:42.753274Z","caller":"traceutil/trace.go:171","msg":"trace[1953447697] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"313.808486ms","start":"2026-04-12T20:51:42.439401Z","end":"2026-04-12T20:51:42.753209Z","steps":["trace[1953447697] 'process raft request' (duration: 313.686154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:42.754065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:42.439378Z","time spent":"314.519762ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:44.283472Z","caller":"traceutil/trace.go:171","msg":"trace[901427203] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"176.727502ms","start":"2026-04-12T20:51:44.106718Z","end":"2026-04-12T20:51:44.283446Z","steps":["trace[901427203] 'process raft request' (duration: 176.558199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.410866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.542609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-12T20:51:47.411152Z","caller":"traceutil/trace.go:171","msg":"trace[1123118483] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"359.70046ms","start":"2026-04-12T20:51:47.051418Z","end":"2026-04-12T20:51:47.411118Z","steps":["trace[1123118483] 'read index received' (duration: 181.888506ms)","trace[1123118483] 'applied index is now lower than readState.Index' (duration: 177.809653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:47.411432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.996575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-12T20:51:47.411473Z","caller":"traceutil/trace.go:171","msg":"trace[1666461811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1380; }","duration":"360.106057ms","start":"2026-04-12T20:51:47.051357Z","end":"2026-04-12T20:51:47.411463Z","steps":["trace[1666461811] 'agreement among raft nodes before linearized reading' (duration: 359.908703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.411517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.051333Z","time spent":"360.175238ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-12T20:51:47.411641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.495884ms","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-12T20:51:47.411741Z","caller":"traceutil/trace.go:171","msg":"trace[1289696909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"198.638707ms","start":"2026-04-12T20:51:47.213062Z","end":"2026-04-12T20:51:47.411701Z","steps":["trace[1289696909] 'agreement among raft nodes before linearized reading' (duration: 198.379652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.412308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.209309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-12T20:51:47.412364Z","caller":"traceutil/trace.go:171","msg":"trace[998123240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"199.296091ms","start":"2026-04-12T20:51:47.213048Z","end":"2026-04-12T20:51:47.412344Z","steps":["trace[998123240] 'agreement among raft nodes before linearized reading' (duration: 198.298451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.822305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:51:47.82257Z","caller":"traceutil/trace.go:171","msg":"trace[1918393416] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"404.227545ms","start":"2026-04-12T20:51:47.418324Z","end":"2026-04-12T20:51:47.822552Z","steps":["trace[1918393416] 'process raft request' (duration: 404.094052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.822741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.41831Z","time spent":"404.299906ms","remote":"127.0.0.1:51154","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-12T20:51:47.822719Z","caller":"traceutil/trace.go:171","msg":"trace[2006140078] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"405.309247ms","start":"2026-04-12T20:51:47.417373Z","end":"2026-04-12T20:51:47.822683Z","steps":["trace[2006140078] 'process raft request' (duration: 198.506516ms)","trace[2006140078] 'compare' (duration: 206.101036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:51:47.822889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.417347Z","time spent":"405.443429ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:47.82295Z","caller":"traceutil/trace.go:171","msg":"trace[828344383] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"403.392847ms","start":"2026-04-12T20:51:47.419543Z","end":"2026-04-12T20:51:47.822936Z","steps":["trace[828344383] 'process raft request' (duration: 403.165373ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:51:47.822944Z","caller":"traceutil/trace.go:171","msg":"trace[115038601] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"404.082972ms","start":"2026-04-12T20:51:47.41882Z","end":"2026-04-12T20:51:47.822903Z","steps":["trace[115038601] 'process raft request' (duration: 403.646723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.823045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.419535Z","time spent":"403.446868ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:51:47.823115Z","caller":"traceutil/trace.go:171","msg":"trace[825023489] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"402.723924ms","start":"2026-04-12T20:51:47.420357Z","end":"2026-04-12T20:51:47.82308Z","steps":["trace[825023489] 'process raft request' (duration: 402.50746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.823319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.418802Z","time spent":"404.247405ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:51:47.82332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:51:47.42035Z","time spent":"402.867307ms","remote":"127.0.0.1:51154","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-12T20:51:47.826313Z","caller":"traceutil/trace.go:171","msg":"trace[2089342114] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1439; }","duration":"187.025337ms","start":"2026-04-12T20:51:47.639223Z","end":"2026-04-12T20:51:47.826249Z","steps":["trace[2089342114] 'read index received' (duration: 84.554061ms)","trace[2089342114] 'applied index is now lower than readState.Index' (duration: 102.470496ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:51:47.826507Z","caller":"traceutil/trace.go:171","msg":"trace[1835617336] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"208.740669ms","start":"2026-04-12T20:51:47.617756Z","end":"2026-04-12T20:51:47.826497Z","steps":["trace[1835617336] 'process raft request' (duration: 208.394401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.826974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.734241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-12T20:51:47.827254Z","caller":"traceutil/trace.go:171","msg":"trace[1191951572] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1386; }","duration":"188.057538ms","start":"2026-04-12T20:51:47.639168Z","end":"2026-04-12T20:51:47.827226Z","steps":["trace[1191951572] 'agreement among raft nodes before linearized reading' (duration: 187.738582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.827115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.42471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:51:47.827556Z","caller":"traceutil/trace.go:171","msg":"trace[257838696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"149.865209ms","start":"2026-04-12T20:51:47.677673Z","end":"2026-04-12T20:51:47.827538Z","steps":["trace[257838696] 'agreement among raft nodes before linearized reading' (duration: 149.39097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:51:47.827262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.988696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115545"} {"level":"info","ts":"2026-04-12T20:51:47.827704Z","caller":"traceutil/trace.go:171","msg":"trace[599922487] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1386; }","duration":"188.456776ms","start":"2026-04-12T20:51:47.639232Z","end":"2026-04-12T20:51:47.827689Z","steps":["trace[599922487] 'agreement among raft nodes before linearized reading' (duration: 187.493227ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:08.378624Z","caller":"traceutil/trace.go:171","msg":"trace[1077354025] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"125.647207ms","start":"2026-04-12T20:52:08.252949Z","end":"2026-04-12T20:52:08.378596Z","steps":["trace[1077354025] 'process raft request' (duration: 125.508564ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:09.66898Z","caller":"traceutil/trace.go:171","msg":"trace[2007081356] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"105.914896ms","start":"2026-04-12T20:52:09.563027Z","end":"2026-04-12T20:52:09.668942Z","steps":["trace[2007081356] 'process raft request' (duration: 105.700812ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:21.883027Z","caller":"traceutil/trace.go:171","msg":"trace[632906879] linearizableReadLoop","detail":"{readStateIndex:1604; appliedIndex:1603; }","duration":"203.29836ms","start":"2026-04-12T20:52:21.67968Z","end":"2026-04-12T20:52:21.882979Z","steps":["trace[632906879] 'read index received' (duration: 201.355972ms)","trace[632906879] 'applied index is now lower than readState.Index' (duration: 1.941028ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:52:21.883036Z","caller":"traceutil/trace.go:171","msg":"trace[1397898692] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"327.673883ms","start":"2026-04-12T20:52:21.555332Z","end":"2026-04-12T20:52:21.883006Z","steps":["trace[1397898692] 'process raft request' (duration: 325.687964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:52:21.88317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.497784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:52:21.883216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:52:21.555307Z","time spent":"327.812655ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:52:21.883277Z","caller":"traceutil/trace.go:171","msg":"trace[490563132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1538; }","duration":"203.652799ms","start":"2026-04-12T20:52:21.679601Z","end":"2026-04-12T20:52:21.883254Z","steps":["trace[490563132] 'agreement among raft nodes before linearized reading' (duration: 203.442764ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:21.926959Z","caller":"traceutil/trace.go:171","msg":"trace[1051053942] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"185.521498ms","start":"2026-04-12T20:52:21.741389Z","end":"2026-04-12T20:52:21.92691Z","steps":["trace[1051053942] 'process raft request' (duration: 185.264283ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:22.200929Z","caller":"traceutil/trace.go:171","msg":"trace[1519483094] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"166.629352ms","start":"2026-04-12T20:52:22.034272Z","end":"2026-04-12T20:52:22.200901Z","steps":["trace[1519483094] 'process raft request' (duration: 166.52731ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:22.201014Z","caller":"traceutil/trace.go:171","msg":"trace[910665944] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"167.182442ms","start":"2026-04-12T20:52:22.033796Z","end":"2026-04-12T20:52:22.200978Z","steps":["trace[910665944] 'process raft request' (duration: 148.468741ms)","trace[910665944] 'compare' (duration: 18.385585ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:52:22.201151Z","caller":"traceutil/trace.go:171","msg":"trace[13997808] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"166.474698ms","start":"2026-04-12T20:52:22.034653Z","end":"2026-04-12T20:52:22.201127Z","steps":["trace[13997808] 'process raft request' (duration: 166.211232ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:52:25.121437Z","caller":"traceutil/trace.go:171","msg":"trace[70541262] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"101.489586ms","start":"2026-04-12T20:52:25.019923Z","end":"2026-04-12T20:52:25.121412Z","steps":["trace[70541262] 'process raft request' (duration: 101.335193ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:10.039056Z","caller":"traceutil/trace.go:171","msg":"trace[1147288451] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"103.387041ms","start":"2026-04-12T20:53:09.935643Z","end":"2026-04-12T20:53:10.03903Z","steps":["trace[1147288451] 'process raft request' (duration: 103.244578ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:53:42.398521Z","caller":"traceutil/trace.go:171","msg":"trace[290565931] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"101.588135ms","start":"2026-04-12T20:53:42.296905Z","end":"2026-04-12T20:53:42.398493Z","steps":["trace[290565931] 'process raft request' (duration: 101.443342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:53:56.654106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.658368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-12T20:53:56.654204Z","caller":"traceutil/trace.go:171","msg":"trace[1172817958] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1972; }","duration":"108.814912ms","start":"2026-04-12T20:53:56.545369Z","end":"2026-04-12T20:53:56.654184Z","steps":["trace[1172817958] 'range keys from in-memory index tree' (duration: 108.465995ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:03.441334Z","caller":"traceutil/trace.go:171","msg":"trace[474399151] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"125.281265ms","start":"2026-04-12T20:54:03.316024Z","end":"2026-04-12T20:54:03.441305Z","steps":["trace[474399151] 'process raft request' (duration: 125.100982ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:03.446264Z","caller":"traceutil/trace.go:171","msg":"trace[1068196330] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"126.707874ms","start":"2026-04-12T20:54:03.319538Z","end":"2026-04-12T20:54:03.446246Z","steps":["trace[1068196330] 'process raft request' (duration: 126.610182ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:04.695592Z","caller":"traceutil/trace.go:171","msg":"trace[1627862408] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"170.598759ms","start":"2026-04-12T20:54:04.524968Z","end":"2026-04-12T20:54:04.695567Z","steps":["trace[1627862408] 'process raft request' (duration: 170.007857ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:14.888248Z","caller":"traceutil/trace.go:171","msg":"trace[410531404] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"127.843444ms","start":"2026-04-12T20:54:14.760147Z","end":"2026-04-12T20:54:14.887991Z","steps":["trace[410531404] 'process raft request' (duration: 127.571779ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:15.064929Z","caller":"traceutil/trace.go:171","msg":"trace[43197625] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"171.548117ms","start":"2026-04-12T20:54:14.893357Z","end":"2026-04-12T20:54:15.064905Z","steps":["trace[43197625] 'process raft request' (duration: 171.455015ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:15.064991Z","caller":"traceutil/trace.go:171","msg":"trace[1303649363] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"171.662349ms","start":"2026-04-12T20:54:14.893288Z","end":"2026-04-12T20:54:15.064951Z","steps":["trace[1303649363] 'process raft request' (duration: 171.384344ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:15.065138Z","caller":"traceutil/trace.go:171","msg":"trace[2045393794] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"170.864994ms","start":"2026-04-12T20:54:14.894241Z","end":"2026-04-12T20:54:15.065106Z","steps":["trace[2045393794] 'process raft request' (duration: 170.608969ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:33.183194Z","caller":"traceutil/trace.go:171","msg":"trace[432364098] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"120.457904ms","start":"2026-04-12T20:54:33.062701Z","end":"2026-04-12T20:54:33.183159Z","steps":["trace[432364098] 'process raft request' (duration: 98.378453ms)","trace[432364098] 'compare' (duration: 21.897097ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:54:33.183898Z","caller":"traceutil/trace.go:171","msg":"trace[135635099] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"120.959404ms","start":"2026-04-12T20:54:33.062918Z","end":"2026-04-12T20:54:33.183878Z","steps":["trace[135635099] 'process raft request' (duration: 120.826131ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:53.657976Z","caller":"traceutil/trace.go:171","msg":"trace[548135719] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"163.39554ms","start":"2026-04-12T20:54:53.49455Z","end":"2026-04-12T20:54:53.657946Z","steps":["trace[548135719] 'process raft request' (duration: 163.126524ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:53.659274Z","caller":"traceutil/trace.go:171","msg":"trace[717893717] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2351; }","duration":"159.563717ms","start":"2026-04-12T20:54:53.499685Z","end":"2026-04-12T20:54:53.659249Z","steps":["trace[717893717] 'read index received' (duration: 157.918555ms)","trace[717893717] 'applied index is now lower than readState.Index' (duration: 1.644082ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:54:53.659407Z","caller":"traceutil/trace.go:171","msg":"trace[1692253150] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"162.598344ms","start":"2026-04-12T20:54:53.496795Z","end":"2026-04-12T20:54:53.659394Z","steps":["trace[1692253150] 'process raft request' (duration: 162.317989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:54:53.659772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.060966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-12T20:54:53.659914Z","caller":"traceutil/trace.go:171","msg":"trace[757216397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2256; }","duration":"160.234108ms","start":"2026-04-12T20:54:53.499657Z","end":"2026-04-12T20:54:53.659891Z","steps":["trace[757216397] 'agreement among raft nodes before linearized reading' (duration: 159.964043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:54:53.659892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.472119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-12T20:54:53.660012Z","caller":"traceutil/trace.go:171","msg":"trace[164298752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2256; }","duration":"155.647411ms","start":"2026-04-12T20:54:53.504342Z","end":"2026-04-12T20:54:53.659989Z","steps":["trace[164298752] 'agreement among raft nodes before linearized reading' (duration: 155.316745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:54:53.659812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.120173ms","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-12T20:54:53.660101Z","caller":"traceutil/trace.go:171","msg":"trace[364829585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2256; }","duration":"153.435899ms","start":"2026-04-12T20:54:53.506645Z","end":"2026-04-12T20:54:53.660081Z","steps":["trace[364829585] 'agreement among raft nodes before linearized reading' (duration: 153.031921ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:54:59.841336Z","caller":"traceutil/trace.go:171","msg":"trace[89567682] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"128.529936ms","start":"2026-04-12T20:54:59.712781Z","end":"2026-04-12T20:54:59.841311Z","steps":["trace[89567682] 'process raft request' (duration: 71.525611ms)","trace[89567682] 'compare' (duration: 56.643628ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:54:59.841523Z","caller":"traceutil/trace.go:171","msg":"trace[116907637] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"125.510438ms","start":"2026-04-12T20:54:59.716006Z","end":"2026-04-12T20:54:59.841516Z","steps":["trace[116907637] 'process raft request' (duration: 125.265074ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:55:01.010616Z","caller":"traceutil/trace.go:171","msg":"trace[1020006661] linearizableReadLoop","detail":"{readStateIndex:2394; appliedIndex:2393; }","duration":"331.267083ms","start":"2026-04-12T20:55:00.679325Z","end":"2026-04-12T20:55:01.010592Z","steps":["trace[1020006661] 'read index received' (duration: 291.961165ms)","trace[1020006661] 'applied index is now lower than readState.Index' (duration: 39.305118ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:55:01.010833Z","caller":"traceutil/trace.go:171","msg":"trace[938161992] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"504.067961ms","start":"2026-04-12T20:55:00.506735Z","end":"2026-04-12T20:55:01.010803Z","steps":["trace[938161992] 'process raft request' (duration: 464.60808ms)","trace[938161992] 'compare' (duration: 39.152195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:55:01.010988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:00.506703Z","time spent":"504.204853ms","remote":"127.0.0.1:51154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:55:01.010837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.490097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:55:01.011782Z","caller":"traceutil/trace.go:171","msg":"trace[1224580804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2296; }","duration":"332.494627ms","start":"2026-04-12T20:55:00.679267Z","end":"2026-04-12T20:55:01.011762Z","steps":["trace[1224580804] 'agreement among raft nodes before linearized reading' (duration: 331.506168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:01.011925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:00.67924Z","time spent":"332.631269ms","remote":"127.0.0.1:50878","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-12T20:55:31.954098Z","caller":"traceutil/trace.go:171","msg":"trace[1820027469] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"103.482808ms","start":"2026-04-12T20:55:31.850581Z","end":"2026-04-12T20:55:31.954063Z","steps":["trace[1820027469] 'process raft request' (duration: 99.623405ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:55:31.954269Z","caller":"traceutil/trace.go:171","msg":"trace[1370772446] linearizableReadLoop","detail":"{readStateIndex:2553; appliedIndex:2550; }","duration":"103.108322ms","start":"2026-04-12T20:55:31.851124Z","end":"2026-04-12T20:55:31.954232Z","steps":["trace[1370772446] 'read index received' (duration: 3.537217ms)","trace[1370772446] 'applied index is now lower than readState.Index' (duration: 99.568435ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:55:31.954391Z","caller":"traceutil/trace.go:171","msg":"trace[654494824] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"103.47794ms","start":"2026-04-12T20:55:31.850884Z","end":"2026-04-12T20:55:31.954362Z","steps":["trace[654494824] 'process raft request' (duration: 103.123902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:31.954512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.355817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-12T20:55:31.954599Z","caller":"traceutil/trace.go:171","msg":"trace[2086975958] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:2447; }","duration":"103.470159ms","start":"2026-04-12T20:55:31.851109Z","end":"2026-04-12T20:55:31.954579Z","steps":["trace[2086975958] 'agreement among raft nodes before linearized reading' (duration: 103.302196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:36.043067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.455296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:55:36.043145Z","caller":"traceutil/trace.go:171","msg":"trace[932002372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2462; }","duration":"363.585948ms","start":"2026-04-12T20:55:35.67954Z","end":"2026-04-12T20:55:36.043126Z","steps":["trace[932002372] 'range keys from in-memory index tree' (duration: 363.286843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:36.043269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:35.679517Z","time spent":"363.734612ms","remote":"127.0.0.1:50894","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-12T20:55:36.04333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.986789ms","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-12T20:55:36.043423Z","caller":"traceutil/trace.go:171","msg":"trace[2066366756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2462; }","duration":"175.126472ms","start":"2026-04-12T20:55:35.868273Z","end":"2026-04-12T20:55:36.0434Z","steps":["trace[2066366756] 'range keys from in-memory index tree' (duration: 174.812237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:36.043584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.020081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-2lzbv\" ","response":"range_response_count:1 size:11390"} {"level":"info","ts":"2026-04-12T20:55:36.043726Z","caller":"traceutil/trace.go:171","msg":"trace[656177670] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-2lzbv; range_end:; response_count:1; response_revision:2462; }","duration":"462.535631ms","start":"2026-04-12T20:55:35.581167Z","end":"2026-04-12T20:55:36.043702Z","steps":["trace[656177670] 'range keys from in-memory index tree' (duration: 461.760476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:55:36.043823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:55:35.581149Z","time spent":"462.659693ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11413,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-2lzbv\" "}