{"level":"warn","ts":"2026-03-30T17:56:11.236184Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:56:11.236294Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.80: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.80:2380","--initial-cluster=instance=https://199.19.213.80:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.80:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.80:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-30T17:56:11.236417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:56:11.236429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.80:2380"]} {"level":"info","ts":"2026-03-30T17:56:11.236445Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-30T17:56:11.237217Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"]} {"level":"info","ts":"2026-03-30T17:56:11.237302Z","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.80:2380"],"listen-peer-urls":["https://199.19.213.80:2380"],"advertise-client-urls":["https://199.19.213.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.80:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-30T17:56:11.243528Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.678984ms"} {"level":"info","ts":"2026-03-30T17:56:11.254156Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95f079218969d088","cluster-id":"4cb7d1aa36c4178f"} {"level":"info","ts":"2026-03-30T17:56:11.254226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T17:56:11.254249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became follower at term 0"} {"level":"info","ts":"2026-03-30T17:56:11.254267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95f079218969d088 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T17:56:11.254275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became follower at term 1"} {"level":"info","ts":"2026-03-30T17:56:11.254315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=(10804268691008114824)"} {"level":"warn","ts":"2026-03-30T17:56:11.260598Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T17:56:11.265207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T17:56:11.268177Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-30T17:56:11.273162Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95f079218969d088","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T17:56:11.273381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95f079218969d088","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T17:56:11.274635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=(10804268691008114824)"} {"level":"info","ts":"2026-03-30T17:56:11.274765Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4cb7d1aa36c4178f","local-member-id":"95f079218969d088","added-peer-id":"95f079218969d088","added-peer-peer-urls":["https://199.19.213.80:2380"]} {"level":"info","ts":"2026-03-30T17:56:11.273847Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:56:11.275597Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:56:11.275618Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:56:11.27849Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-30T17:56:11.278626Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.80:2380"} {"level":"info","ts":"2026-03-30T17:56:11.278674Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.80:2380"} {"level":"info","ts":"2026-03-30T17:56:11.27887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95f079218969d088","initial-advertise-peer-urls":["https://199.19.213.80:2380"],"listen-peer-urls":["https://199.19.213.80:2380"],"advertise-client-urls":["https://199.19.213.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T17:56:11.278928Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T17:56:11.655149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T17:56:11.655218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T17:56:11.655256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 received MsgPreVoteResp from 95f079218969d088 at term 1"} {"level":"info","ts":"2026-03-30T17:56:11.655275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became candidate at term 2"} {"level":"info","ts":"2026-03-30T17:56:11.655286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 received MsgVoteResp from 95f079218969d088 at term 2"} {"level":"info","ts":"2026-03-30T17:56:11.655302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became leader at term 2"} {"level":"info","ts":"2026-03-30T17:56:11.655316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95f079218969d088 elected leader 95f079218969d088 at term 2"} {"level":"info","ts":"2026-03-30T17:56:11.656465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:56:11.657596Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95f079218969d088","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.80:2379]}","request-path":"/0/members/95f079218969d088/attributes","cluster-id":"4cb7d1aa36c4178f","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T17:56:11.657798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:56:11.657915Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:56:11.658038Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T17:56:11.658087Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T17:56:11.658146Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4cb7d1aa36c4178f","local-member-id":"95f079218969d088","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:56:11.65823Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:56:11.658262Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:56:11.661589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-30T17:56:11.662019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.80:2379"} {"level":"warn","ts":"2026-03-30T17:56:15.016749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.440685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:56:15.017062Z","caller":"traceutil/trace.go:171","msg":"trace[401874451] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"724.53718ms","start":"2026-03-30T17:56:14.29249Z","end":"2026-03-30T17:56:15.017028Z","steps":["trace[401874451] 'process raft request' (duration: 16.027025ms)","trace[401874451] 'compare' (duration: 707.240581ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:56:15.017253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:14.292475Z","time spent":"724.699062ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":659,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:56:15.355293Z","caller":"traceutil/trace.go:171","msg":"trace[936095484] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"263.875748ms","start":"2026-03-30T17:56:15.091383Z","end":"2026-03-30T17:56:15.355258Z","steps":["trace[936095484] 'process raft request' (duration: 248.331811ms)","trace[936095484] 'compare' (duration: 15.411534ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:15.80465Z","caller":"traceutil/trace.go:171","msg":"trace[2097444902] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"443.25961ms","start":"2026-03-30T17:56:15.361361Z","end":"2026-03-30T17:56:15.804621Z","steps":["trace[2097444902] 'process raft request' (duration: 440.702893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:15.804804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:15.36133Z","time spent":"443.403282ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":588,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:56:42.908477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.442772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:56:42.908871Z","caller":"traceutil/trace.go:171","msg":"trace[845294339] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"457.684957ms","start":"2026-03-30T17:56:42.451166Z","end":"2026-03-30T17:56:42.908851Z","steps":["trace[845294339] 'read index received' (duration: 136.319346ms)","trace[845294339] 'applied index is now lower than readState.Index' (duration: 321.364761ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:42.908973Z","caller":"traceutil/trace.go:171","msg":"trace[6524361] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"478.827647ms","start":"2026-03-30T17:56:42.430135Z","end":"2026-03-30T17:56:42.908962Z","steps":["trace[6524361] 'process raft request' (duration: 156.798224ms)","trace[6524361] 'compare' (duration: 321.227598ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:56:42.909037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:42.430102Z","time spent":"478.905148ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:56:42.909168Z","caller":"traceutil/trace.go:171","msg":"trace[1265855961] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"442.12536ms","start":"2026-03-30T17:56:42.466971Z","end":"2026-03-30T17:56:42.909096Z","steps":["trace[1265855961] 'process raft request' (duration: 441.770863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:42.909257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.208499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:56:42.909351Z","caller":"traceutil/trace.go:171","msg":"trace[1974120749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"133.361022ms","start":"2026-03-30T17:56:42.775966Z","end":"2026-03-30T17:56:42.909327Z","steps":["trace[1974120749] 'agreement among raft nodes before linearized reading' (duration: 133.163349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:42.90947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.302788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:56:42.909529Z","caller":"traceutil/trace.go:171","msg":"trace[204942847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"458.42805ms","start":"2026-03-30T17:56:42.451088Z","end":"2026-03-30T17:56:42.909516Z","steps":["trace[204942847] 'agreement among raft nodes before linearized reading' (duration: 458.323439ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:42.909578Z","caller":"traceutil/trace.go:171","msg":"trace[1265877954] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"472.007281ms","start":"2026-03-30T17:56:42.437556Z","end":"2026-03-30T17:56:42.909563Z","steps":["trace[1265877954] 'process raft request' (duration: 471.064934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:42.909579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:42.451073Z","time spent":"458.496932ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-30T17:56:42.90973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:42.466932Z","time spent":"442.362493ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:56:42.910419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:42.437522Z","time spent":"472.090552ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:56:45.662471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.644872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:56:45.662605Z","caller":"traceutil/trace.go:171","msg":"trace[271558108] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"116.851156ms","start":"2026-03-30T17:56:45.545723Z","end":"2026-03-30T17:56:45.662575Z","steps":["trace[271558108] 'range keys from in-memory index tree' (duration: 116.52983ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:51.87088Z","caller":"traceutil/trace.go:171","msg":"trace[1677549647] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"110.871605ms","start":"2026-03-30T17:56:51.759979Z","end":"2026-03-30T17:56:51.87085Z","steps":["trace[1677549647] 'read index received' (duration: 110.712063ms)","trace[1677549647] 'applied index is now lower than readState.Index' (duration: 157.982µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:56:51.871155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.995848ms","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":"warn","ts":"2026-03-30T17:56:51.871137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.126911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:56:51.871027Z","caller":"traceutil/trace.go:171","msg":"trace[652835285] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"159.532465ms","start":"2026-03-30T17:56:51.711439Z","end":"2026-03-30T17:56:51.870972Z","steps":["trace[652835285] 'process raft request' (duration: 159.228959ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:51.871242Z","caller":"traceutil/trace.go:171","msg":"trace[1170613004] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:678; }","duration":"111.274033ms","start":"2026-03-30T17:56:51.759949Z","end":"2026-03-30T17:56:51.871223Z","steps":["trace[1170613004] 'agreement among raft nodes before linearized reading' (duration: 111.09702ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:51.871221Z","caller":"traceutil/trace.go:171","msg":"trace[1931851058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:678; }","duration":"111.10161ms","start":"2026-03-30T17:56:51.760106Z","end":"2026-03-30T17:56:51.871207Z","steps":["trace[1931851058] 'agreement among raft nodes before linearized reading' (duration: 110.973918ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:52.348791Z","caller":"traceutil/trace.go:171","msg":"trace[1538408033] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"114.649994ms","start":"2026-03-30T17:56:52.234082Z","end":"2026-03-30T17:56:52.348732Z","steps":["trace[1538408033] 'process raft request' (duration: 114.317178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:53.315657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.153953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:56:53.315733Z","caller":"traceutil/trace.go:171","msg":"trace[111583189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"129.239115ms","start":"2026-03-30T17:56:53.186474Z","end":"2026-03-30T17:56:53.315714Z","steps":["trace[111583189] 'range keys from in-memory index tree' (duration: 129.016251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:53.315776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.137052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:56:53.315878Z","caller":"traceutil/trace.go:171","msg":"trace[1200001457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"350.305244ms","start":"2026-03-30T17:56:52.965549Z","end":"2026-03-30T17:56:53.315854Z","steps":["trace[1200001457] 'range keys from in-memory index tree' (duration: 349.883987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:53.31589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.899245ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:56:53.315943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:52.965525Z","time spent":"350.389336ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-30T17:56:53.315969Z","caller":"traceutil/trace.go:171","msg":"trace[1648208131] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:680; }","duration":"117.982347ms","start":"2026-03-30T17:56:53.197977Z","end":"2026-03-30T17:56:53.315959Z","steps":["trace[1648208131] 'range keys from in-memory index tree' (duration: 117.886605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:53.316233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.684426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:56:53.316273Z","caller":"traceutil/trace.go:171","msg":"trace[328334557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"347.763918ms","start":"2026-03-30T17:56:52.968491Z","end":"2026-03-30T17:56:53.316255Z","steps":["trace[328334557] 'range keys from in-memory index tree' (duration: 347.572854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:53.316312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:52.968467Z","time spent":"347.83721ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:56:53.424856Z","caller":"traceutil/trace.go:171","msg":"trace[1684514516] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"103.547931ms","start":"2026-03-30T17:56:53.321283Z","end":"2026-03-30T17:56:53.424831Z","steps":["trace[1684514516] 'process raft request' (duration: 103.368588ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:53.439054Z","caller":"traceutil/trace.go:171","msg":"trace[1520783048] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"117.213023ms","start":"2026-03-30T17:56:53.32174Z","end":"2026-03-30T17:56:53.438953Z","steps":["trace[1520783048] 'process raft request' (duration: 116.824166ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:53.438993Z","caller":"traceutil/trace.go:171","msg":"trace[775901089] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"116.988079ms","start":"2026-03-30T17:56:53.321945Z","end":"2026-03-30T17:56:53.438933Z","steps":["trace[775901089] 'process raft request' (duration: 116.915317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:09.793154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.088981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:57:09.79326Z","caller":"traceutil/trace.go:171","msg":"trace[922383706] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"350.370497ms","start":"2026-03-30T17:57:09.442874Z","end":"2026-03-30T17:57:09.793244Z","steps":["trace[922383706] 'read index received' (duration: 245.085373ms)","trace[922383706] 'applied index is now lower than readState.Index' (duration: 105.282234ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:09.793584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.090451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:57:09.793616Z","caller":"traceutil/trace.go:171","msg":"trace[1258825585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"299.142121ms","start":"2026-03-30T17:57:09.494463Z","end":"2026-03-30T17:57:09.793605Z","steps":["trace[1258825585] 'agreement among raft nodes before linearized reading' (duration: 298.999479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:09.793627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.715733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qrkr9\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-03-30T17:57:09.793722Z","caller":"traceutil/trace.go:171","msg":"trace[1696539548] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qrkr9; range_end:; response_count:1; response_revision:794; }","duration":"350.878806ms","start":"2026-03-30T17:57:09.442822Z","end":"2026-03-30T17:57:09.7937Z","steps":["trace[1696539548] 'agreement among raft nodes before linearized reading' (duration: 350.55775ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:09.793724Z","caller":"traceutil/trace.go:171","msg":"trace[1310102391] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"376.315425ms","start":"2026-03-30T17:57:09.417371Z","end":"2026-03-30T17:57:09.793687Z","steps":["trace[1310102391] 'process raft request' (duration: 270.611963ms)","trace[1310102391] 'compare' (duration: 104.968538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:09.793779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.442797Z","time spent":"350.968107ms","remote":"127.0.0.1:45264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qrkr9\" "} {"level":"warn","ts":"2026-03-30T17:57:09.793792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.321901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:57:09.793823Z","caller":"traceutil/trace.go:171","msg":"trace[538903736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"288.397192ms","start":"2026-03-30T17:57:09.505416Z","end":"2026-03-30T17:57:09.793813Z","steps":["trace[538903736] 'agreement among raft nodes before linearized reading' (duration: 288.322311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:09.793852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:09.417336Z","time spent":"376.443467ms","remote":"127.0.0.1:45354","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-03-30T17:57:09.7939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.817266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-30T17:57:09.79398Z","caller":"traceutil/trace.go:171","msg":"trace[1918477265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"284.905158ms","start":"2026-03-30T17:57:09.509055Z","end":"2026-03-30T17:57:09.793961Z","steps":["trace[1918477265] 'agreement among raft nodes before linearized reading' (duration: 284.663913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.123576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15026433054447992459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:45.72109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.839639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:57:45.721273Z","caller":"traceutil/trace.go:171","msg":"trace[1275670441] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"1.098571777s","start":"2026-03-30T17:57:44.622685Z","end":"2026-03-30T17:57:45.721257Z","steps":["trace[1275670441] 'read index received' (duration: 648.426653ms)","trace[1275670441] 'applied index is now lower than readState.Index' (duration: 450.144094ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:45.721359Z","caller":"traceutil/trace.go:171","msg":"trace[114930444] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.099453283s","start":"2026-03-30T17:57:44.621892Z","end":"2026-03-30T17:57:45.721346Z","steps":["trace[114930444] 'process raft request' (duration: 649.277048ms)","trace[114930444] 'compare' (duration: 449.372051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:45.721426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:44.621865Z","time spent":"1.099525145s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:45.721485Z","caller":"traceutil/trace.go:171","msg":"trace[1346862721] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"835.315273ms","start":"2026-03-30T17:57:44.886145Z","end":"2026-03-30T17:57:45.72146Z","steps":["trace[1346862721] 'process raft request' (duration: 835.044538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.721576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.441563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T17:57:45.721617Z","caller":"traceutil/trace.go:171","msg":"trace[104249383] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1009; }","duration":"551.528794ms","start":"2026-03-30T17:57:45.170075Z","end":"2026-03-30T17:57:45.721604Z","steps":["trace[104249383] 'agreement among raft nodes before linearized reading' (duration: 551.458193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.721625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:44.886123Z","time spent":"835.427014ms","remote":"127.0.0.1:45354","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-03-30T17:57:45.721651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.170048Z","time spent":"551.593976ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:57:45.721508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098812431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-30T17:57:45.721692Z","caller":"traceutil/trace.go:171","msg":"trace[1384298949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"1.099033696s","start":"2026-03-30T17:57:44.622649Z","end":"2026-03-30T17:57:45.721683Z","steps":["trace[1384298949] 'agreement among raft nodes before linearized reading' (duration: 1.098763281s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.721726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:44.62264Z","time spent":"1.099076506s","remote":"127.0.0.1:45354","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-03-30T17:57:45.721821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.380616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-30T17:57:45.721836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.057922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:45.721862Z","caller":"traceutil/trace.go:171","msg":"trace[678700473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"536.119694ms","start":"2026-03-30T17:57:45.185735Z","end":"2026-03-30T17:57:45.721854Z","steps":["trace[678700473] 'agreement among raft nodes before linearized reading' (duration: 536.073583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.72189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.185714Z","time spent":"536.172814ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:57:45.721856Z","caller":"traceutil/trace.go:171","msg":"trace[1942115006] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1009; }","duration":"167.228482ms","start":"2026-03-30T17:57:45.554618Z","end":"2026-03-30T17:57:45.721847Z","steps":["trace[1942115006] 'agreement among raft nodes before linearized reading' (duration: 167.17158ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:45.864431Z","caller":"traceutil/trace.go:171","msg":"trace[490939821] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"137.779844ms","start":"2026-03-30T17:57:45.726625Z","end":"2026-03-30T17:57:45.864405Z","steps":["trace[490939821] 'process raft request' (duration: 134.733468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.329746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.565085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-30T17:58:06.329834Z","caller":"traceutil/trace.go:171","msg":"trace[666708308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1076; }","duration":"243.653017ms","start":"2026-03-30T17:58:06.086158Z","end":"2026-03-30T17:58:06.329812Z","steps":["trace[666708308] 'range keys from in-memory index tree' (duration: 243.363921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.329872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.190647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:58:06.329901Z","caller":"traceutil/trace.go:171","msg":"trace[1093551996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"255.270908ms","start":"2026-03-30T17:58:06.074622Z","end":"2026-03-30T17:58:06.329893Z","steps":["trace[1093551996] 'range keys from in-memory index tree' (duration: 255.046894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.329894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.423094ms","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":"warn","ts":"2026-03-30T17:58:06.32999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.069143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.80\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-30T17:58:06.33001Z","caller":"traceutil/trace.go:171","msg":"trace[1338566626] range","detail":"{range_begin:/registry/masterleases/199.19.213.80; range_end:; response_count:1; response_revision:1076; }","duration":"233.132734ms","start":"2026-03-30T17:58:06.096871Z","end":"2026-03-30T17:58:06.330004Z","steps":["trace[1338566626] 'range keys from in-memory index tree' (duration: 232.914589ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:06.329996Z","caller":"traceutil/trace.go:171","msg":"trace[1627778227] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1076; }","duration":"190.622509ms","start":"2026-03-30T17:58:06.13935Z","end":"2026-03-30T17:58:06.329973Z","steps":["trace[1627778227] 'count revisions from in-memory index tree' (duration: 190.319393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.329985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.260252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-30T17:58:06.330066Z","caller":"traceutil/trace.go:171","msg":"trace[151272358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1076; }","duration":"241.378895ms","start":"2026-03-30T17:58:06.088667Z","end":"2026-03-30T17:58:06.330046Z","steps":["trace[151272358] 'range keys from in-memory index tree' (duration: 241.065779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.330219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.047128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:06.330244Z","caller":"traceutil/trace.go:171","msg":"trace[1395483316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"144.12894ms","start":"2026-03-30T17:58:06.186109Z","end":"2026-03-30T17:58:06.330238Z","steps":["trace[1395483316] 'range keys from in-memory index tree' (duration: 143.932076ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:06.931014Z","caller":"traceutil/trace.go:171","msg":"trace[1244194734] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"597.132517ms","start":"2026-03-30T17:58:06.33385Z","end":"2026-03-30T17:58:06.930983Z","steps":["trace[1244194734] 'process raft request' (duration: 596.846051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.93129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.333837Z","time spent":"597.329542ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:06.931995Z","caller":"traceutil/trace.go:171","msg":"trace[414336341] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"598.071265ms","start":"2026-03-30T17:58:06.33389Z","end":"2026-03-30T17:58:06.931962Z","steps":["trace[414336341] 'process raft request' (duration: 597.019065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.932202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.333881Z","time spent":"598.195347ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:06.932677Z","caller":"traceutil/trace.go:171","msg":"trace[473417195] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"598.730357ms","start":"2026-03-30T17:58:06.333918Z","end":"2026-03-30T17:58:06.932648Z","steps":["trace[473417195] 'process raft request' (duration: 597.918001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.932762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.333902Z","time spent":"598.819159ms","remote":"127.0.0.1:45354","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-03-30T17:58:06.932925Z","caller":"traceutil/trace.go:171","msg":"trace[1307164074] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1121; }","duration":"532.038789ms","start":"2026-03-30T17:58:06.400861Z","end":"2026-03-30T17:58:06.9329Z","steps":["trace[1307164074] 'read index received' (duration: 13.73475ms)","trace[1307164074] 'applied index is now lower than readState.Index' (duration: 518.302819ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:06.93309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.208172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T17:58:06.933133Z","caller":"traceutil/trace.go:171","msg":"trace[1306634348] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1080; }","duration":"532.318624ms","start":"2026-03-30T17:58:06.4008Z","end":"2026-03-30T17:58:06.933118Z","steps":["trace[1306634348] 'agreement among raft nodes before linearized reading' (duration: 532.194062ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:06.933165Z","caller":"traceutil/trace.go:171","msg":"trace[337332241] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"597.425664ms","start":"2026-03-30T17:58:06.335727Z","end":"2026-03-30T17:58:06.933152Z","steps":["trace[337332241] 'process raft request' (duration: 596.855394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:06.933175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.400776Z","time spent":"532.387026ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:58:06.933226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.335717Z","time spent":"597.476304ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:07.196151Z","caller":"traceutil/trace.go:171","msg":"trace[2103871573] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"780.370691ms","start":"2026-03-30T17:58:06.415762Z","end":"2026-03-30T17:58:07.196132Z","steps":["trace[2103871573] 'process raft request' (duration: 751.788509ms)","trace[2103871573] 'compare' (duration: 28.46643ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:07.196243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:06.415735Z","time spent":"780.456654ms","remote":"127.0.0.1:45080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:08.122386Z","caller":"traceutil/trace.go:171","msg":"trace[2066906049] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"116.17925ms","start":"2026-03-30T17:58:08.006179Z","end":"2026-03-30T17:58:08.122358Z","steps":["trace[2066906049] 'process raft request' (duration: 115.466618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:08.375258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.257572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:08.375326Z","caller":"traceutil/trace.go:171","msg":"trace[824917138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"190.372135ms","start":"2026-03-30T17:58:08.184938Z","end":"2026-03-30T17:58:08.37531Z","steps":["trace[824917138] 'range keys from in-memory index tree' (duration: 190.155451ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:09.089574Z","caller":"traceutil/trace.go:171","msg":"trace[419684301] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"149.03269ms","start":"2026-03-30T17:58:08.940501Z","end":"2026-03-30T17:58:09.089533Z","steps":["trace[419684301] 'process raft request' (duration: 148.814326ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:09.089844Z","caller":"traceutil/trace.go:171","msg":"trace[526519731] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"147.38458ms","start":"2026-03-30T17:58:08.942433Z","end":"2026-03-30T17:58:09.089817Z","steps":["trace[526519731] 'process raft request' (duration: 147.058224ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:09.09014Z","caller":"traceutil/trace.go:171","msg":"trace[765868691] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"145.913644ms","start":"2026-03-30T17:58:08.944214Z","end":"2026-03-30T17:58:09.090128Z","steps":["trace[765868691] 'process raft request' (duration: 145.548197ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:22.111047Z","caller":"traceutil/trace.go:171","msg":"trace[1800296189] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"252.168462ms","start":"2026-03-30T17:58:21.858832Z","end":"2026-03-30T17:58:22.111Z","steps":["trace[1800296189] 'process raft request' (duration: 251.960279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.656031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15026433054447992892,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:58:23.86176Z","caller":"traceutil/trace.go:171","msg":"trace[128116864] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"706.34472ms","start":"2026-03-30T17:58:23.155375Z","end":"2026-03-30T17:58:23.86172Z","steps":["trace[128116864] 'read index received' (duration: 706.100646ms)","trace[128116864] 'applied index is now lower than readState.Index' (duration: 243.284µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:23.861887Z","caller":"traceutil/trace.go:171","msg":"trace[1891362588] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"798.678495ms","start":"2026-03-30T17:58:23.063158Z","end":"2026-03-30T17:58:23.861836Z","steps":["trace[1891362588] 'process raft request' (duration: 798.37464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.862035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.915386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-30T17:58:23.862088Z","caller":"traceutil/trace.go:171","msg":"trace[186811396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1190; }","duration":"704.048169ms","start":"2026-03-30T17:58:23.158022Z","end":"2026-03-30T17:58:23.862071Z","steps":["trace[186811396] 'agreement among raft nodes before linearized reading' (duration: 703.885166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.862125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.157989Z","time spent":"704.12708ms","remote":"127.0.0.1:45354","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-03-30T17:58:23.862177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.763909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-30T17:58:23.862217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.052287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:23.862285Z","caller":"traceutil/trace.go:171","msg":"trace[714357078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"706.923461ms","start":"2026-03-30T17:58:23.155334Z","end":"2026-03-30T17:58:23.862258Z","steps":["trace[714357078] 'agreement among raft nodes before linearized reading' (duration: 706.543064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.862122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.063142Z","time spent":"798.854959ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:23.86231Z","caller":"traceutil/trace.go:171","msg":"trace[1616286180] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1190; }","duration":"385.2347ms","start":"2026-03-30T17:58:23.477046Z","end":"2026-03-30T17:58:23.86228Z","steps":["trace[1616286180] 'agreement among raft nodes before linearized reading' (duration: 385.068427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.862344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.155317Z","time spent":"707.017392ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-30T17:58:23.862362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.477001Z","time spent":"385.346812ms","remote":"127.0.0.1:44212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-03-30T17:58:23.862445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.200896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-30T17:58:23.862472Z","caller":"traceutil/trace.go:171","msg":"trace[1698170541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"701.236516ms","start":"2026-03-30T17:58:23.161228Z","end":"2026-03-30T17:58:23.862465Z","steps":["trace[1698170541] 'agreement among raft nodes before linearized reading' (duration: 701.159985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.8625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.161218Z","time spent":"701.275438ms","remote":"127.0.0.1:45354","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-03-30T17:58:23.86237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.184838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:23.862592Z","caller":"traceutil/trace.go:171","msg":"trace[252575555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"677.420442ms","start":"2026-03-30T17:58:23.185163Z","end":"2026-03-30T17:58:23.862583Z","steps":["trace[252575555] 'agreement among raft nodes before linearized reading' (duration: 677.183827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:23.862639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.185155Z","time spent":"677.474852ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:58:24.432423Z","caller":"traceutil/trace.go:171","msg":"trace[1266145556] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"565.609162ms","start":"2026-03-30T17:58:23.866565Z","end":"2026-03-30T17:58:24.432174Z","steps":["trace[1266145556] 'process raft request' (duration: 565.415889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:24.432567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.866548Z","time spent":"565.942937ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:24.433336Z","caller":"traceutil/trace.go:171","msg":"trace[357897711] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1241; }","duration":"246.964627ms","start":"2026-03-30T17:58:24.186351Z","end":"2026-03-30T17:58:24.433316Z","steps":["trace[357897711] 'read index received' (duration: 245.775125ms)","trace[357897711] 'applied index is now lower than readState.Index' (duration: 1.188062ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:24.433393Z","caller":"traceutil/trace.go:171","msg":"trace[1047988592] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"565.835046ms","start":"2026-03-30T17:58:23.867528Z","end":"2026-03-30T17:58:24.433363Z","steps":["trace[1047988592] 'process raft request' (duration: 565.647872ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:24.433434Z","caller":"traceutil/trace.go:171","msg":"trace[1515540086] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"563.961262ms","start":"2026-03-30T17:58:23.869463Z","end":"2026-03-30T17:58:24.433424Z","steps":["trace[1515540086] 'process raft request' (duration: 563.783319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:24.433515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.869454Z","time spent":"564.019073ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:58:24.433555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:23.867508Z","time spent":"565.958318ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:24.433689Z","caller":"traceutil/trace.go:171","msg":"trace[474872798] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"315.68129ms","start":"2026-03-30T17:58:24.117995Z","end":"2026-03-30T17:58:24.433676Z","steps":["trace[474872798] 'process raft request' (duration: 315.285013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:24.433803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:24.117963Z","time spent":"315.755561ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:58:24.433968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.622148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:24.434004Z","caller":"traceutil/trace.go:171","msg":"trace[1767910945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"247.69649ms","start":"2026-03-30T17:58:24.186298Z","end":"2026-03-30T17:58:24.433994Z","steps":["trace[1767910945] 'agreement among raft nodes before linearized reading' (duration: 247.594658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:24.434071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.635086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-30T17:58:24.434129Z","caller":"traceutil/trace.go:171","msg":"trace[591810091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"243.725738ms","start":"2026-03-30T17:58:24.190391Z","end":"2026-03-30T17:58:24.434117Z","steps":["trace[591810091] 'agreement among raft nodes before linearized reading' (duration: 243.542854ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:36.708196Z","caller":"traceutil/trace.go:171","msg":"trace[543839423] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"211.163168ms","start":"2026-03-30T17:58:36.497008Z","end":"2026-03-30T17:58:36.708171Z","steps":["trace[543839423] 'read index received' (duration: 210.939414ms)","trace[543839423] 'applied index is now lower than readState.Index' (duration: 222.424µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:36.708296Z","caller":"traceutil/trace.go:171","msg":"trace[346706328] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"211.941621ms","start":"2026-03-30T17:58:36.496323Z","end":"2026-03-30T17:58:36.708264Z","steps":["trace[346706328] 'process raft request' (duration: 211.703517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:36.708392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.378432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:58:36.70842Z","caller":"traceutil/trace.go:171","msg":"trace[806106479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1231; }","duration":"211.454283ms","start":"2026-03-30T17:58:36.496956Z","end":"2026-03-30T17:58:36.70841Z","steps":["trace[806106479] 'agreement among raft nodes before linearized reading' (duration: 211.335171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:36.70846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.009113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-30T17:58:36.708488Z","caller":"traceutil/trace.go:171","msg":"trace[896736202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"200.101485ms","start":"2026-03-30T17:58:36.50838Z","end":"2026-03-30T17:58:36.708482Z","steps":["trace[896736202] 'agreement among raft nodes before linearized reading' (duration: 199.999563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:36.708559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.830453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-30T17:58:36.70857Z","caller":"traceutil/trace.go:171","msg":"trace[2065685263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"190.903545ms","start":"2026-03-30T17:58:36.517663Z","end":"2026-03-30T17:58:36.708566Z","steps":["trace[2065685263] 'agreement among raft nodes before linearized reading' (duration: 190.865274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:36.708576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.587393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:58:36.708596Z","caller":"traceutil/trace.go:171","msg":"trace[322676036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"188.626094ms","start":"2026-03-30T17:58:36.519964Z","end":"2026-03-30T17:58:36.70859Z","steps":["trace[322676036] 'agreement among raft nodes before linearized reading' (duration: 188.551323ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:36.884679Z","caller":"traceutil/trace.go:171","msg":"trace[2059226244] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"172.214672ms","start":"2026-03-30T17:58:36.712437Z","end":"2026-03-30T17:58:36.884652Z","steps":["trace[2059226244] 'process raft request' (duration: 172.038259ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:36.997166Z","caller":"traceutil/trace.go:171","msg":"trace[1498314308] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"284.161198ms","start":"2026-03-30T17:58:36.712974Z","end":"2026-03-30T17:58:36.997135Z","steps":["trace[1498314308] 'process raft request' (duration: 209.905095ms)","trace[1498314308] 'compare' (duration: 74.032439ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:36.997509Z","caller":"traceutil/trace.go:171","msg":"trace[1001138265] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"284.086806ms","start":"2026-03-30T17:58:36.713394Z","end":"2026-03-30T17:58:36.997481Z","steps":["trace[1001138265] 'process raft request' (duration: 283.688768ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:36.997756Z","caller":"traceutil/trace.go:171","msg":"trace[588680792] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"284.011075ms","start":"2026-03-30T17:58:36.713733Z","end":"2026-03-30T17:58:36.997744Z","steps":["trace[588680792] 'process raft request' (duration: 283.664298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:37.656594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.179818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T17:58:37.656664Z","caller":"traceutil/trace.go:171","msg":"trace[738069704] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1237; }","duration":"241.356262ms","start":"2026-03-30T17:58:37.415292Z","end":"2026-03-30T17:58:37.656648Z","steps":["trace[738069704] 'count revisions from in-memory index tree' (duration: 241.041816ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:38.055655Z","caller":"traceutil/trace.go:171","msg":"trace[83379568] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"120.261789ms","start":"2026-03-30T17:58:37.935364Z","end":"2026-03-30T17:58:38.055626Z","steps":["trace[83379568] 'process raft request' (duration: 120.078086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:38.726166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.401787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:58:38.726183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.642365ms","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-03-30T17:58:38.726509Z","caller":"traceutil/trace.go:171","msg":"trace[1035619807] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1239; }","duration":"279.886999ms","start":"2026-03-30T17:58:38.446489Z","end":"2026-03-30T17:58:38.726376Z","steps":["trace[1035619807] 'count revisions from in-memory index tree' (duration: 279.584004ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:38.726713Z","caller":"traceutil/trace.go:171","msg":"trace[2028142221] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1239; }","duration":"244.542269ms","start":"2026-03-30T17:58:38.481739Z","end":"2026-03-30T17:58:38.726281Z","steps":["trace[2028142221] 'range keys from in-memory index tree' (duration: 244.336367ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:38.730065Z","caller":"traceutil/trace.go:171","msg":"trace[407863863] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"100.570717ms","start":"2026-03-30T17:58:38.62947Z","end":"2026-03-30T17:58:38.730041Z","steps":["trace[407863863] 'process raft request' (duration: 100.458865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.316593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.770029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:58:39.316705Z","caller":"traceutil/trace.go:171","msg":"trace[1856402337] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"602.024405ms","start":"2026-03-30T17:58:38.714657Z","end":"2026-03-30T17:58:39.316682Z","steps":["trace[1856402337] 'process raft request' (duration: 359.080393ms)","trace[1856402337] 'compare' (duration: 242.559925ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:39.316773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:38.714631Z","time spent":"602.111636ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:39.375719Z","caller":"traceutil/trace.go:171","msg":"trace[1543613015] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"642.645931ms","start":"2026-03-30T17:58:38.733054Z","end":"2026-03-30T17:58:39.3757Z","steps":["trace[1543613015] 'process raft request' (duration: 642.503998ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:39.375707Z","caller":"traceutil/trace.go:171","msg":"trace[1898401332] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"486.615035ms","start":"2026-03-30T17:58:38.889063Z","end":"2026-03-30T17:58:39.375678Z","steps":["trace[1898401332] 'read index received' (duration: 184.64832ms)","trace[1898401332] 'applied index is now lower than readState.Index' (duration: 301.965615ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:39.375805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:38.73304Z","time spent":"642.726962ms","remote":"127.0.0.1:45138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:58:39.375972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.672379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:58:39.376021Z","caller":"traceutil/trace.go:171","msg":"trace[798996763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"374.773701ms","start":"2026-03-30T17:58:39.001236Z","end":"2026-03-30T17:58:39.37601Z","steps":["trace[798996763] 'agreement among raft nodes before linearized reading' (duration: 374.605418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.376004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.91911ms","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-03-30T17:58:39.37606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:39.001217Z","time spent":"374.833782ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:58:39.376089Z","caller":"traceutil/trace.go:171","msg":"trace[1454529227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"487.042802ms","start":"2026-03-30T17:58:38.889028Z","end":"2026-03-30T17:58:39.376071Z","steps":["trace[1454529227] 'agreement among raft nodes before linearized reading' (duration: 486.748047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.376077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.608371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:39.376137Z","caller":"traceutil/trace.go:171","msg":"trace[36472494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"189.754194ms","start":"2026-03-30T17:58:39.186372Z","end":"2026-03-30T17:58:39.376126Z","steps":["trace[36472494] 'agreement among raft nodes before linearized reading' (duration: 189.673093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.376139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:38.889017Z","time spent":"487.113604ms","remote":"127.0.0.1:45354","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-03-30T17:58:39.376399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.129462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:58:39.376443Z","caller":"traceutil/trace.go:171","msg":"trace[1798457512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"373.192523ms","start":"2026-03-30T17:58:39.003241Z","end":"2026-03-30T17:58:39.376433Z","steps":["trace[1798457512] 'agreement among raft nodes before linearized reading' (duration: 373.082341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.376475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:39.003233Z","time spent":"373.232804ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-30T17:58:39.376512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.634721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-30T17:58:39.376552Z","caller":"traceutil/trace.go:171","msg":"trace[851930940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"373.675452ms","start":"2026-03-30T17:58:39.002867Z","end":"2026-03-30T17:58:39.376543Z","steps":["trace[851930940] 'agreement among raft nodes before linearized reading' (duration: 373.558779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.37658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:39.002806Z","time spent":"373.766463ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-30T17:58:39.615288Z","caller":"traceutil/trace.go:171","msg":"trace[1841681894] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"234.580777ms","start":"2026-03-30T17:58:39.380674Z","end":"2026-03-30T17:58:39.615254Z","steps":["trace[1841681894] 'process raft request' (duration: 145.722346ms)","trace[1841681894] 'compare' (duration: 88.474645ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:39.615301Z","caller":"traceutil/trace.go:171","msg":"trace[1335294772] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"234.068378ms","start":"2026-03-30T17:58:39.381205Z","end":"2026-03-30T17:58:39.615273Z","steps":["trace[1335294772] 'process raft request' (duration: 233.951126ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:39.615563Z","caller":"traceutil/trace.go:171","msg":"trace[987250832] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"234.17742ms","start":"2026-03-30T17:58:39.381358Z","end":"2026-03-30T17:58:39.615535Z","steps":["trace[987250832] 'process raft request' (duration: 233.863895ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:39.615678Z","caller":"traceutil/trace.go:171","msg":"trace[618695636] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1296; }","duration":"166.931715ms","start":"2026-03-30T17:58:39.448722Z","end":"2026-03-30T17:58:39.615653Z","steps":["trace[618695636] 'read index received' (duration: 77.696868ms)","trace[618695636] 'applied index is now lower than readState.Index' (duration: 89.233227ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:39.615866Z","caller":"traceutil/trace.go:171","msg":"trace[375379534] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"233.63564ms","start":"2026-03-30T17:58:39.382211Z","end":"2026-03-30T17:58:39.615847Z","steps":["trace[375379534] 'process raft request' (duration: 233.231182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:39.615904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.14741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-03-30T17:58:39.61702Z","caller":"traceutil/trace.go:171","msg":"trace[136031380] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1246; }","duration":"168.32672ms","start":"2026-03-30T17:58:39.448679Z","end":"2026-03-30T17:58:39.617006Z","steps":["trace[136031380] 'agreement among raft nodes before linearized reading' (duration: 167.051588ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:39.798079Z","caller":"traceutil/trace.go:171","msg":"trace[1489453525] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"173.827691ms","start":"2026-03-30T17:58:39.624222Z","end":"2026-03-30T17:58:39.79805Z","steps":["trace[1489453525] 'process raft request' (duration: 144.327699ms)","trace[1489453525] 'compare' (duration: 29.35861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:53.688652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15026433054447993229,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:58:53.814059Z","caller":"traceutil/trace.go:171","msg":"trace[1468396189] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"657.898191ms","start":"2026-03-30T17:58:53.156138Z","end":"2026-03-30T17:58:53.814036Z","steps":["trace[1468396189] 'process raft request' (duration: 657.724578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:53.814184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:53.15611Z","time spent":"658.005093ms","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:53.89554Z","caller":"traceutil/trace.go:171","msg":"trace[2005817464] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"507.04935ms","start":"2026-03-30T17:58:53.38845Z","end":"2026-03-30T17:58:53.895499Z","steps":["trace[2005817464] 'process raft request' (duration: 462.229277ms)","trace[2005817464] 'compare' (duration: 44.60203ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:53.895754Z","caller":"traceutil/trace.go:171","msg":"trace[209709476] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"707.815597ms","start":"2026-03-30T17:58:53.187596Z","end":"2026-03-30T17:58:53.895412Z","steps":["trace[209709476] 'read index received' (duration: 626.377382ms)","trace[209709476] 'applied index is now lower than readState.Index' (duration: 81.436755ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:53.895799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:53.388417Z","time spent":"507.286066ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:58:53.895756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.099583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:58:53.896042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.966204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-30T17:58:53.896141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.986994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-30T17:58:53.896239Z","caller":"traceutil/trace.go:171","msg":"trace[367051337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"199.106026ms","start":"2026-03-30T17:58:53.697116Z","end":"2026-03-30T17:58:53.896222Z","steps":["trace[367051337] 'agreement among raft nodes before linearized reading' (duration: 198.853822ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:53.896177Z","caller":"traceutil/trace.go:171","msg":"trace[1167624954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1350; }","duration":"199.148246ms","start":"2026-03-30T17:58:53.697017Z","end":"2026-03-30T17:58:53.896165Z","steps":["trace[1167624954] 'agreement among raft nodes before linearized reading' (duration: 198.904962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:53.896272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.157817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:58:53.896159Z","caller":"traceutil/trace.go:171","msg":"trace[1241568913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"708.541711ms","start":"2026-03-30T17:58:53.187545Z","end":"2026-03-30T17:58:53.896087Z","steps":["trace[1241568913] 'agreement among raft nodes before linearized reading' (duration: 708.075452ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:53.896366Z","caller":"traceutil/trace.go:171","msg":"trace[719796342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"199.402481ms","start":"2026-03-30T17:58:53.696941Z","end":"2026-03-30T17:58:53.896344Z","steps":["trace[719796342] 'agreement among raft nodes before linearized reading' (duration: 199.055625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:53.896413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:53.187526Z","time spent":"708.863446ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:58:53.896463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.483043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-30T17:58:53.896501Z","caller":"traceutil/trace.go:171","msg":"trace[1016447604] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1350; }","duration":"266.564575ms","start":"2026-03-30T17:58:53.629926Z","end":"2026-03-30T17:58:53.89649Z","steps":["trace[1016447604] 'agreement among raft nodes before linearized reading' (duration: 265.955234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:53.896703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.414531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-30T17:58:53.896784Z","caller":"traceutil/trace.go:171","msg":"trace[2087561899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"199.539093ms","start":"2026-03-30T17:58:53.697234Z","end":"2026-03-30T17:58:53.896773Z","steps":["trace[2087561899] 'agreement among raft nodes before linearized reading' (duration: 198.660667ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:01:59.237551Z","caller":"traceutil/trace.go:171","msg":"trace[339813063] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"159.298716ms","start":"2026-03-30T18:01:59.078221Z","end":"2026-03-30T18:01:59.23752Z","steps":["trace[339813063] 'process raft request' (duration: 159.104563ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:08.340416Z","caller":"traceutil/trace.go:171","msg":"trace[1058891696] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"433.178906ms","start":"2026-03-30T18:02:07.907206Z","end":"2026-03-30T18:02:08.340385Z","steps":["trace[1058891696] 'process raft request' (duration: 393.668323ms)","trace[1058891696] 'compare' (duration: 39.390521ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T18:02:08.340572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T18:02:07.90719Z","time spent":"433.3045ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}