{"level":"warn","ts":"2026-04-28T03:24:41.234663Z","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-28T03:24:41.234808Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.81:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.81:2380","--initial-cluster=instance=https://199.19.213.81:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.81:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.81: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-28T03:24:41.234918Z","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-28T03:24:41.234944Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-04-28T03:24:41.234972Z","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-28T03:24:41.236263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"]} {"level":"info","ts":"2026-04-28T03:24:41.236391Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.81: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-28T03:24:41.24178Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.987448ms"} {"level":"info","ts":"2026-04-28T03:24:41.249745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1627b0b72e417489","cluster-id":"d42e5eae8a1b814c"} {"level":"info","ts":"2026-04-28T03:24:41.249833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:24:41.249859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 0"} {"level":"info","ts":"2026-04-28T03:24:41.249876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1627b0b72e417489 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:24:41.249901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 1"} {"level":"info","ts":"2026-04-28T03:24:41.24995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"warn","ts":"2026-04-28T03:24:41.259426Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:24:41.260645Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:24:41.261746Z","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-28T03:24:41.263679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1627b0b72e417489","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:24:41.264198Z","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-28T03:24:41.2643Z","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-28T03:24:41.264312Z","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-28T03:24:41.264657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"info","ts":"2026-04-28T03:24:41.265595Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","added-peer-id":"1627b0b72e417489","added-peer-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-04-28T03:24:41.264101Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1627b0b72e417489","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:24:41.268283Z","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-28T03:24:41.268387Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-04-28T03:24:41.268439Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-04-28T03:24:41.2687Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1627b0b72e417489","initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:24:41.268769Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:24:41.550697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:24:41.550768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:24:41.550799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgPreVoteResp from 1627b0b72e417489 at term 1"} {"level":"info","ts":"2026-04-28T03:24:41.550818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:24:41.55083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgVoteResp from 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-04-28T03:24:41.550846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became leader at term 2"} {"level":"info","ts":"2026-04-28T03:24:41.550859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1627b0b72e417489 elected leader 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-04-28T03:24:41.616678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:24:41.767606Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1627b0b72e417489","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.81:2379]}","request-path":"/0/members/1627b0b72e417489/attributes","cluster-id":"d42e5eae8a1b814c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:24:41.767911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:24:41.768014Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:24:41.775942Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.81:2379"} {"level":"info","ts":"2026-04-28T03:24:41.778111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:24:41.77816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:24:41.783082Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:24:41.783477Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:24:41.783604Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:24:41.783652Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:24:43.168106Z","caller":"traceutil/trace.go:171","msg":"trace[365477676] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"111.073243ms","start":"2026-04-28T03:24:43.057005Z","end":"2026-04-28T03:24:43.168079Z","steps":["trace[365477676] 'process raft request' (duration: 110.932709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:43.168211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.042101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:24:43.1683Z","caller":"traceutil/trace.go:171","msg":"trace[1065812721] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:4; }","duration":"110.150565ms","start":"2026-04-28T03:24:43.058135Z","end":"2026-04-28T03:24:43.168286Z","steps":["trace[1065812721] 'agreement among raft nodes before linearized reading' (duration: 109.973649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:43.221659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.319142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:24:43.221737Z","caller":"traceutil/trace.go:171","msg":"trace[1180922366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"111.416434ms","start":"2026-04-28T03:24:43.110304Z","end":"2026-04-28T03:24:43.22172Z","steps":["trace[1180922366] 'agreement among raft nodes before linearized reading' (duration: 111.302971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:43.221886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.983665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:24:43.221939Z","caller":"traceutil/trace.go:171","msg":"trace[268117940] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:0; response_revision:11; }","duration":"102.052618ms","start":"2026-04-28T03:24:43.119877Z","end":"2026-04-28T03:24:43.22193Z","steps":["trace[268117940] 'agreement among raft nodes before linearized reading' (duration: 101.988506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:43.222086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.047782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T03:24:43.222068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.761565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T03:24:43.222121Z","caller":"traceutil/trace.go:171","msg":"trace[1625509259] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"111.101505ms","start":"2026-04-28T03:24:43.111012Z","end":"2026-04-28T03:24:43.222113Z","steps":["trace[1625509259] 'agreement among raft nodes before linearized reading' (duration: 111.050453ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:43.222145Z","caller":"traceutil/trace.go:171","msg":"trace[726704843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"111.861469ms","start":"2026-04-28T03:24:43.110259Z","end":"2026-04-28T03:24:43.222121Z","steps":["trace[726704843] 'agreement among raft nodes before linearized reading' (duration: 111.762786ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:43.550832Z","caller":"traceutil/trace.go:171","msg":"trace[1974406098] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"107.306225ms","start":"2026-04-28T03:24:43.443473Z","end":"2026-04-28T03:24:43.55078Z","steps":["trace[1974406098] 'process raft request' (duration: 28.299798ms)","trace[1974406098] 'compare' (duration: 78.560533ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:44.572546Z","caller":"traceutil/trace.go:171","msg":"trace[1483032110] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:127; }","duration":"243.445894ms","start":"2026-04-28T03:24:44.329075Z","end":"2026-04-28T03:24:44.572521Z","steps":["trace[1483032110] 'read index received' (duration: 234.731998ms)","trace[1483032110] 'applied index is now lower than readState.Index' (duration: 8.712826ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:44.572573Z","caller":"traceutil/trace.go:171","msg":"trace[323537722] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"291.401526ms","start":"2026-04-28T03:24:44.281153Z","end":"2026-04-28T03:24:44.572554Z","steps":["trace[323537722] 'process raft request' (duration: 282.571496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:44.572693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.63014ms","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-28T03:24:44.572776Z","caller":"traceutil/trace.go:171","msg":"trace[1082400828] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:124; }","duration":"243.705683ms","start":"2026-04-28T03:24:44.329011Z","end":"2026-04-28T03:24:44.572717Z","steps":["trace[1082400828] 'agreement among raft nodes before linearized reading' (duration: 243.60616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:47.133832Z","caller":"traceutil/trace.go:171","msg":"trace[1519855646] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:231; }","duration":"136.384958ms","start":"2026-04-28T03:24:46.997423Z","end":"2026-04-28T03:24:47.133808Z","steps":["trace[1519855646] 'read index received' (duration: 136.193941ms)","trace[1519855646] 'applied index is now lower than readState.Index' (duration: 190.077µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:47.134007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.571853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:47.134051Z","caller":"traceutil/trace.go:171","msg":"trace[406452900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:227; }","duration":"136.669046ms","start":"2026-04-28T03:24:46.997369Z","end":"2026-04-28T03:24:47.134038Z","steps":["trace[406452900] 'agreement among raft nodes before linearized reading' (duration: 136.540992ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:47.134122Z","caller":"traceutil/trace.go:171","msg":"trace[1078729644] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"243.601379ms","start":"2026-04-28T03:24:46.8905Z","end":"2026-04-28T03:24:47.134101Z","steps":["trace[1078729644] 'process raft request' (duration: 243.198396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:48.34665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.48613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:24:48.346785Z","caller":"traceutil/trace.go:171","msg":"trace[1129501978] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"1.208624557s","start":"2026-04-28T03:24:47.138127Z","end":"2026-04-28T03:24:48.346752Z","steps":["trace[1129501978] 'process raft request' (duration: 475.392783ms)","trace[1129501978] 'compare' (duration: 732.281794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:48.346861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.138108Z","time spent":"1.20871755s","remote":"127.0.0.1:48606","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-28T03:24:49.070044Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.408513459s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T03:24:49.424345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.002722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:24:49.424693Z","caller":"traceutil/trace.go:171","msg":"trace[1047050591] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"2.286213176s","start":"2026-04-28T03:24:47.138422Z","end":"2026-04-28T03:24:49.424635Z","steps":["trace[1047050591] 'process raft request' (duration: 1.931843523s)","trace[1047050591] 'compare' (duration: 353.823116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:49.424763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.195381Z","time spent":"2.229338642s","remote":"127.0.0.1:48440","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T03:24:49.424849Z","caller":"traceutil/trace.go:171","msg":"trace[1150225260] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:232; }","duration":"2.229398623s","start":"2026-04-28T03:24:47.195429Z","end":"2026-04-28T03:24:49.424828Z","steps":["trace[1150225260] 'read index received' (duration: 418.213179ms)","trace[1150225260] 'applied index is now lower than readState.Index' (duration: 1.811184644s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:49.424861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.138409Z","time spent":"2.286379841s","remote":"127.0.0.1:48676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:24:49.425691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230192509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:24:49.425714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749084434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:49.425742Z","caller":"traceutil/trace.go:171","msg":"trace[496459948] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:231; }","duration":"2.230308023s","start":"2026-04-28T03:24:47.19542Z","end":"2026-04-28T03:24:49.425729Z","steps":["trace[496459948] 'agreement among raft nodes before linearized reading' (duration: 2.23021683s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:49.425763Z","caller":"traceutil/trace.go:171","msg":"trace[534120065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:231; }","duration":"1.749134885s","start":"2026-04-28T03:24:47.676614Z","end":"2026-04-28T03:24:49.425749Z","steps":["trace[534120065] 'agreement among raft nodes before linearized reading' (duration: 1.749052963s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.425781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.19539Z","time spent":"2.230383375s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-28T03:24:49.425799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.676557Z","time spent":"1.749234779s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:24:49.425808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.953482499s","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-28T03:24:49.425883Z","caller":"traceutil/trace.go:171","msg":"trace[1566758752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:231; }","duration":"1.953638614s","start":"2026-04-28T03:24:47.472224Z","end":"2026-04-28T03:24:49.425863Z","steps":["trace[1566758752] 'agreement among raft nodes before linearized reading' (duration: 1.953464478s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.425933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075209823s","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-28T03:24:49.425933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.472202Z","time spent":"1.953721987s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:24:49.425969Z","caller":"traceutil/trace.go:171","msg":"trace[459695359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:231; }","duration":"1.075277135s","start":"2026-04-28T03:24:48.350682Z","end":"2026-04-28T03:24:49.425959Z","steps":["trace[459695359] 'agreement among raft nodes before linearized reading' (duration: 1.075220133s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.425996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:48.350666Z","time spent":"1.075323917s","remote":"127.0.0.1:48606","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-28T03:24:49.425977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074776249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:49.424916Z","caller":"traceutil/trace.go:171","msg":"trace[2035809012] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"2.111676379s","start":"2026-04-28T03:24:47.31323Z","end":"2026-04-28T03:24:49.424906Z","steps":["trace[2035809012] 'process raft request' (duration: 2.111493033s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:49.426061Z","caller":"traceutil/trace.go:171","msg":"trace[2003542073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:231; }","duration":"1.074895704s","start":"2026-04-28T03:24:48.351145Z","end":"2026-04-28T03:24:49.42604Z","steps":["trace[2003542073] 'agreement among raft nodes before linearized reading' (duration: 1.074721118s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.31321Z","time spent":"2.112843945s","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:24:49.426116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:48.3511Z","time spent":"1.075007457s","remote":"127.0.0.1:48368","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-28T03:24:49.424972Z","caller":"traceutil/trace.go:171","msg":"trace[1325721077] transaction","detail":"{read_only:false; number_of_response:0; response_revision:229; }","duration":"2.124052002s","start":"2026-04-28T03:24:47.300887Z","end":"2026-04-28T03:24:49.424939Z","steps":["trace[1325721077] 'process raft request' (duration: 2.123732001s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.300855Z","time spent":"2.125335352s","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:24:49.426012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973214275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:24:49.426361Z","caller":"traceutil/trace.go:171","msg":"trace[1452800798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:231; }","duration":"1.973589667s","start":"2026-04-28T03:24:47.452757Z","end":"2026-04-28T03:24:49.426347Z","steps":["trace[1452800798] 'agreement among raft nodes before linearized reading' (duration: 1.973217695s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.452737Z","time spent":"1.97365764s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:24:49.425149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229714904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-28T03:24:49.425192Z","caller":"traceutil/trace.go:171","msg":"trace[102257574] transaction","detail":"{read_only:false; number_of_response:0; response_revision:231; }","duration":"2.111328177s","start":"2026-04-28T03:24:47.31384Z","end":"2026-04-28T03:24:49.425168Z","steps":["trace[102257574] 'process raft request' (duration: 2.110940035s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:49.425198Z","caller":"traceutil/trace.go:171","msg":"trace[1915552273] transaction","detail":"{read_only:false; number_of_response:0; response_revision:231; }","duration":"2.110889653s","start":"2026-04-28T03:24:47.314298Z","end":"2026-04-28T03:24:49.425187Z","steps":["trace[1915552273] 'process raft request' (duration: 2.110504621s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:49.425243Z","caller":"traceutil/trace.go:171","msg":"trace[525163419] transaction","detail":"{read_only:false; number_of_response:0; response_revision:231; }","duration":"2.111832563s","start":"2026-04-28T03:24:47.313403Z","end":"2026-04-28T03:24:49.425236Z","steps":["trace[525163419] 'process raft request' (duration: 2.111350558s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.313397Z","time spent":"2.113054862s","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:24:49.426575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.313833Z","time spent":"2.11266022s","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:24:49.426593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.314291Z","time spent":"2.112268417s","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:24:49.425199Z","caller":"traceutil/trace.go:171","msg":"trace[1579119455] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"2.113035022s","start":"2026-04-28T03:24:47.312154Z","end":"2026-04-28T03:24:49.425189Z","steps":["trace[1579119455] 'process raft request' (duration: 2.112507285s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.312135Z","time spent":"2.114525308s","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:24:49.42672Z","caller":"traceutil/trace.go:171","msg":"trace[1561043697] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:231; }","duration":"2.231325255s","start":"2026-04-28T03:24:47.195381Z","end":"2026-04-28T03:24:49.426707Z","steps":["trace[1561043697] 'agreement among raft nodes before linearized reading' (duration: 2.229514307s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:49.426784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:24:47.195359Z","time spent":"2.231388417s","remote":"127.0.0.1:48718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-28T03:24:59.68205Z","caller":"traceutil/trace.go:171","msg":"trace[1154777] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"192.31156ms","start":"2026-04-28T03:24:59.489714Z","end":"2026-04-28T03:24:59.682025Z","steps":["trace[1154777] 'read index received' (duration: 3.102098ms)","trace[1154777] 'applied index is now lower than readState.Index' (duration: 189.208192ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:59.68235Z","caller":"traceutil/trace.go:171","msg":"trace[696546356] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"194.120358ms","start":"2026-04-28T03:24:59.488217Z","end":"2026-04-28T03:24:59.682338Z","steps":["trace[696546356] 'process raft request' (duration: 139.212816ms)","trace[696546356] 'compare' (duration: 54.480919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:59.682552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.815566ms","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-28T03:24:59.682578Z","caller":"traceutil/trace.go:171","msg":"trace[490632446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"192.860238ms","start":"2026-04-28T03:24:59.48971Z","end":"2026-04-28T03:24:59.68257Z","steps":["trace[490632446] 'agreement among raft nodes before linearized reading' (duration: 192.791236ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:59.721169Z","caller":"traceutil/trace.go:171","msg":"trace[2042463137] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"197.684023ms","start":"2026-04-28T03:24:59.523455Z","end":"2026-04-28T03:24:59.721139Z","steps":["trace[2042463137] 'process raft request' (duration: 197.551328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:59.721646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.59998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:24:59.721765Z","caller":"traceutil/trace.go:171","msg":"trace[1965797185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"227.769966ms","start":"2026-04-28T03:24:59.493959Z","end":"2026-04-28T03:24:59.721729Z","steps":["trace[1965797185] 'agreement among raft nodes before linearized reading' (duration: 227.515748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:01.208383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.94578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:25:01.208656Z","caller":"traceutil/trace.go:171","msg":"trace[867352777] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"373.275553ms","start":"2026-04-28T03:25:00.835336Z","end":"2026-04-28T03:25:01.208612Z","steps":["trace[867352777] 'process raft request' (duration: 123.034234ms)","trace[867352777] 'compare' (duration: 249.704612ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:01.208834Z","caller":"traceutil/trace.go:171","msg":"trace[2021136673] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"372.121267ms","start":"2026-04-28T03:25:00.836665Z","end":"2026-04-28T03:25:01.208786Z","steps":["trace[2021136673] 'process raft request' (duration: 371.936931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:01.208858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:00.835307Z","time spent":"373.431048ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:01.209184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:00.836659Z","time spent":"372.241601ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:25:01.209488Z","caller":"traceutil/trace.go:171","msg":"trace[48246618] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"372.639923ms","start":"2026-04-28T03:25:00.83605Z","end":"2026-04-28T03:25:01.20869Z","steps":["trace[48246618] 'process raft request' (duration: 372.457947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:01.209568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:00.836032Z","time spent":"373.499051ms","remote":"127.0.0.1:48780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:25:10.182902Z","caller":"traceutil/trace.go:171","msg":"trace[1551062987] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"271.253687ms","start":"2026-04-28T03:25:09.911608Z","end":"2026-04-28T03:25:10.182861Z","steps":["trace[1551062987] 'process raft request' (duration: 271.04543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:13.910695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.556909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:25:13.910724Z","caller":"traceutil/trace.go:171","msg":"trace[1247315022] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"100.28267ms","start":"2026-04-28T03:25:13.81042Z","end":"2026-04-28T03:25:13.910702Z","steps":["trace[1247315022] 'process raft request' (duration: 77.912261ms)","trace[1247315022] 'compare' (duration: 22.266396ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:13.910779Z","caller":"traceutil/trace.go:171","msg":"trace[692237966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"100.678003ms","start":"2026-04-28T03:25:13.810085Z","end":"2026-04-28T03:25:13.910763Z","steps":["trace[692237966] 'range keys from in-memory index tree' (duration: 100.492797ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.709848Z","caller":"traceutil/trace.go:171","msg":"trace[1307388163] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"406.504418ms","start":"2026-04-28T03:25:14.30331Z","end":"2026-04-28T03:25:14.709814Z","steps":["trace[1307388163] 'process raft request' (duration: 406.210529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.710176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.303275Z","time spent":"406.678213ms","remote":"127.0.0.1:48606","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-28T03:25:14.717605Z","caller":"traceutil/trace.go:171","msg":"trace[1686422482] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"403.054798ms","start":"2026-04-28T03:25:14.314526Z","end":"2026-04-28T03:25:14.717581Z","steps":["trace[1686422482] 'process raft request' (duration: 402.887473ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.717678Z","caller":"traceutil/trace.go:171","msg":"trace[1342336547] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"402.084978ms","start":"2026-04-28T03:25:14.315574Z","end":"2026-04-28T03:25:14.717659Z","steps":["trace[1342336547] 'process raft request' (duration: 401.963774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.717722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.314503Z","time spent":"403.159302ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:14.717784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.315555Z","time spent":"402.183091ms","remote":"127.0.0.1:48418","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-28T03:25:14.717801Z","caller":"traceutil/trace.go:171","msg":"trace[454011256] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"402.109538ms","start":"2026-04-28T03:25:14.315631Z","end":"2026-04-28T03:25:14.717741Z","steps":["trace[454011256] 'process raft request' (duration: 401.941503ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.717894Z","caller":"traceutil/trace.go:171","msg":"trace[1326553788] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"362.44099ms","start":"2026-04-28T03:25:14.355424Z","end":"2026-04-28T03:25:14.717865Z","steps":["trace[1326553788] 'process raft request' (duration: 362.361458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.718117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.355398Z","time spent":"362.577575ms","remote":"127.0.0.1:48418","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-28T03:25:14.718253Z","caller":"traceutil/trace.go:171","msg":"trace[841786224] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"390.759458ms","start":"2026-04-28T03:25:14.327478Z","end":"2026-04-28T03:25:14.718237Z","steps":["trace[841786224] 'process raft request' (duration: 390.17845ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.718278Z","caller":"traceutil/trace.go:171","msg":"trace[1056974950] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"390.619514ms","start":"2026-04-28T03:25:14.327648Z","end":"2026-04-28T03:25:14.718267Z","steps":["trace[1056974950] 'process raft request' (duration: 390.047786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.718328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.327619Z","time spent":"390.687316ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:14.718338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.327453Z","time spent":"390.847411ms","remote":"127.0.0.1:48418","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-28T03:25:14.718331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.315627Z","time spent":"402.323445ms","remote":"127.0.0.1:48418","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-28T03:25:14.718408Z","caller":"traceutil/trace.go:171","msg":"trace[1659972004] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"384.453138ms","start":"2026-04-28T03:25:14.333944Z","end":"2026-04-28T03:25:14.718398Z","steps":["trace[1659972004] 'process raft request' (duration: 383.801747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.718454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.333918Z","time spent":"384.51686ms","remote":"127.0.0.1:48418","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-28T03:25:14.718553Z","caller":"traceutil/trace.go:171","msg":"trace[838232550] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"391.12288ms","start":"2026-04-28T03:25:14.327421Z","end":"2026-04-28T03:25:14.718544Z","steps":["trace[838232550] 'process raft request' (duration: 390.19594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.718596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.327399Z","time spent":"391.178932ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:25:14.719553Z","caller":"traceutil/trace.go:171","msg":"trace[762590569] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"359.774855ms","start":"2026-04-28T03:25:14.359758Z","end":"2026-04-28T03:25:14.719532Z","steps":["trace[762590569] 'process raft request' (duration: 358.096752ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.719592Z","caller":"traceutil/trace.go:171","msg":"trace[621472419] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"348.293361ms","start":"2026-04-28T03:25:14.371282Z","end":"2026-04-28T03:25:14.719575Z","steps":["trace[621472419] 'process raft request' (duration: 348.168567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.719627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.359731Z","time spent":"359.864258ms","remote":"127.0.0.1:48418","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-28T03:25:14.719632Z","caller":"traceutil/trace.go:171","msg":"trace[967624398] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:490; }","duration":"277.588728ms","start":"2026-04-28T03:25:14.442031Z","end":"2026-04-28T03:25:14.71962Z","steps":["trace[967624398] 'read index received' (duration: 267.615932ms)","trace[967624398] 'applied index is now lower than readState.Index' (duration: 9.972036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:14.719666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.371241Z","time spent":"348.394774ms","remote":"127.0.0.1:48418","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-28T03:25:14.719715Z","caller":"traceutil/trace.go:171","msg":"trace[1233514179] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"325.282391ms","start":"2026-04-28T03:25:14.394422Z","end":"2026-04-28T03:25:14.719704Z","steps":["trace[1233514179] 'process raft request' (duration: 325.073544ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:14.719756Z","caller":"traceutil/trace.go:171","msg":"trace[1012815073] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"233.880521ms","start":"2026-04-28T03:25:14.485866Z","end":"2026-04-28T03:25:14.719747Z","steps":["trace[1012815073] 'process raft request' (duration: 233.693205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:14.71977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:14.394401Z","time spent":"325.350123ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:14.719782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.704321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:14.719851Z","caller":"traceutil/trace.go:171","msg":"trace[672396002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"277.857106ms","start":"2026-04-28T03:25:14.441969Z","end":"2026-04-28T03:25:14.719827Z","steps":["trace[672396002] 'agreement among raft nodes before linearized reading' (duration: 277.694661ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:15.295468Z","caller":"traceutil/trace.go:171","msg":"trace[784071333] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"271.297838ms","start":"2026-04-28T03:25:15.024151Z","end":"2026-04-28T03:25:15.295448Z","steps":["trace[784071333] 'process raft request' (duration: 271.166223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:15.347948Z","caller":"traceutil/trace.go:171","msg":"trace[1428219939] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"321.637405ms","start":"2026-04-28T03:25:15.026287Z","end":"2026-04-28T03:25:15.347924Z","steps":["trace[1428219939] 'process raft request' (duration: 321.288104ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:15.347951Z","caller":"traceutil/trace.go:171","msg":"trace[2066888577] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"316.947026ms","start":"2026-04-28T03:25:15.030962Z","end":"2026-04-28T03:25:15.347909Z","steps":["trace[2066888577] 'read index received' (duration: 264.177012ms)","trace[2066888577] 'applied index is now lower than readState.Index' (duration: 52.768944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:15.348023Z","caller":"traceutil/trace.go:171","msg":"trace[1418757321] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"312.488765ms","start":"2026-04-28T03:25:15.035487Z","end":"2026-04-28T03:25:15.347976Z","steps":["trace[1418757321] 'process raft request' (duration: 312.375131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:15.348074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.02628Z","time spent":"321.727828ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:15.348077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.109091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T03:25:15.348115Z","caller":"traceutil/trace.go:171","msg":"trace[1643914652] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:524; }","duration":"317.165773ms","start":"2026-04-28T03:25:15.030939Z","end":"2026-04-28T03:25:15.348105Z","steps":["trace[1643914652] 'agreement among raft nodes before linearized reading' (duration: 317.0854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:15.348132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.030931Z","time spent":"317.196954ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.81\" "} {"level":"warn","ts":"2026-04-28T03:25:15.348192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.035473Z","time spent":"312.64167ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:16.062887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982690902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:25:16.320149Z","caller":"traceutil/trace.go:171","msg":"trace[1288857061] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"940.628734ms","start":"2026-04-28T03:25:15.379485Z","end":"2026-04-28T03:25:16.320114Z","steps":["trace[1288857061] 'process raft request' (duration: 867.412691ms)","trace[1288857061] 'compare' (duration: 73.076579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:16.320314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.379463Z","time spent":"940.753489ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:25:16.336766Z","caller":"traceutil/trace.go:171","msg":"trace[1293804601] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"956.489927ms","start":"2026-04-28T03:25:15.380256Z","end":"2026-04-28T03:25:16.336746Z","steps":["trace[1293804601] 'process raft request' (duration: 956.272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:16.336862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.380243Z","time spent":"956.57444ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:25:16.336926Z","caller":"traceutil/trace.go:171","msg":"trace[1897749647] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"774.528744ms","start":"2026-04-28T03:25:15.562378Z","end":"2026-04-28T03:25:16.336907Z","steps":["trace[1897749647] 'read index received' (duration: 684.469907ms)","trace[1897749647] 'applied index is now lower than readState.Index' (duration: 90.055967ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:16.336984Z","caller":"traceutil/trace.go:171","msg":"trace[375391524] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"917.690817ms","start":"2026-04-28T03:25:15.419262Z","end":"2026-04-28T03:25:16.336952Z","steps":["trace[375391524] 'process raft request' (duration: 917.409878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:16.337146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.419234Z","time spent":"917.811141ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:16.337169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.619358ms","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-28T03:25:16.337162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.965063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:25:16.337195Z","caller":"traceutil/trace.go:171","msg":"trace[739362641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"422.682541ms","start":"2026-04-28T03:25:15.914505Z","end":"2026-04-28T03:25:16.337187Z","steps":["trace[739362641] 'agreement among raft nodes before linearized reading' (duration: 422.582338ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:16.337209Z","caller":"traceutil/trace.go:171","msg":"trace[1809960615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"419.058896ms","start":"2026-04-28T03:25:15.918137Z","end":"2026-04-28T03:25:16.337196Z","steps":["trace[1809960615] 'agreement among raft nodes before linearized reading' (duration: 418.935122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:16.33722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.914478Z","time spent":"422.736692ms","remote":"127.0.0.1:48606","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-28T03:25:16.337242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.918112Z","time spent":"419.122738ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:25:16.337249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.800533ms","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-28T03:25:16.337425Z","caller":"traceutil/trace.go:171","msg":"trace[438556831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"775.067312ms","start":"2026-04-28T03:25:15.562287Z","end":"2026-04-28T03:25:16.337355Z","steps":["trace[438556831] 'agreement among raft nodes before linearized reading' (duration: 774.727061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:16.337496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.562242Z","time spent":"775.241128ms","remote":"127.0.0.1:48606","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-28T03:25:16.337053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.786767ms","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-28T03:25:16.337562Z","caller":"traceutil/trace.go:171","msg":"trace[1386223273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"581.355766ms","start":"2026-04-28T03:25:15.756198Z","end":"2026-04-28T03:25:16.337554Z","steps":["trace[1386223273] 'agreement among raft nodes before linearized reading' (duration: 580.805068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:16.337589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:15.756178Z","time spent":"581.404247ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:25:21.291217Z","caller":"traceutil/trace.go:171","msg":"trace[1853184544] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"217.416469ms","start":"2026-04-28T03:25:21.073778Z","end":"2026-04-28T03:25:21.291194Z","steps":["trace[1853184544] 'process raft request' (duration: 216.301953ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:21.450543Z","caller":"traceutil/trace.go:171","msg":"trace[1875580067] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"121.187545ms","start":"2026-04-28T03:25:21.329327Z","end":"2026-04-28T03:25:21.450514Z","steps":["trace[1875580067] 'process raft request' (duration: 120.913126ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:21.513233Z","caller":"traceutil/trace.go:171","msg":"trace[2140385204] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"161.262247ms","start":"2026-04-28T03:25:21.351933Z","end":"2026-04-28T03:25:21.513195Z","steps":["trace[2140385204] 'process raft request' (duration: 161.112172ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:22.089077Z","caller":"traceutil/trace.go:171","msg":"trace[27063489] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"140.55078ms","start":"2026-04-28T03:25:21.948499Z","end":"2026-04-28T03:25:22.08905Z","steps":["trace[27063489] 'process raft request' (duration: 139.783205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:23.30827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047174564s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:25:23.308393Z","caller":"traceutil/trace.go:171","msg":"trace[1108918230] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"939.86378ms","start":"2026-04-28T03:25:22.368504Z","end":"2026-04-28T03:25:23.308367Z","steps":["trace[1108918230] 'read index received' (duration: 31.581µs)","trace[1108918230] 'applied index is now lower than readState.Index' (duration: 939.830229ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:23.308403Z","caller":"traceutil/trace.go:171","msg":"trace[1949233755] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.164808287s","start":"2026-04-28T03:25:22.143565Z","end":"2026-04-28T03:25:23.308373Z","steps":["trace[1949233755] 'process raft request' (duration: 117.464907ms)","trace[1949233755] 'compare' (duration: 1.046473132s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:23.308502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.14354Z","time spent":"1.164914081s","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:25:23.308742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.143386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:25:23.308819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.361251ms","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-28T03:25:23.30884Z","caller":"traceutil/trace.go:171","msg":"trace[1364418200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"869.286331ms","start":"2026-04-28T03:25:22.439527Z","end":"2026-04-28T03:25:23.308813Z","steps":["trace[1364418200] 'agreement among raft nodes before linearized reading' (duration: 869.115586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:23.308875Z","caller":"traceutil/trace.go:171","msg":"trace[770457484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"920.432323ms","start":"2026-04-28T03:25:22.388425Z","end":"2026-04-28T03:25:23.308857Z","steps":["trace[770457484] 'agreement among raft nodes before linearized reading' (duration: 920.31888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:23.308926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.388413Z","time spent":"920.502035ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:25:23.309036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.009521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T03:25:23.30904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.439508Z","time spent":"869.406084ms","remote":"127.0.0.1:48368","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-28T03:25:23.309083Z","caller":"traceutil/trace.go:171","msg":"trace[838422093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"932.117304ms","start":"2026-04-28T03:25:22.376955Z","end":"2026-04-28T03:25:23.309072Z","steps":["trace[838422093] 'agreement among raft nodes before linearized reading' (duration: 931.935918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:23.309121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.37694Z","time spent":"932.171725ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:25:23.309718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.449165ms","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-28T03:25:23.309736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.189432ms","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-28T03:25:23.309802Z","caller":"traceutil/trace.go:171","msg":"trace[747917305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"939.55641ms","start":"2026-04-28T03:25:22.370208Z","end":"2026-04-28T03:25:23.309764Z","steps":["trace[747917305] 'agreement among raft nodes before linearized reading' (duration: 938.2865ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:23.30984Z","caller":"traceutil/trace.go:171","msg":"trace[2087364705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"941.355728ms","start":"2026-04-28T03:25:22.368457Z","end":"2026-04-28T03:25:23.309813Z","steps":["trace[2087364705] 'agreement among raft nodes before linearized reading' (duration: 940.015625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:23.309861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.370178Z","time spent":"939.665894ms","remote":"127.0.0.1:48606","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-28T03:25:23.309907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:22.368436Z","time spent":"941.46103ms","remote":"127.0.0.1:48606","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-28T03:25:23.818081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982691021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:25:24.085126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.948136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:25:24.085376Z","caller":"traceutil/trace.go:171","msg":"trace[46770997] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"770.425184ms","start":"2026-04-28T03:25:23.314918Z","end":"2026-04-28T03:25:24.085343Z","steps":["trace[46770997] 'process raft request' (duration: 621.134017ms)","trace[46770997] 'compare' (duration: 148.622126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:24.085559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.314894Z","time spent":"770.567499ms","remote":"127.0.0.1:48606","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-28T03:25:24.085712Z","caller":"traceutil/trace.go:171","msg":"trace[503169544] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"770.60572ms","start":"2026-04-28T03:25:23.315084Z","end":"2026-04-28T03:25:24.085689Z","steps":["trace[503169544] 'process raft request' (duration: 770.197697ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:24.085919Z","caller":"traceutil/trace.go:171","msg":"trace[2022577440] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:603; }","duration":"768.951097ms","start":"2026-04-28T03:25:23.316947Z","end":"2026-04-28T03:25:24.085898Z","steps":["trace[2022577440] 'read index received' (duration: 619.097853ms)","trace[2022577440] 'applied index is now lower than readState.Index' (duration: 149.850574ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:24.085739Z","caller":"traceutil/trace.go:171","msg":"trace[366705265] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"770.465375ms","start":"2026-04-28T03:25:23.315243Z","end":"2026-04-28T03:25:24.085708Z","steps":["trace[366705265] 'process raft request' (duration: 770.330571ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:24.085948Z","caller":"traceutil/trace.go:171","msg":"trace[1279611771] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"770.181266ms","start":"2026-04-28T03:25:23.315747Z","end":"2026-04-28T03:25:24.085928Z","steps":["trace[1279611771] 'process raft request' (duration: 769.875647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.086036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.315739Z","time spent":"770.253178ms","remote":"127.0.0.1:48606","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-28T03:25:24.086044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.315237Z","time spent":"770.739334ms","remote":"127.0.0.1:48606","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-28T03:25:24.086096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.055361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:25:24.08593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.315056Z","time spent":"770.737644ms","remote":"127.0.0.1:48606","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-28T03:25:24.086234Z","caller":"traceutil/trace.go:171","msg":"trace[1205852254] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:587; }","duration":"769.299989ms","start":"2026-04-28T03:25:23.316915Z","end":"2026-04-28T03:25:24.086215Z","steps":["trace[1205852254] 'agreement among raft nodes before linearized reading' (duration: 769.048991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.086376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.316907Z","time spent":"769.454973ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-28T03:25:24.086473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.72105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:25:24.086491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.898306ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.08653Z","caller":"traceutil/trace.go:171","msg":"trace[247035051] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"149.795483ms","start":"2026-04-28T03:25:23.93672Z","end":"2026-04-28T03:25:24.086515Z","steps":["trace[247035051] 'agreement among raft nodes before linearized reading' (duration: 149.71209ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:24.086553Z","caller":"traceutil/trace.go:171","msg":"trace[1776517030] 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:587; }","duration":"282.982969ms","start":"2026-04-28T03:25:23.803558Z","end":"2026-04-28T03:25:24.086541Z","steps":["trace[1776517030] 'agreement among raft nodes before linearized reading' (duration: 282.890746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.086572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.356218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:25:24.086627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.634775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.086674Z","caller":"traceutil/trace.go:171","msg":"trace[101976577] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"149.474383ms","start":"2026-04-28T03:25:23.937187Z","end":"2026-04-28T03:25:24.086661Z","steps":["trace[101976577] 'agreement among raft nodes before linearized reading' (duration: 149.343919ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:24.086693Z","caller":"traceutil/trace.go:171","msg":"trace[952604995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"304.896993ms","start":"2026-04-28T03:25:23.781784Z","end":"2026-04-28T03:25:24.086681Z","steps":["trace[952604995] 'agreement among raft nodes before linearized reading' (duration: 304.592264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.086715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.105432ms","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-28T03:25:24.086769Z","caller":"traceutil/trace.go:171","msg":"trace[324591940] 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:587; }","duration":"283.181535ms","start":"2026-04-28T03:25:23.803559Z","end":"2026-04-28T03:25:24.08674Z","steps":["trace[324591940] 'agreement among raft nodes before linearized reading' (duration: 283.131204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.086781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.781767Z","time spent":"304.974766ms","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T03:25:24.08691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.076239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.086952Z","caller":"traceutil/trace.go:171","msg":"trace[1448210315] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"305.158932ms","start":"2026-04-28T03:25:23.781783Z","end":"2026-04-28T03:25:24.086942Z","steps":["trace[1448210315] 'agreement among raft nodes before linearized reading' (duration: 305.09745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.087011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.781761Z","time spent":"305.214294ms","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:25:24.08702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.366081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.087065Z","caller":"traceutil/trace.go:171","msg":"trace[2050195333] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"150.469794ms","start":"2026-04-28T03:25:23.936585Z","end":"2026-04-28T03:25:24.087055Z","steps":["trace[2050195333] 'agreement among raft nodes before linearized reading' (duration: 150.370561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.087156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.884976ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.087224Z","caller":"traceutil/trace.go:171","msg":"trace[1191614198] 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:587; }","duration":"282.984449ms","start":"2026-04-28T03:25:23.804226Z","end":"2026-04-28T03:25:24.08721Z","steps":["trace[1191614198] 'agreement among raft nodes before linearized reading' (duration: 282.190854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.087197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.258885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:24.087407Z","caller":"traceutil/trace.go:171","msg":"trace[273446234] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:587; }","duration":"305.548435ms","start":"2026-04-28T03:25:23.781834Z","end":"2026-04-28T03:25:24.087383Z","steps":["trace[273446234] 'agreement among raft nodes before linearized reading' (duration: 305.060399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:24.087563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:23.781818Z","time spent":"305.718209ms","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-04-28T03:25:24.471754Z","caller":"traceutil/trace.go:171","msg":"trace[1967311795] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"132.844415ms","start":"2026-04-28T03:25:24.338875Z","end":"2026-04-28T03:25:24.471719Z","steps":["trace[1967311795] 'process raft request' (duration: 130.80325ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:25.980048Z","caller":"traceutil/trace.go:171","msg":"trace[552835816] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"531.325638ms","start":"2026-04-28T03:25:25.448693Z","end":"2026-04-28T03:25:25.980018Z","steps":["trace[552835816] 'process raft request' (duration: 531.276216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:25.980213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:25.448678Z","time spent":"531.466992ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:25:25.980113Z","caller":"traceutil/trace.go:171","msg":"trace[57770058] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"532.697711ms","start":"2026-04-28T03:25:25.447394Z","end":"2026-04-28T03:25:25.980092Z","steps":["trace[57770058] 'process raft request' (duration: 532.433303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:25.980332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:25.44738Z","time spent":"532.906538ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:25:25.988086Z","caller":"traceutil/trace.go:171","msg":"trace[849183551] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"357.837523ms","start":"2026-04-28T03:25:25.630226Z","end":"2026-04-28T03:25:25.988064Z","steps":["trace[849183551] 'process raft request' (duration: 357.785882ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:25.988167Z","caller":"traceutil/trace.go:171","msg":"trace[965845915] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"137.236045ms","start":"2026-04-28T03:25:25.850915Z","end":"2026-04-28T03:25:25.988151Z","steps":["trace[965845915] 'process raft request' (duration: 137.126181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:25.988203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:25.630187Z","time spent":"357.959578ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:25:25.988182Z","caller":"traceutil/trace.go:171","msg":"trace[1973896078] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"479.708191ms","start":"2026-04-28T03:25:25.508431Z","end":"2026-04-28T03:25:25.988139Z","steps":["trace[1973896078] 'process raft request' (duration: 479.435672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:25.988266Z","caller":"traceutil/trace.go:171","msg":"trace[1010460880] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"479.729851ms","start":"2026-04-28T03:25:25.508528Z","end":"2026-04-28T03:25:25.988258Z","steps":["trace[1010460880] 'process raft request' (duration: 479.459133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:25.988369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:25.508506Z","time spent":"479.821264ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:25.988449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:25.508411Z","time spent":"479.893806ms","remote":"127.0.0.1:48418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:25:28.812364Z","caller":"traceutil/trace.go:171","msg":"trace[1510796754] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"179.874997ms","start":"2026-04-28T03:25:28.632456Z","end":"2026-04-28T03:25:28.812331Z","steps":["trace[1510796754] 'process raft request' (duration: 179.675791ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:35.596629Z","caller":"traceutil/trace.go:171","msg":"trace[1172519399] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"143.200084ms","start":"2026-04-28T03:25:35.453393Z","end":"2026-04-28T03:25:35.596593Z","steps":["trace[1172519399] 'process raft request' (duration: 142.932755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:35.596826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.381998ms","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-28T03:25:35.596922Z","caller":"traceutil/trace.go:171","msg":"trace[1015309079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"113.548463ms","start":"2026-04-28T03:25:35.483351Z","end":"2026-04-28T03:25:35.596899Z","steps":["trace[1015309079] 'agreement among raft nodes before linearized reading' (duration: 113.234123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:35.798633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.862388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:25:35.798765Z","caller":"traceutil/trace.go:171","msg":"trace[1642850720] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"190.267047ms","start":"2026-04-28T03:25:35.608474Z","end":"2026-04-28T03:25:35.798741Z","steps":["trace[1642850720] 'process raft request' (duration: 67.182271ms)","trace[1642850720] 'compare' (duration: 122.678893ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:36.005134Z","caller":"traceutil/trace.go:171","msg":"trace[1552943078] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"393.076791ms","start":"2026-04-28T03:25:35.612021Z","end":"2026-04-28T03:25:36.005098Z","steps":["trace[1552943078] 'process raft request' (duration: 392.985038ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:36.005146Z","caller":"traceutil/trace.go:171","msg":"trace[1472059572] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"394.29274ms","start":"2026-04-28T03:25:35.61083Z","end":"2026-04-28T03:25:36.005122Z","steps":["trace[1472059572] 'process raft request' (duration: 337.967162ms)","trace[1472059572] 'compare' (duration: 55.994827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:36.005415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:35.61201Z","time spent":"393.295399ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:36.00545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:35.610806Z","time spent":"394.517997ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:36.905835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.083099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:36.90598Z","caller":"traceutil/trace.go:171","msg":"trace[404234460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"467.309497ms","start":"2026-04-28T03:25:36.438645Z","end":"2026-04-28T03:25:36.905954Z","steps":["trace[404234460] 'range keys from in-memory index tree' (duration: 466.942365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:36.906036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:36.438602Z","time spent":"467.42316ms","remote":"127.0.0.1:48368","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-28T03:25:41.169818Z","caller":"traceutil/trace.go:171","msg":"trace[1537079125] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"336.185158ms","start":"2026-04-28T03:25:40.833598Z","end":"2026-04-28T03:25:41.169784Z","steps":["trace[1537079125] 'process raft request' (duration: 336.017903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:41.17003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:40.833531Z","time spent":"336.380864ms","remote":"127.0.0.1:48606","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-28T03:25:41.858164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.717488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:41.858266Z","caller":"traceutil/trace.go:171","msg":"trace[1706710059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"416.927164ms","start":"2026-04-28T03:25:41.441316Z","end":"2026-04-28T03:25:41.858243Z","steps":["trace[1706710059] 'range keys from in-memory index tree' (duration: 416.187391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:41.85831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:41.441288Z","time spent":"417.010976ms","remote":"127.0.0.1:48378","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-28T03:25:41.963588Z","caller":"traceutil/trace.go:171","msg":"trace[1745434425] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"298.07181ms","start":"2026-04-28T03:25:41.665481Z","end":"2026-04-28T03:25:41.963553Z","steps":["trace[1745434425] 'read index received' (duration: 297.885054ms)","trace[1745434425] 'applied index is now lower than readState.Index' (duration: 185.416µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:41.963748Z","caller":"traceutil/trace.go:171","msg":"trace[1127281604] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"513.08251ms","start":"2026-04-28T03:25:41.450584Z","end":"2026-04-28T03:25:41.963667Z","steps":["trace[1127281604] 'process raft request' (duration: 512.76725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:41.964045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:41.450507Z","time spent":"513.391739ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:41.964757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.244987ms","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-28T03:25:41.964827Z","caller":"traceutil/trace.go:171","msg":"trace[191308897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"299.404322ms","start":"2026-04-28T03:25:41.665406Z","end":"2026-04-28T03:25:41.96481Z","steps":["trace[191308897] 'agreement among raft nodes before linearized reading' (duration: 298.275436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.077139Z","caller":"traceutil/trace.go:171","msg":"trace[1240033880] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"130.193883ms","start":"2026-04-28T03:25:55.946913Z","end":"2026-04-28T03:25:56.077106Z","steps":["trace[1240033880] 'read index received' (duration: 130.002647ms)","trace[1240033880] 'applied index is now lower than readState.Index' (duration: 190.026µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:56.077409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.458612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-6ngls\" ","response":"range_response_count:1 size:2274"} {"level":"info","ts":"2026-04-28T03:25:56.07745Z","caller":"traceutil/trace.go:171","msg":"trace[953564131] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-6ngls; range_end:; response_count:1; response_revision:892; }","duration":"130.561825ms","start":"2026-04-28T03:25:55.946877Z","end":"2026-04-28T03:25:56.077439Z","steps":["trace[953564131] 'agreement among raft nodes before linearized reading' (duration: 130.360909ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.077631Z","caller":"traceutil/trace.go:171","msg":"trace[158452566] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"160.463532ms","start":"2026-04-28T03:25:55.917126Z","end":"2026-04-28T03:25:56.077589Z","steps":["trace[158452566] 'process raft request' (duration: 159.757629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.210239Z","caller":"traceutil/trace.go:171","msg":"trace[1642185000] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"128.624764ms","start":"2026-04-28T03:25:56.081584Z","end":"2026-04-28T03:25:56.210209Z","steps":["trace[1642185000] 'read index received' (duration: 63.868833ms)","trace[1642185000] 'applied index is now lower than readState.Index' (duration: 64.754641ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:56.210396Z","caller":"traceutil/trace.go:171","msg":"trace[1345218172] transaction","detail":"{read_only:false; number_of_response:1; response_revision:893; }","duration":"130.071269ms","start":"2026-04-28T03:25:56.080284Z","end":"2026-04-28T03:25:56.210355Z","steps":["trace[1345218172] 'process raft request' (duration: 65.214445ms)","trace[1345218172] 'compare' (duration: 64.353158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:25:56.210676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.069657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6ngls\" ","response":"range_response_count:1 size:4880"} {"level":"info","ts":"2026-04-28T03:25:56.210729Z","caller":"traceutil/trace.go:171","msg":"trace[1317592588] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6ngls; range_end:; response_count:1; response_revision:893; }","duration":"129.186832ms","start":"2026-04-28T03:25:56.081529Z","end":"2026-04-28T03:25:56.210716Z","steps":["trace[1317592588] 'agreement among raft nodes before linearized reading' (duration: 128.838241ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.446781Z","caller":"traceutil/trace.go:171","msg":"trace[1824429263] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:922; }","duration":"236.422368ms","start":"2026-04-28T03:25:56.210335Z","end":"2026-04-28T03:25:56.446757Z","steps":["trace[1824429263] 'read index received' (duration: 154.447451ms)","trace[1824429263] 'applied index is now lower than readState.Index' (duration: 81.973607ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:56.446941Z","caller":"traceutil/trace.go:171","msg":"trace[1713469151] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"361.108447ms","start":"2026-04-28T03:25:56.085822Z","end":"2026-04-28T03:25:56.446931Z","steps":["trace[1713469151] 'process raft request' (duration: 360.88287ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.447092Z","caller":"traceutil/trace.go:171","msg":"trace[1201223822] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"364.36663ms","start":"2026-04-28T03:25:56.082691Z","end":"2026-04-28T03:25:56.447058Z","steps":["trace[1201223822] 'process raft request' (duration: 282.001541ms)","trace[1201223822] 'compare' (duration: 81.786101ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:56.446802Z","caller":"traceutil/trace.go:171","msg":"trace[1968705335] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"363.006977ms","start":"2026-04-28T03:25:56.083766Z","end":"2026-04-28T03:25:56.446773Z","steps":["trace[1968705335] 'process raft request' (duration: 362.880263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.447229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.193157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:25:56.447325Z","caller":"traceutil/trace.go:171","msg":"trace[15006843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"342.321532ms","start":"2026-04-28T03:25:56.104983Z","end":"2026-04-28T03:25:56.447304Z","steps":["trace[15006843] 'agreement among raft nodes before linearized reading' (duration: 342.035843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.447378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.104975Z","time spent":"342.393334ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:25:56.447532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.082665Z","time spent":"364.539236ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:56.447988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.085809Z","time spent":"361.160528ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:56.449409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.083753Z","time spent":"365.540397ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:25:56.682701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.615662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:25:56.68282Z","caller":"traceutil/trace.go:171","msg":"trace[191404368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"241.787638ms","start":"2026-04-28T03:25:56.440998Z","end":"2026-04-28T03:25:56.682786Z","steps":["trace[191404368] 'agreement among raft nodes before linearized reading' (duration: 241.600702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.683121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.931176ms","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-28T03:25:56.682954Z","caller":"traceutil/trace.go:171","msg":"trace[1076428522] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"463.498389ms","start":"2026-04-28T03:25:56.219391Z","end":"2026-04-28T03:25:56.682889Z","steps":["trace[1076428522] 'process raft request' (duration: 462.849459ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.683196Z","caller":"traceutil/trace.go:171","msg":"trace[2004258346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"310.260846ms","start":"2026-04-28T03:25:56.372913Z","end":"2026-04-28T03:25:56.683174Z","steps":["trace[2004258346] 'agreement among raft nodes before linearized reading' (duration: 309.7592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.683377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.372894Z","time spent":"310.465233ms","remote":"127.0.0.1:48606","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-28T03:25:56.683449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.846985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T03:25:56.683525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.219368Z","time spent":"463.882722ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5053,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:25:56.683665Z","caller":"traceutil/trace.go:171","msg":"trace[1812713303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"407.099003ms","start":"2026-04-28T03:25:56.276495Z","end":"2026-04-28T03:25:56.683594Z","steps":["trace[1812713303] 'agreement among raft nodes before linearized reading' (duration: 406.524385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.683754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.276467Z","time spent":"407.273489ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T03:25:56.684862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.122379ms","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-28T03:25:56.684962Z","caller":"traceutil/trace.go:171","msg":"trace[894370047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"380.303275ms","start":"2026-04-28T03:25:56.304635Z","end":"2026-04-28T03:25:56.684938Z","steps":["trace[894370047] 'agreement among raft nodes before linearized reading' (duration: 378.136596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.68501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:25:56.304604Z","time spent":"380.396088ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:25:56.682597Z","caller":"traceutil/trace.go:171","msg":"trace[1642103492] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"235.693905ms","start":"2026-04-28T03:25:56.446866Z","end":"2026-04-28T03:25:56.68256Z","steps":["trace[1642103492] 'read index received' (duration: 235.426946ms)","trace[1642103492] 'applied index is now lower than readState.Index' (duration: 265.399µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:56.689293Z","caller":"traceutil/trace.go:171","msg":"trace[1758364787] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"237.096699ms","start":"2026-04-28T03:25:56.452173Z","end":"2026-04-28T03:25:56.689269Z","steps":["trace[1758364787] 'process raft request' (duration: 236.82283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.689716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.068228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:25:56.691494Z","caller":"traceutil/trace.go:171","msg":"trace[292501973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"220.906116ms","start":"2026-04-28T03:25:56.470573Z","end":"2026-04-28T03:25:56.691479Z","steps":["trace[292501973] 'agreement among raft nodes before linearized reading' (duration: 218.919343ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.689705Z","caller":"traceutil/trace.go:171","msg":"trace[1160913873] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"232.216584ms","start":"2026-04-28T03:25:56.457442Z","end":"2026-04-28T03:25:56.689658Z","steps":["trace[1160913873] 'process raft request' (duration: 231.77526ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.893767Z","caller":"traceutil/trace.go:171","msg":"trace[901202602] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"201.898274ms","start":"2026-04-28T03:25:56.691831Z","end":"2026-04-28T03:25:56.893729Z","steps":["trace[901202602] 'process raft request' (duration: 156.816146ms)","trace[901202602] 'compare' (duration: 44.84648ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:56.894745Z","caller":"traceutil/trace.go:171","msg":"trace[18181406] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"202.165982ms","start":"2026-04-28T03:25:56.692548Z","end":"2026-04-28T03:25:56.894714Z","steps":["trace[18181406] 'process raft request' (duration: 201.117729ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.894778Z","caller":"traceutil/trace.go:171","msg":"trace[1521434124] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"201.842562ms","start":"2026-04-28T03:25:56.692912Z","end":"2026-04-28T03:25:56.894755Z","steps":["trace[1521434124] 'process raft request' (duration: 201.611865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:25:56.895662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.106368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6ngls\" ","response":"range_response_count:1 size:5068"} {"level":"info","ts":"2026-04-28T03:25:56.894938Z","caller":"traceutil/trace.go:171","msg":"trace[1472719097] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"199.485826ms","start":"2026-04-28T03:25:56.695437Z","end":"2026-04-28T03:25:56.894923Z","steps":["trace[1472719097] 'process raft request' (duration: 199.244309ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.895726Z","caller":"traceutil/trace.go:171","msg":"trace[1217098493] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6ngls; range_end:; response_count:1; response_revision:905; }","duration":"171.227691ms","start":"2026-04-28T03:25:56.724483Z","end":"2026-04-28T03:25:56.895711Z","steps":["trace[1217098493] 'agreement among raft nodes before linearized reading' (duration: 171.008034ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:56.894764Z","caller":"traceutil/trace.go:171","msg":"trace[803111661] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:930; }","duration":"170.191239ms","start":"2026-04-28T03:25:56.724541Z","end":"2026-04-28T03:25:56.894732Z","steps":["trace[803111661] 'read index received' (duration: 124.074539ms)","trace[803111661] 'applied index is now lower than readState.Index' (duration: 46.11566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:19.302487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982691880,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:26:19.549375Z","caller":"traceutil/trace.go:171","msg":"trace[384922581] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"747.343389ms","start":"2026-04-28T03:26:18.802009Z","end":"2026-04-28T03:26:19.549352Z","steps":["trace[384922581] 'read index received' (duration: 747.157363ms)","trace[384922581] 'applied index is now lower than readState.Index' (duration: 185.266µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:19.549619Z","caller":"traceutil/trace.go:171","msg":"trace[1653236608] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"775.14302ms","start":"2026-04-28T03:26:18.774431Z","end":"2026-04-28T03:26:19.549574Z","steps":["trace[1653236608] 'process raft request' (duration: 774.786749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.549736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.062944ms","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-28T03:26:19.549763Z","caller":"traceutil/trace.go:171","msg":"trace[1113387232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"731.139577ms","start":"2026-04-28T03:26:18.818614Z","end":"2026-04-28T03:26:19.549753Z","steps":["trace[1113387232] 'agreement among raft nodes before linearized reading' (duration: 731.020843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.549763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:18.774401Z","time spent":"775.273424ms","remote":"127.0.0.1:48606","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-28T03:26:19.54982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.098557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:19.549839Z","caller":"traceutil/trace.go:171","msg":"trace[59530769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"110.156178ms","start":"2026-04-28T03:26:19.439677Z","end":"2026-04-28T03:26:19.549834Z","steps":["trace[59530769] 'agreement among raft nodes before linearized reading' (duration: 110.125497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.549793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.743972ms","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-28T03:26:19.549903Z","caller":"traceutil/trace.go:171","msg":"trace[2062649427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"747.925228ms","start":"2026-04-28T03:26:18.801955Z","end":"2026-04-28T03:26:19.549881Z","steps":["trace[2062649427] 'agreement among raft nodes before linearized reading' (duration: 747.578447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.549946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:18.801935Z","time spent":"748.0033ms","remote":"127.0.0.1:48606","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-28T03:26:19.549784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:18.818584Z","time spent":"731.194968ms","remote":"127.0.0.1:48606","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-28T03:26:19.549983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.378789ms","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-28T03:26:19.550001Z","caller":"traceutil/trace.go:171","msg":"trace[2121771210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"543.451291ms","start":"2026-04-28T03:26:19.006544Z","end":"2026-04-28T03:26:19.549995Z","steps":["trace[2121771210] 'agreement among raft nodes before linearized reading' (duration: 543.39924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.550018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.006532Z","time spent":"543.482033ms","remote":"127.0.0.1:48606","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-28T03:26:19.550104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.930784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:26:19.55012Z","caller":"traceutil/trace.go:171","msg":"trace[1245010008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"547.968275ms","start":"2026-04-28T03:26:19.002147Z","end":"2026-04-28T03:26:19.550115Z","steps":["trace[1245010008] 'agreement among raft nodes before linearized reading' (duration: 547.917834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.550135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.002135Z","time spent":"547.995536ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:19.550138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.695868ms","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-28T03:26:19.550159Z","caller":"traceutil/trace.go:171","msg":"trace[654141733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"548.746269ms","start":"2026-04-28T03:26:19.001406Z","end":"2026-04-28T03:26:19.550153Z","steps":["trace[654141733] 'agreement among raft nodes before linearized reading' (duration: 548.681797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:19.550176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.001318Z","time spent":"548.853803ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:20.941464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982691884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:26:21.026312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319391476s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:26:21.026519Z","caller":"traceutil/trace.go:171","msg":"trace[1333385798] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.471937207s","start":"2026-04-28T03:26:19.554492Z","end":"2026-04-28T03:26:21.026429Z","steps":["trace[1333385798] 'process raft request' (duration: 152.314314ms)","trace[1333385798] 'compare' (duration: 1.31922169s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:21.026624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.554467Z","time spent":"1.472108153s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:26:21.24209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.535228692s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:26:21.24246Z","caller":"traceutil/trace.go:171","msg":"trace[1420206809] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.687239346s","start":"2026-04-28T03:26:19.555195Z","end":"2026-04-28T03:26:21.242434Z","steps":["trace[1420206809] 'process raft request' (duration: 1.687064951s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.242619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.555182Z","time spent":"1.68735481s","remote":"127.0.0.1:48606","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-28T03:26:21.242736Z","caller":"traceutil/trace.go:171","msg":"trace[1275595356] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.687400121s","start":"2026-04-28T03:26:19.555312Z","end":"2026-04-28T03:26:21.242712Z","steps":["trace[1275595356] 'process raft request' (duration: 1.687084501s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.242862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.555293Z","time spent":"1.687487364s","remote":"127.0.0.1:48606","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-28T03:26:21.243193Z","caller":"traceutil/trace.go:171","msg":"trace[1330285048] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.686955518s","start":"2026-04-28T03:26:19.556223Z","end":"2026-04-28T03:26:21.243179Z","steps":["trace[1330285048] 'process raft request' (duration: 1.686901296s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.24327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.556215Z","time spent":"1.687018619s","remote":"127.0.0.1:48606","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-28T03:26:21.24327Z","caller":"traceutil/trace.go:171","msg":"trace[1348576627] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.687871357s","start":"2026-04-28T03:26:19.555371Z","end":"2026-04-28T03:26:21.243242Z","steps":["trace[1348576627] 'process raft request' (duration: 1.687639819s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.243595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:19.555356Z","time spent":"1.688160365s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:26:21.245786Z","caller":"traceutil/trace.go:171","msg":"trace[2089974069] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1013; }","duration":"805.15955ms","start":"2026-04-28T03:26:20.440611Z","end":"2026-04-28T03:26:21.24577Z","steps":["trace[2089974069] 'read index received' (duration: 801.654529ms)","trace[2089974069] 'applied index is now lower than readState.Index' (duration: 3.503941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:21.245898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.272454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:21.245972Z","caller":"traceutil/trace.go:171","msg":"trace[768994143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"805.392178ms","start":"2026-04-28T03:26:20.440559Z","end":"2026-04-28T03:26:21.245951Z","steps":["trace[768994143] 'agreement among raft nodes before linearized reading' (duration: 805.255923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.245991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.088682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T03:26:21.246003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.381441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:26:21.246026Z","caller":"traceutil/trace.go:171","msg":"trace[264258953] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:983; }","duration":"400.168544ms","start":"2026-04-28T03:26:20.845847Z","end":"2026-04-28T03:26:21.246016Z","steps":["trace[264258953] 'agreement among raft nodes before linearized reading' (duration: 400.086411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.246028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:20.440529Z","time spent":"805.486211ms","remote":"127.0.0.1:48368","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-28T03:26:21.246047Z","caller":"traceutil/trace.go:171","msg":"trace[180240832] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:983; }","duration":"101.465173ms","start":"2026-04-28T03:26:21.144569Z","end":"2026-04-28T03:26:21.246034Z","steps":["trace[180240832] 'agreement among raft nodes before linearized reading' (duration: 101.37313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.246063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:20.845756Z","time spent":"400.299318ms","remote":"127.0.0.1:48662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:26:35.239399Z","caller":"traceutil/trace.go:171","msg":"trace[315396396] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"126.573149ms","start":"2026-04-28T03:26:35.112803Z","end":"2026-04-28T03:26:35.239376Z","steps":["trace[315396396] 'read index received' (duration: 126.448435ms)","trace[315396396] 'applied index is now lower than readState.Index' (duration: 123.404µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:35.239727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.903239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:26:35.23981Z","caller":"traceutil/trace.go:171","msg":"trace[710085310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"127.036134ms","start":"2026-04-28T03:26:35.112753Z","end":"2026-04-28T03:26:35.239789Z","steps":["trace[710085310] 'agreement among raft nodes before linearized reading' (duration: 126.711464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:43.944507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:26:44.444831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:26:44.539532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134537448s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:26:44.541302Z","caller":"traceutil/trace.go:171","msg":"trace[955918721] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.136398657s","start":"2026-04-28T03:26:43.404879Z","end":"2026-04-28T03:26:44.541278Z","steps":["trace[955918721] 'process raft request' (duration: 1.134860618s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.548257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.404858Z","time spent":"1.143279725s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:26:44.571964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128068523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:44.571924Z","caller":"traceutil/trace.go:171","msg":"trace[582286713] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"814.362835ms","start":"2026-04-28T03:26:43.757333Z","end":"2026-04-28T03:26:44.571696Z","steps":["trace[582286713] 'process raft request' (duration: 814.097717ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:44.572013Z","caller":"traceutil/trace.go:171","msg":"trace[1874980565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"1.128160776s","start":"2026-04-28T03:26:43.44384Z","end":"2026-04-28T03:26:44.572001Z","steps":["trace[1874980565] 'agreement among raft nodes before linearized reading' (duration: 1.128083314s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.572048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.44382Z","time spent":"1.128219778s","remote":"127.0.0.1:48368","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-28T03:26:44.572117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.757302Z","time spent":"814.727367ms","remote":"127.0.0.1:48606","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-28T03:26:44.571866Z","caller":"traceutil/trace.go:171","msg":"trace[77756060] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"1.127657891s","start":"2026-04-28T03:26:43.443895Z","end":"2026-04-28T03:26:44.571553Z","steps":["trace[77756060] 'read index received' (duration: 1.095867116s)","trace[77756060] 'applied index is now lower than readState.Index' (duration: 31.789865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:44.572472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091162487s","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-28T03:26:44.572502Z","caller":"traceutil/trace.go:171","msg":"trace[266565972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"1.09123904s","start":"2026-04-28T03:26:43.481254Z","end":"2026-04-28T03:26:44.572493Z","steps":["trace[266565972] 'agreement among raft nodes before linearized reading' (duration: 1.091130306s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.57253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.481232Z","time spent":"1.091291741s","remote":"127.0.0.1:48606","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-28T03:26:44.572592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090683301s","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-28T03:26:44.572638Z","caller":"traceutil/trace.go:171","msg":"trace[730015104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"1.090736064s","start":"2026-04-28T03:26:43.481889Z","end":"2026-04-28T03:26:44.572625Z","steps":["trace[730015104] 'agreement among raft nodes before linearized reading' (duration: 1.090578229s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.572669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.481884Z","time spent":"1.090778285s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:44.572703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090919139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:26:44.572732Z","caller":"traceutil/trace.go:171","msg":"trace[1610092774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"1.090963161s","start":"2026-04-28T03:26:43.481761Z","end":"2026-04-28T03:26:44.572724Z","steps":["trace[1610092774] 'agreement among raft nodes before linearized reading' (duration: 1.090888649s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.572757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.481752Z","time spent":"1.090998692s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:44.572921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091429225s","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-28T03:26:44.572947Z","caller":"traceutil/trace.go:171","msg":"trace[1676976924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"1.091487417s","start":"2026-04-28T03:26:43.481452Z","end":"2026-04-28T03:26:44.572939Z","steps":["trace[1676976924] 'agreement among raft nodes before linearized reading' (duration: 1.091397274s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.572974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.481437Z","time spent":"1.091528989s","remote":"127.0.0.1:48606","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-28T03:26:44.573468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.311038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:44.573516Z","caller":"traceutil/trace.go:171","msg":"trace[359701295] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1053; }","duration":"612.399181ms","start":"2026-04-28T03:26:43.961102Z","end":"2026-04-28T03:26:44.573502Z","steps":["trace[359701295] 'agreement among raft nodes before linearized reading' (duration: 612.302107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:44.573557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:43.961085Z","time spent":"612.460983ms","remote":"127.0.0.1:56908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:26:45.078853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.911682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:45.07894Z","caller":"traceutil/trace.go:171","msg":"trace[1180355141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"480.036426ms","start":"2026-04-28T03:26:44.59887Z","end":"2026-04-28T03:26:45.078907Z","steps":["trace[1180355141] 'range keys from in-memory index tree' (duration: 479.789158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.079055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:44.598844Z","time spent":"480.125789ms","remote":"127.0.0.1:48368","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-28T03:26:45.079328Z","caller":"traceutil/trace.go:171","msg":"trace[1389198300] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"480.240943ms","start":"2026-04-28T03:26:44.599073Z","end":"2026-04-28T03:26:45.079314Z","steps":["trace[1389198300] 'process raft request' (duration: 449.928915ms)","trace[1389198300] 'compare' (duration: 30.140732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:45.079408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:44.599055Z","time spent":"480.299995ms","remote":"127.0.0.1:48606","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-28T03:26:45.097111Z","caller":"traceutil/trace.go:171","msg":"trace[1214920698] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"497.961323ms","start":"2026-04-28T03:26:44.599127Z","end":"2026-04-28T03:26:45.097088Z","steps":["trace[1214920698] 'process raft request' (duration: 497.717275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.097226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:44.599112Z","time spent":"498.053606ms","remote":"127.0.0.1:48606","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-28T03:26:45.097353Z","caller":"traceutil/trace.go:171","msg":"trace[165080] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"496.714124ms","start":"2026-04-28T03:26:44.600625Z","end":"2026-04-28T03:26:45.097339Z","steps":["trace[165080] 'process raft request' (duration: 496.372143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.097457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:44.600605Z","time spent":"496.785656ms","remote":"127.0.0.1:48606","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-28T03:26:45.097046Z","caller":"traceutil/trace.go:171","msg":"trace[444612386] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"497.85512ms","start":"2026-04-28T03:26:44.599168Z","end":"2026-04-28T03:26:45.097023Z","steps":["trace[444612386] 'process raft request' (duration: 497.790888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.098072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:44.599143Z","time spent":"498.842221ms","remote":"127.0.0.1:48606","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-28T03:26:46.942327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:26:47.443342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:26:47.556293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.233024231s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:26:47.556866Z","caller":"traceutil/trace.go:171","msg":"trace[675932004] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.233694231s","start":"2026-04-28T03:26:46.32312Z","end":"2026-04-28T03:26:47.556815Z","steps":["trace[675932004] 'process raft request' (duration: 1.233536896s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.557056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.323094Z","time spent":"1.233872817s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:26:47.98235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.703046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:26:47.982463Z","caller":"traceutil/trace.go:171","msg":"trace[243654488] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"1.541169592s","start":"2026-04-28T03:26:46.441273Z","end":"2026-04-28T03:26:47.982443Z","steps":["trace[243654488] 'read index received' (duration: 1.115381731s)","trace[243654488] 'applied index is now lower than readState.Index' (duration: 425.786331ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:47.98273Z","caller":"traceutil/trace.go:171","msg":"trace[1017804518] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.377266161s","start":"2026-04-28T03:26:46.605446Z","end":"2026-04-28T03:26:47.982712Z","steps":["trace[1017804518] 'process raft request' (duration: 1.039081939s)","trace[1017804518] 'compare' (duration: 337.48141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:47.982805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.605424Z","time spent":"1.377340472s","remote":"127.0.0.1:48606","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-28T03:26:47.982979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54171488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:47.98305Z","caller":"traceutil/trace.go:171","msg":"trace[1579704826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"1.541813643s","start":"2026-04-28T03:26:46.441227Z","end":"2026-04-28T03:26:47.983041Z","steps":["trace[1579704826] 'agreement among raft nodes before linearized reading' (duration: 1.54172419s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.983078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.441209Z","time spent":"1.541862154s","remote":"127.0.0.1:48368","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-28T03:26:47.983316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424094421s","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-28T03:26:47.983347Z","caller":"traceutil/trace.go:171","msg":"trace[1720664435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"1.424172414s","start":"2026-04-28T03:26:46.559166Z","end":"2026-04-28T03:26:47.983338Z","steps":["trace[1720664435] 'agreement among raft nodes before linearized reading' (duration: 1.42405574s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.983377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.559137Z","time spent":"1.424233116s","remote":"127.0.0.1:48606","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-28T03:26:47.983601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.848102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:26:47.983634Z","caller":"traceutil/trace.go:171","msg":"trace[475280273] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1060; }","duration":"555.928505ms","start":"2026-04-28T03:26:47.427694Z","end":"2026-04-28T03:26:47.983622Z","steps":["trace[475280273] 'agreement among raft nodes before linearized reading' (duration: 555.879494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.983676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.427669Z","time spent":"555.985146ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:26:47.98383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.363081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:47.983856Z","caller":"traceutil/trace.go:171","msg":"trace[1237165816] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1060; }","duration":"613.421973ms","start":"2026-04-28T03:26:47.370427Z","end":"2026-04-28T03:26:47.983849Z","steps":["trace[1237165816] 'agreement among raft nodes before linearized reading' (duration: 613.378711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.983882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.370406Z","time spent":"613.469804ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:26:47.984093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.282941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:26:47.984124Z","caller":"traceutil/trace.go:171","msg":"trace[166176545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"881.342553ms","start":"2026-04-28T03:26:47.102771Z","end":"2026-04-28T03:26:47.984113Z","steps":["trace[166176545] 'agreement among raft nodes before linearized reading' (duration: 881.25802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.984161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.102753Z","time spent":"881.399195ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:47.984349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.990184ms","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-28T03:26:47.984379Z","caller":"traceutil/trace.go:171","msg":"trace[657202930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"882.043265ms","start":"2026-04-28T03:26:47.102327Z","end":"2026-04-28T03:26:47.98437Z","steps":["trace[657202930] 'agreement among raft nodes before linearized reading' (duration: 881.951112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.984404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.102316Z","time spent":"882.082197ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:47.984568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.619915ms","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-28T03:26:47.984601Z","caller":"traceutil/trace.go:171","msg":"trace[1734131400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"883.674657ms","start":"2026-04-28T03:26:47.100919Z","end":"2026-04-28T03:26:47.984594Z","steps":["trace[1734131400] 'agreement among raft nodes before linearized reading' (duration: 883.592215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.984626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.100907Z","time spent":"883.713518ms","remote":"127.0.0.1:48606","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-28T03:26:47.99947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.825033ms","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-28T03:26:47.999507Z","caller":"traceutil/trace.go:171","msg":"trace[902772373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"915.889175ms","start":"2026-04-28T03:26:47.083608Z","end":"2026-04-28T03:26:47.999497Z","steps":["trace[902772373] 'agreement among raft nodes before linearized reading' (duration: 901.070137ms)","trace[902772373] 'range keys from bolt db' (duration: 14.765707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:47.999527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:47.08359Z","time spent":"915.933127ms","remote":"127.0.0.1:48606","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-28T03:26:48.154894Z","caller":"traceutil/trace.go:171","msg":"trace[1189112375] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"166.164893ms","start":"2026-04-28T03:26:47.988669Z","end":"2026-04-28T03:26:48.154834Z","steps":["trace[1189112375] 'process raft request' (duration: 147.555485ms)","trace[1189112375] 'compare' (duration: 18.35025ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:48.155212Z","caller":"traceutil/trace.go:171","msg":"trace[877306698] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"164.725127ms","start":"2026-04-28T03:26:47.990457Z","end":"2026-04-28T03:26:48.155182Z","steps":["trace[877306698] 'process raft request' (duration: 164.303914ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:48.155494Z","caller":"traceutil/trace.go:171","msg":"trace[1322130370] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"165.238104ms","start":"2026-04-28T03:26:47.990244Z","end":"2026-04-28T03:26:48.155482Z","steps":["trace[1322130370] 'process raft request' (duration: 164.779229ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:48.155569Z","caller":"traceutil/trace.go:171","msg":"trace[486777838] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1100; }","duration":"162.946762ms","start":"2026-04-28T03:26:47.992596Z","end":"2026-04-28T03:26:48.155543Z","steps":["trace[486777838] 'read index received' (duration: 143.742315ms)","trace[486777838] 'applied index is now lower than readState.Index' (duration: 19.203437ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:48.15562Z","caller":"traceutil/trace.go:171","msg":"trace[142556878] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"153.519654ms","start":"2026-04-28T03:26:48.002081Z","end":"2026-04-28T03:26:48.155601Z","steps":["trace[142556878] 'process raft request' (duration: 153.283856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:48.155699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.082196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:48.155717Z","caller":"traceutil/trace.go:171","msg":"trace[1328749789] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"153.612977ms","start":"2026-04-28T03:26:48.002093Z","end":"2026-04-28T03:26:48.155706Z","steps":["trace[1328749789] 'process raft request' (duration: 153.39701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:48.155742Z","caller":"traceutil/trace.go:171","msg":"trace[669583009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"163.159818ms","start":"2026-04-28T03:26:47.992569Z","end":"2026-04-28T03:26:48.155728Z","steps":["trace[669583009] 'agreement among raft nodes before linearized reading' (duration: 163.060824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.160803Z","caller":"traceutil/trace.go:171","msg":"trace[1865401733] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"167.780854ms","start":"2026-04-28T03:26:49.992997Z","end":"2026-04-28T03:26:50.160778Z","steps":["trace[1865401733] 'process raft request' (duration: 167.576567ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.334617Z","caller":"traceutil/trace.go:171","msg":"trace[1435469346] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"169.442067ms","start":"2026-04-28T03:26:50.165141Z","end":"2026-04-28T03:26:50.334583Z","steps":["trace[1435469346] 'process raft request' (duration: 169.23183ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.334815Z","caller":"traceutil/trace.go:171","msg":"trace[2135526648] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"169.642053ms","start":"2026-04-28T03:26:50.165144Z","end":"2026-04-28T03:26:50.334786Z","steps":["trace[2135526648] 'process raft request' (duration: 169.389075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.342107Z","caller":"traceutil/trace.go:171","msg":"trace[2036890829] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"176.787189ms","start":"2026-04-28T03:26:50.165295Z","end":"2026-04-28T03:26:50.342082Z","steps":["trace[2036890829] 'process raft request' (duration: 176.621624ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.342322Z","caller":"traceutil/trace.go:171","msg":"trace[1530218924] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"176.917913ms","start":"2026-04-28T03:26:50.165393Z","end":"2026-04-28T03:26:50.342311Z","steps":["trace[1530218924] 'process raft request' (duration: 176.654905ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:50.342313Z","caller":"traceutil/trace.go:171","msg":"trace[770149849] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"176.251212ms","start":"2026-04-28T03:26:50.166046Z","end":"2026-04-28T03:26:50.342297Z","steps":["trace[770149849] 'process raft request' (duration: 176.152079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:02.890272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:03.390558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:03.891528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:03.916526Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.711707606s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T03:27:03.916873Z","caller":"traceutil/trace.go:171","msg":"trace[2078242272] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"1.712096008s","start":"2026-04-28T03:27:02.204757Z","end":"2026-04-28T03:27:03.916853Z","steps":["trace[2078242272] 'process raft request' (duration: 1.711924813s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.917004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.204744Z","time spent":"1.71216998s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:03.918103Z","caller":"traceutil/trace.go:171","msg":"trace[1846234517] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"1.354178982s","start":"2026-04-28T03:27:02.563916Z","end":"2026-04-28T03:27:03.918095Z","steps":["trace[1846234517] 'process raft request' (duration: 1.354081059s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.563891Z","time spent":"1.354237895s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:03.918059Z","caller":"traceutil/trace.go:171","msg":"trace[1418781685] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1200; }","duration":"1.528797353s","start":"2026-04-28T03:27:02.389251Z","end":"2026-04-28T03:27:03.918048Z","steps":["trace[1418781685] 'read index received' (duration: 1.527463951s)","trace[1418781685] 'applied index is now lower than readState.Index' (duration: 1.332582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:03.918374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529094552s","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-28T03:27:03.918427Z","caller":"traceutil/trace.go:171","msg":"trace[1839337523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1157; }","duration":"1.529200206s","start":"2026-04-28T03:27:02.389213Z","end":"2026-04-28T03:27:03.918413Z","steps":["trace[1839337523] 'agreement among raft nodes before linearized reading' (duration: 1.52902902s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.389195Z","time spent":"1.529305709s","remote":"127.0.0.1:48606","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-28T03:27:03.918507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528763552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:27:03.918532Z","caller":"traceutil/trace.go:171","msg":"trace[1458281044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.528807573s","start":"2026-04-28T03:27:02.389718Z","end":"2026-04-28T03:27:03.918526Z","steps":["trace[1458281044] 'agreement among raft nodes before linearized reading' (duration: 1.528690269s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.389709Z","time spent":"1.528839394s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:03.918795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51670675s","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-28T03:27:03.918817Z","caller":"traceutil/trace.go:171","msg":"trace[2062631843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.516746052s","start":"2026-04-28T03:27:02.402066Z","end":"2026-04-28T03:27:03.918812Z","steps":["trace[2062631843] 'agreement among raft nodes before linearized reading' (duration: 1.51669148s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517538147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T03:27:03.918832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.402057Z","time spent":"1.516771003s","remote":"127.0.0.1:48606","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-28T03:27:03.918837Z","caller":"traceutil/trace.go:171","msg":"trace[1360669912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1157; }","duration":"1.517576208s","start":"2026-04-28T03:27:02.401255Z","end":"2026-04-28T03:27:03.918831Z","steps":["trace[1360669912] 'agreement among raft nodes before linearized reading' (duration: 1.517521156s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.401245Z","time spent":"1.517605239s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:03.918941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.861939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:03.918958Z","caller":"traceutil/trace.go:171","msg":"trace[228844113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1157; }","duration":"317.923792ms","start":"2026-04-28T03:27:03.601027Z","end":"2026-04-28T03:27:03.918951Z","steps":["trace[228844113] 'agreement among raft nodes before linearized reading' (duration: 317.894461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.918975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:03.601003Z","time spent":"317.965422ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:03.919051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515802062s","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-28T03:27:03.919085Z","caller":"traceutil/trace.go:171","msg":"trace[702583878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1157; }","duration":"1.515874295s","start":"2026-04-28T03:27:02.403204Z","end":"2026-04-28T03:27:03.919078Z","steps":["trace[702583878] 'agreement among raft nodes before linearized reading' (duration: 1.515767751s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.919106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.403181Z","time spent":"1.515920395s","remote":"127.0.0.1:48606","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-28T03:27:03.919126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47937491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:03.919177Z","caller":"traceutil/trace.go:171","msg":"trace[996196838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1157; }","duration":"1.479464663s","start":"2026-04-28T03:27:02.439699Z","end":"2026-04-28T03:27:03.919164Z","steps":["trace[996196838] 'agreement among raft nodes before linearized reading' (duration: 1.47935917s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:03.919207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:02.439687Z","time spent":"1.479514765s","remote":"127.0.0.1:48368","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-28T03:27:16.13288Z","caller":"traceutil/trace.go:171","msg":"trace[1141598115] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"134.957296ms","start":"2026-04-28T03:27:15.997894Z","end":"2026-04-28T03:27:16.132851Z","steps":["trace[1141598115] 'read index received' (duration: 129.037799ms)","trace[1141598115] 'applied index is now lower than readState.Index' (duration: 5.918457ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:16.133022Z","caller":"traceutil/trace.go:171","msg":"trace[2057697285] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"153.109011ms","start":"2026-04-28T03:27:15.979892Z","end":"2026-04-28T03:27:16.133001Z","steps":["trace[2057697285] 'process raft request' (duration: 147.023959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:16.133141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.233695ms","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-28T03:27:16.13319Z","caller":"traceutil/trace.go:171","msg":"trace[1910764693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"135.327788ms","start":"2026-04-28T03:27:15.997849Z","end":"2026-04-28T03:27:16.133177Z","steps":["trace[1910764693] 'agreement among raft nodes before linearized reading' (duration: 135.152713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:16.133187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.461025ms","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-28T03:27:16.133245Z","caller":"traceutil/trace.go:171","msg":"trace[134633097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"130.537007ms","start":"2026-04-28T03:27:16.002695Z","end":"2026-04-28T03:27:16.133232Z","steps":["trace[134633097] 'agreement among raft nodes before linearized reading' (duration: 130.362622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:16.133221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.288471ms","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-28T03:27:16.133307Z","caller":"traceutil/trace.go:171","msg":"trace[2138740314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"131.410655ms","start":"2026-04-28T03:27:16.001879Z","end":"2026-04-28T03:27:16.133289Z","steps":["trace[2138740314] 'agreement among raft nodes before linearized reading' (duration: 131.172597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:16.133346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.407754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:27:16.133377Z","caller":"traceutil/trace.go:171","msg":"trace[178733437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"131.478826ms","start":"2026-04-28T03:27:16.00189Z","end":"2026-04-28T03:27:16.133369Z","steps":["trace[178733437] 'agreement among raft nodes before linearized reading' (duration: 131.353332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:19.032182Z","caller":"traceutil/trace.go:171","msg":"trace[235408498] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"210.152264ms","start":"2026-04-28T03:27:18.821958Z","end":"2026-04-28T03:27:19.03211Z","steps":["trace[235408498] 'process raft request' (duration: 209.948218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:19.962269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.227771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:19.962379Z","caller":"traceutil/trace.go:171","msg":"trace[941347848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"523.404517ms","start":"2026-04-28T03:27:19.438951Z","end":"2026-04-28T03:27:19.962356Z","steps":["trace[941347848] 'range keys from in-memory index tree' (duration: 523.131899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:19.962421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:19.43893Z","time spent":"523.4804ms","remote":"127.0.0.1:48368","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-28T03:27:20.451436Z","caller":"traceutil/trace.go:171","msg":"trace[382157388] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"464.477015ms","start":"2026-04-28T03:27:19.986946Z","end":"2026-04-28T03:27:20.451423Z","steps":["trace[382157388] 'process raft request' (duration: 464.284419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:20.451572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:19.986932Z","time spent":"464.544436ms","remote":"127.0.0.1:48606","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-28T03:27:20.451364Z","caller":"traceutil/trace.go:171","msg":"trace[1493863771] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"309.581357ms","start":"2026-04-28T03:27:20.141764Z","end":"2026-04-28T03:27:20.451345Z","steps":["trace[1493863771] 'read index received' (duration: 309.442413ms)","trace[1493863771] 'applied index is now lower than readState.Index' (duration: 138.104µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:20.452003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.070784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:27:20.452103Z","caller":"traceutil/trace.go:171","msg":"trace[362877137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"243.254871ms","start":"2026-04-28T03:27:20.208835Z","end":"2026-04-28T03:27:20.452089Z","steps":["trace[362877137] 'agreement among raft nodes before linearized reading' (duration: 242.988762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:20.45215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.659514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-28T03:27:20.452161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.633872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T03:27:20.452259Z","caller":"traceutil/trace.go:171","msg":"trace[970308036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"243.793037ms","start":"2026-04-28T03:27:20.208429Z","end":"2026-04-28T03:27:20.452223Z","steps":["trace[970308036] 'agreement among raft nodes before linearized reading' (duration: 243.524879ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:20.452271Z","caller":"traceutil/trace.go:171","msg":"trace[1721113260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"243.761657ms","start":"2026-04-28T03:27:20.208476Z","end":"2026-04-28T03:27:20.452238Z","steps":["trace[1721113260] 'agreement among raft nodes before linearized reading' (duration: 243.455637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:20.452374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.594869ms","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-28T03:27:20.452439Z","caller":"traceutil/trace.go:171","msg":"trace[884805564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"310.738253ms","start":"2026-04-28T03:27:20.141683Z","end":"2026-04-28T03:27:20.452422Z","steps":["trace[884805564] 'agreement among raft nodes before linearized reading' (duration: 309.983279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:20.452476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:20.141666Z","time spent":"310.802016ms","remote":"127.0.0.1:48606","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-28T03:27:20.452497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.014019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:27:20.452537Z","caller":"traceutil/trace.go:171","msg":"trace[667649630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"259.110012ms","start":"2026-04-28T03:27:20.193415Z","end":"2026-04-28T03:27:20.452524Z","steps":["trace[667649630] 'agreement among raft nodes before linearized reading' (duration: 258.363568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:22.77532Z","caller":"traceutil/trace.go:171","msg":"trace[1820755959] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"315.278257ms","start":"2026-04-28T03:27:22.460022Z","end":"2026-04-28T03:27:22.7753Z","steps":["trace[1820755959] 'process raft request' (duration: 315.170664ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:22.775415Z","caller":"traceutil/trace.go:171","msg":"trace[896667695] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1286; }","duration":"259.954018ms","start":"2026-04-28T03:27:22.515423Z","end":"2026-04-28T03:27:22.775377Z","steps":["trace[896667695] 'read index received' (duration: 259.943967ms)","trace[896667695] 'applied index is now lower than readState.Index' (duration: 8.491µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:22.775444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.460005Z","time spent":"315.37207ms","remote":"127.0.0.1:48606","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-28T03:27:22.775646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.214136ms","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-28T03:27:22.775694Z","caller":"traceutil/trace.go:171","msg":"trace[829249313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"260.316799ms","start":"2026-04-28T03:27:22.515367Z","end":"2026-04-28T03:27:22.775684Z","steps":["trace[829249313] 'agreement among raft nodes before linearized reading' (duration: 260.140314ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:22.982391Z","caller":"traceutil/trace.go:171","msg":"trace[1529508330] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"206.86987ms","start":"2026-04-28T03:27:22.775475Z","end":"2026-04-28T03:27:22.982345Z","steps":["trace[1529508330] 'read index received' (duration: 206.689034ms)","trace[1529508330] 'applied index is now lower than readState.Index' (duration: 179.456µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:22.982603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.919836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-28T03:27:22.982659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.436623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:27:22.982689Z","caller":"traceutil/trace.go:171","msg":"trace[1016039990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"434.516636ms","start":"2026-04-28T03:27:22.548163Z","end":"2026-04-28T03:27:22.98268Z","steps":["trace[1016039990] 'agreement among raft nodes before linearized reading' (duration: 434.340231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:22.982693Z","caller":"traceutil/trace.go:171","msg":"trace[1627404148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"433.06262ms","start":"2026-04-28T03:27:22.549609Z","end":"2026-04-28T03:27:22.982672Z","steps":["trace[1627404148] 'agreement among raft nodes before linearized reading' (duration: 432.771121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:22.982721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.548155Z","time spent":"434.559217ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:22.982743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.549588Z","time spent":"433.145163ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:27:22.982773Z","caller":"traceutil/trace.go:171","msg":"trace[1180350000] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"398.320022ms","start":"2026-04-28T03:27:22.584422Z","end":"2026-04-28T03:27:22.982742Z","steps":["trace[1180350000] 'process raft request' (duration: 397.681792ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:22.982895Z","caller":"traceutil/trace.go:171","msg":"trace[861307300] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"238.333816ms","start":"2026-04-28T03:27:22.744543Z","end":"2026-04-28T03:27:22.982876Z","steps":["trace[861307300] 'process raft request' (duration: 237.731837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:22.982909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.584398Z","time spent":"398.435775ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:27:22.983066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.932519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-28T03:27:22.983078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.262544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:22.983097Z","caller":"traceutil/trace.go:171","msg":"trace[77022812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"434.999851ms","start":"2026-04-28T03:27:22.54809Z","end":"2026-04-28T03:27:22.98309Z","steps":["trace[77022812] 'agreement among raft nodes before linearized reading' (duration: 434.883448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:22.983119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.548073Z","time spent":"435.041993ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:27:22.983117Z","caller":"traceutil/trace.go:171","msg":"trace[2050233837] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1239; }","duration":"301.343626ms","start":"2026-04-28T03:27:22.681763Z","end":"2026-04-28T03:27:22.983106Z","steps":["trace[2050233837] 'agreement among raft nodes before linearized reading' (duration: 301.283604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:22.983145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.681742Z","time spent":"301.396678ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T03:27:22.983262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.361773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:27:22.98329Z","caller":"traceutil/trace.go:171","msg":"trace[2064218958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1239; }","duration":"435.428005ms","start":"2026-04-28T03:27:22.547856Z","end":"2026-04-28T03:27:22.983284Z","steps":["trace[2064218958] 'agreement among raft nodes before linearized reading' (duration: 435.349953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:22.983312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:22.547835Z","time spent":"435.470396ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T03:27:23.022602Z","caller":"traceutil/trace.go:171","msg":"trace[1046031577] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"241.993311ms","start":"2026-04-28T03:27:22.78058Z","end":"2026-04-28T03:27:23.022573Z","steps":["trace[1046031577] 'process raft request' (duration: 241.766784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:23.022627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.195326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:23.022677Z","caller":"traceutil/trace.go:171","msg":"trace[615996337] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1240; }","duration":"192.321441ms","start":"2026-04-28T03:27:22.830343Z","end":"2026-04-28T03:27:23.022664Z","steps":["trace[615996337] 'agreement among raft nodes before linearized reading' (duration: 192.135505ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.151103Z","caller":"traceutil/trace.go:171","msg":"trace[2073916525] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"164.451989ms","start":"2026-04-28T03:27:22.986611Z","end":"2026-04-28T03:27:23.151063Z","steps":["trace[2073916525] 'process raft request' (duration: 164.271053ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.151145Z","caller":"traceutil/trace.go:171","msg":"trace[598564391] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"163.135317ms","start":"2026-04-28T03:27:22.987977Z","end":"2026-04-28T03:27:23.151113Z","steps":["trace[598564391] 'process raft request' (duration: 163.025014ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.15183Z","caller":"traceutil/trace.go:171","msg":"trace[736365413] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"162.677102ms","start":"2026-04-28T03:27:22.989133Z","end":"2026-04-28T03:27:23.15181Z","steps":["trace[736365413] 'process raft request' (duration: 162.647412ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.151947Z","caller":"traceutil/trace.go:171","msg":"trace[368597023] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"162.846788ms","start":"2026-04-28T03:27:22.989085Z","end":"2026-04-28T03:27:23.151932Z","steps":["trace[368597023] 'process raft request' (duration: 162.669983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.151979Z","caller":"traceutil/trace.go:171","msg":"trace[67628593] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"163.156528ms","start":"2026-04-28T03:27:22.988792Z","end":"2026-04-28T03:27:23.151949Z","steps":["trace[67628593] 'process raft request' (duration: 162.9226ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:23.152049Z","caller":"traceutil/trace.go:171","msg":"trace[2079675666] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"164.013345ms","start":"2026-04-28T03:27:22.988027Z","end":"2026-04-28T03:27:23.152041Z","steps":["trace[2079675666] 'process raft request' (duration: 163.026053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:23.333663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.672052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:23.333943Z","caller":"traceutil/trace.go:171","msg":"trace[469743498] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"169.176329ms","start":"2026-04-28T03:27:23.164576Z","end":"2026-04-28T03:27:23.333753Z","steps":["trace[469743498] 'process raft request' (duration: 44.354742ms)","trace[469743498] 'compare' (duration: 124.405323ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:23.353169Z","caller":"traceutil/trace.go:171","msg":"trace[45638000] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"165.579585ms","start":"2026-04-28T03:27:23.187501Z","end":"2026-04-28T03:27:23.35308Z","steps":["trace[45638000] 'process raft request' (duration: 165.397079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:24.704606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.999136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:24.704666Z","caller":"traceutil/trace.go:171","msg":"trace[21613006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"264.12331ms","start":"2026-04-28T03:27:24.440529Z","end":"2026-04-28T03:27:24.704653Z","steps":["trace[21613006] 'range keys from in-memory index tree' (duration: 263.910993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:25.436315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.888115ms","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-28T03:27:25.436392Z","caller":"traceutil/trace.go:171","msg":"trace[202195719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1253; }","duration":"278.000379ms","start":"2026-04-28T03:27:25.158375Z","end":"2026-04-28T03:27:25.436376Z","steps":["trace[202195719] 'range keys from in-memory index tree' (duration: 277.71575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:25.436784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.234416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:27:25.436818Z","caller":"traceutil/trace.go:171","msg":"trace[1589426729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"272.282727ms","start":"2026-04-28T03:27:25.164527Z","end":"2026-04-28T03:27:25.43681Z","steps":["trace[1589426729] 'range keys from in-memory index tree' (duration: 272.083521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:25.436838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.892014ms","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-28T03:27:25.436888Z","caller":"traceutil/trace.go:171","msg":"trace[1850699193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"276.966617ms","start":"2026-04-28T03:27:25.159908Z","end":"2026-04-28T03:27:25.436875Z","steps":["trace[1850699193] 'range keys from in-memory index tree' (duration: 276.79331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:25.437421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.874716ms","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-28T03:27:25.437471Z","caller":"traceutil/trace.go:171","msg":"trace[330151416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"272.949909ms","start":"2026-04-28T03:27:25.164509Z","end":"2026-04-28T03:27:25.437459Z","steps":["trace[330151416] 'range keys from in-memory index tree' (duration: 272.117373ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:27.202411Z","caller":"traceutil/trace.go:171","msg":"trace[180092327] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"141.916576ms","start":"2026-04-28T03:27:27.060471Z","end":"2026-04-28T03:27:27.202388Z","steps":["trace[180092327] 'process raft request' (duration: 141.773651ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:34.81764Z","caller":"traceutil/trace.go:171","msg":"trace[713498636] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"428.018712ms","start":"2026-04-28T03:27:34.389593Z","end":"2026-04-28T03:27:34.817612Z","steps":["trace[713498636] 'read index received' (duration: 427.750834ms)","trace[713498636] 'applied index is now lower than readState.Index' (duration: 266.558µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:34.817698Z","caller":"traceutil/trace.go:171","msg":"trace[1833368847] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"574.779641ms","start":"2026-04-28T03:27:34.242898Z","end":"2026-04-28T03:27:34.817678Z","steps":["trace[1833368847] 'process raft request' (duration: 574.473562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:34.817796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.186917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:34.817982Z","caller":"traceutil/trace.go:171","msg":"trace[2094169013] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1322; }","duration":"428.418104ms","start":"2026-04-28T03:27:34.389541Z","end":"2026-04-28T03:27:34.817959Z","steps":["trace[2094169013] 'agreement among raft nodes before linearized reading' (duration: 428.201647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:34.817936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:34.242886Z","time spent":"574.970657ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:34.818053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:34.38952Z","time spent":"428.511768ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:35.548303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397416505982692653,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:27:35.734281Z","caller":"traceutil/trace.go:171","msg":"trace[242141106] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.291023686s","start":"2026-04-28T03:27:34.443232Z","end":"2026-04-28T03:27:35.734256Z","steps":["trace[242141106] 'process raft request' (duration: 1.229583074s)","trace[242141106] 'compare' (duration: 61.335369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:35.735166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:34.443207Z","time spent":"1.29113819s","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:35.920007Z","caller":"traceutil/trace.go:171","msg":"trace[95447917] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1373; }","duration":"886.456435ms","start":"2026-04-28T03:27:35.033522Z","end":"2026-04-28T03:27:35.919978Z","steps":["trace[95447917] 'read index received' (duration: 639.309032ms)","trace[95447917] 'applied index is now lower than readState.Index' (duration: 247.146403ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:35.92016Z","caller":"traceutil/trace.go:171","msg":"trace[752198314] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.001355008s","start":"2026-04-28T03:27:34.918763Z","end":"2026-04-28T03:27:35.920118Z","steps":["trace[752198314] 'process raft request' (duration: 948.797766ms)","trace[752198314] 'compare' (duration: 52.274763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:35.92031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.495917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T03:27:35.920352Z","caller":"traceutil/trace.go:171","msg":"trace[1876069076] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:1324; }","duration":"880.552289ms","start":"2026-04-28T03:27:35.039789Z","end":"2026-04-28T03:27:35.920341Z","steps":["trace[1876069076] 'agreement among raft nodes before linearized reading' (duration: 880.431575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.920394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.039779Z","time spent":"880.60741ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.81\" "} {"level":"warn","ts":"2026-04-28T03:27:35.920401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.150179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:35.920457Z","caller":"traceutil/trace.go:171","msg":"trace[1885559108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"479.235581ms","start":"2026-04-28T03:27:35.441212Z","end":"2026-04-28T03:27:35.920448Z","steps":["trace[1885559108] 'agreement among raft nodes before linearized reading' (duration: 479.155229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.920489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.441195Z","time spent":"479.288672ms","remote":"127.0.0.1:48368","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-28T03:27:35.920496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.249717ms","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-28T03:27:35.92056Z","caller":"traceutil/trace.go:171","msg":"trace[852259411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"687.34086ms","start":"2026-04-28T03:27:35.233204Z","end":"2026-04-28T03:27:35.920545Z","steps":["trace[852259411] 'agreement among raft nodes before linearized reading' (duration: 687.176615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.920545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.273058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T03:27:35.920305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:34.918742Z","time spent":"1.001488411s","remote":"127.0.0.1:48606","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-28T03:27:35.920604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.23319Z","time spent":"687.403162ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T03:27:35.920615Z","caller":"traceutil/trace.go:171","msg":"trace[1565974455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"396.444003ms","start":"2026-04-28T03:27:35.524154Z","end":"2026-04-28T03:27:35.920598Z","steps":["trace[1565974455] 'agreement among raft nodes before linearized reading' (duration: 396.159744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.920184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.631331ms","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-28T03:27:35.920773Z","caller":"traceutil/trace.go:171","msg":"trace[453946392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1324; }","duration":"887.274281ms","start":"2026-04-28T03:27:35.033481Z","end":"2026-04-28T03:27:35.920755Z","steps":["trace[453946392] 'agreement among raft nodes before linearized reading' (duration: 886.639921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.92079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.175696ms","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-28T03:27:35.920593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.921475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:35.920894Z","caller":"traceutil/trace.go:171","msg":"trace[1074457558] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1324; }","duration":"834.287136ms","start":"2026-04-28T03:27:35.086591Z","end":"2026-04-28T03:27:35.920878Z","steps":["trace[1074457558] 'agreement among raft nodes before linearized reading' (duration: 833.914684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.920827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.033461Z","time spent":"887.350933ms","remote":"127.0.0.1:56814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:35.920979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.086571Z","time spent":"834.384979ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T03:27:35.920845Z","caller":"traceutil/trace.go:171","msg":"trace[1959822553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"372.261139ms","start":"2026-04-28T03:27:35.548575Z","end":"2026-04-28T03:27:35.920836Z","steps":["trace[1959822553] 'agreement among raft nodes before linearized reading' (duration: 371.836915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.921725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.548561Z","time spent":"373.138337ms","remote":"127.0.0.1:48606","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-28T03:27:35.920672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.524134Z","time spent":"396.525806ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:35.920788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.736688ms","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-28T03:27:35.921817Z","caller":"traceutil/trace.go:171","msg":"trace[1274992090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"394.801042ms","start":"2026-04-28T03:27:35.526999Z","end":"2026-04-28T03:27:35.9218Z","steps":["trace[1274992090] 'agreement among raft nodes before linearized reading' (duration: 393.47395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.921118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.479574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:27:35.92201Z","caller":"traceutil/trace.go:171","msg":"trace[688428276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"372.377263ms","start":"2026-04-28T03:27:35.549618Z","end":"2026-04-28T03:27:35.921995Z","steps":["trace[688428276] 'agreement among raft nodes before linearized reading' (duration: 370.716451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.921721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.62172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-28T03:27:35.922083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.549609Z","time spent":"372.461166ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:27:35.922073Z","caller":"traceutil/trace.go:171","msg":"trace[1992469634] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1324; }","duration":"571.012532ms","start":"2026-04-28T03:27:35.351045Z","end":"2026-04-28T03:27:35.922058Z","steps":["trace[1992469634] 'agreement among raft nodes before linearized reading' (duration: 569.294098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:35.922179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.35098Z","time spent":"571.182307ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-28T03:27:35.922666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:35.526952Z","time spent":"395.69469ms","remote":"127.0.0.1:48606","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-28T03:27:48.205826Z","caller":"traceutil/trace.go:171","msg":"trace[476743678] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"129.616545ms","start":"2026-04-28T03:27:48.076154Z","end":"2026-04-28T03:27:48.205771Z","steps":["trace[476743678] 'process raft request' (duration: 49.653199ms)","trace[476743678] 'compare' (duration: 79.655497ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:48.205966Z","caller":"traceutil/trace.go:171","msg":"trace[899394644] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"126.222028ms","start":"2026-04-28T03:27:48.079723Z","end":"2026-04-28T03:27:48.205945Z","steps":["trace[899394644] 'read index received' (duration: 46.056935ms)","trace[899394644] 'applied index is now lower than readState.Index' (duration: 80.161903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:48.206143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.472008ms","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-28T03:27:48.206223Z","caller":"traceutil/trace.go:171","msg":"trace[974384682] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"127.585511ms","start":"2026-04-28T03:27:48.078619Z","end":"2026-04-28T03:27:48.206204Z","steps":["trace[974384682] 'agreement among raft nodes before linearized reading' (duration: 127.345273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:48.206291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.218761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T03:27:48.206338Z","caller":"traceutil/trace.go:171","msg":"trace[1567532207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1381; }","duration":"128.294874ms","start":"2026-04-28T03:27:48.078029Z","end":"2026-04-28T03:27:48.206324Z","steps":["trace[1567532207] 'agreement among raft nodes before linearized reading' (duration: 128.17171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:48.206404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.642243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T03:27:48.206455Z","caller":"traceutil/trace.go:171","msg":"trace[151571591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"127.690504ms","start":"2026-04-28T03:27:48.07874Z","end":"2026-04-28T03:27:48.20643Z","steps":["trace[151571591] 'agreement among raft nodes before linearized reading' (duration: 127.599771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:48.206168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.484873ms","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-28T03:27:48.206723Z","caller":"traceutil/trace.go:171","msg":"trace[1143794366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"131.053021ms","start":"2026-04-28T03:27:48.075614Z","end":"2026-04-28T03:27:48.206667Z","steps":["trace[1143794366] 'agreement among raft nodes before linearized reading' (duration: 130.41202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:48.206596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.840891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:27:48.206847Z","caller":"traceutil/trace.go:171","msg":"trace[322423716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"129.10297ms","start":"2026-04-28T03:27:48.077722Z","end":"2026-04-28T03:27:48.206825Z","steps":["trace[322423716] 'agreement among raft nodes before linearized reading' (duration: 128.79145ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:48.206739Z","caller":"traceutil/trace.go:171","msg":"trace[1898555460] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"122.902763ms","start":"2026-04-28T03:27:48.083821Z","end":"2026-04-28T03:27:48.206723Z","steps":["trace[1898555460] 'process raft request' (duration: 122.080868ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:48.374056Z","caller":"traceutil/trace.go:171","msg":"trace[2048436658] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"162.879306ms","start":"2026-04-28T03:27:48.21115Z","end":"2026-04-28T03:27:48.37403Z","steps":["trace[2048436658] 'process raft request' (duration: 122.657725ms)","trace[2048436658] 'compare' (duration: 40.071457ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:48.374412Z","caller":"traceutil/trace.go:171","msg":"trace[808327535] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1437; }","duration":"135.034217ms","start":"2026-04-28T03:27:48.239359Z","end":"2026-04-28T03:27:48.374393Z","steps":["trace[808327535] 'read index received' (duration: 94.350351ms)","trace[808327535] 'applied index is now lower than readState.Index' (duration: 40.682786ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:48.374556Z","caller":"traceutil/trace.go:171","msg":"trace[1396465405] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"163.019082ms","start":"2026-04-28T03:27:48.211519Z","end":"2026-04-28T03:27:48.374538Z","steps":["trace[1396465405] 'process raft request' (duration: 162.798755ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:48.374521Z","caller":"traceutil/trace.go:171","msg":"trace[1925259687] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"163.172536ms","start":"2026-04-28T03:27:48.211293Z","end":"2026-04-28T03:27:48.374465Z","steps":["trace[1925259687] 'process raft request' (duration: 162.919278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:48.374604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.232534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T03:27:48.374697Z","caller":"traceutil/trace.go:171","msg":"trace[1215832167] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1387; }","duration":"135.378038ms","start":"2026-04-28T03:27:48.239304Z","end":"2026-04-28T03:27:48.374682Z","steps":["trace[1215832167] 'agreement among raft nodes before linearized reading' (duration: 135.165691ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:48.374762Z","caller":"traceutil/trace.go:171","msg":"trace[813959326] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"163.574498ms","start":"2026-04-28T03:27:48.21115Z","end":"2026-04-28T03:27:48.374725Z","steps":["trace[813959326] 'process raft request' (duration: 162.836755ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:55.024841Z","caller":"traceutil/trace.go:171","msg":"trace[2028780393] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"106.332ms","start":"2026-04-28T03:27:54.918488Z","end":"2026-04-28T03:27:55.02482Z","steps":["trace[2028780393] 'read index received' (duration: 106.163575ms)","trace[2028780393] 'applied index is now lower than readState.Index' (duration: 167.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:55.0249Z","caller":"traceutil/trace.go:171","msg":"trace[1406039940] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"165.629762ms","start":"2026-04-28T03:27:54.85924Z","end":"2026-04-28T03:27:55.024869Z","steps":["trace[1406039940] 'process raft request' (duration: 165.454987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:55.024978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.482465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:55.025017Z","caller":"traceutil/trace.go:171","msg":"trace[134689418] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1415; }","duration":"106.581008ms","start":"2026-04-28T03:27:54.918425Z","end":"2026-04-28T03:27:55.025006Z","steps":["trace[134689418] 'agreement among raft nodes before linearized reading' (duration: 106.478995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:28:44.80412Z","caller":"traceutil/trace.go:171","msg":"trace[1452165199] linearizableReadLoop","detail":"{readStateIndex:1707; appliedIndex:1705; }","duration":"131.95728ms","start":"2026-04-28T03:28:44.672141Z","end":"2026-04-28T03:28:44.804098Z","steps":["trace[1452165199] 'read index received' (duration: 22.511731ms)","trace[1452165199] 'applied index is now lower than readState.Index' (duration: 109.444159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:28:44.804372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.565032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T03:28:44.804412Z","caller":"traceutil/trace.go:171","msg":"trace[1001973610] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1642; }","duration":"127.645093ms","start":"2026-04-28T03:28:44.676757Z","end":"2026-04-28T03:28:44.804402Z","steps":["trace[1001973610] 'agreement among raft nodes before linearized reading' (duration: 127.482428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:28:44.804445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.28113ms","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-28T03:28:44.804743Z","caller":"traceutil/trace.go:171","msg":"trace[25164268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1642; }","duration":"132.618111ms","start":"2026-04-28T03:28:44.67211Z","end":"2026-04-28T03:28:44.804728Z","steps":["trace[25164268] 'agreement among raft nodes before linearized reading' (duration: 132.121945ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:28:44.804736Z","caller":"traceutil/trace.go:171","msg":"trace[557356755] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"140.091777ms","start":"2026-04-28T03:28:44.664618Z","end":"2026-04-28T03:28:44.80471Z","steps":["trace[557356755] 'process raft request' (duration: 139.384545ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:28:54.895252Z","caller":"traceutil/trace.go:171","msg":"trace[1661323200] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"156.404935ms","start":"2026-04-28T03:28:54.738812Z","end":"2026-04-28T03:28:54.895217Z","steps":["trace[1661323200] 'process raft request' (duration: 154.865646ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:29:07.170079Z","caller":"traceutil/trace.go:171","msg":"trace[885887792] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"115.201682ms","start":"2026-04-28T03:29:07.05483Z","end":"2026-04-28T03:29:07.170031Z","steps":["trace[885887792] 'process raft request' (duration: 104.516255ms)","trace[885887792] 'compare' (duration: 10.568424ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:30:40.17193Z","caller":"traceutil/trace.go:171","msg":"trace[2114725711] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"128.374022ms","start":"2026-04-28T03:30:40.043519Z","end":"2026-04-28T03:30:40.171893Z","steps":["trace[2114725711] 'process raft request' (duration: 128.082562ms)"],"step_count":1}