{"level":"warn","ts":"2026-05-27T19:43:02.09104Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:43:02.091161Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.84: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.84:2380","--initial-cluster=instance=https://199.19.213.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.84: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-05-27T19:43:02.091249Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:43:02.091262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-05-27T19:43:02.091286Z","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-05-27T19:43:02.092201Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"]} {"level":"info","ts":"2026-05-27T19:43:02.092304Z","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.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.84: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-05-27T19:43:02.098817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.193855ms"} {"level":"info","ts":"2026-05-27T19:43:02.123429Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f823adf772f78a4c","cluster-id":"b5473e4c769d66"} {"level":"info","ts":"2026-05-27T19:43:02.123554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:43:02.123594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 0"} {"level":"info","ts":"2026-05-27T19:43:02.123607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f823adf772f78a4c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:43:02.123619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became follower at term 1"} {"level":"info","ts":"2026-05-27T19:43:02.123679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"warn","ts":"2026-05-27T19:43:02.136561Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:43:02.138523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:43:02.140649Z","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-05-27T19:43:02.14462Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f823adf772f78a4c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:43:02.144746Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f823adf772f78a4c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:43:02.144925Z","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-05-27T19:43:02.144979Z","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-05-27T19:43:02.144993Z","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-05-27T19:43:02.145637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c switched to configuration voters=(17880326223888353868)"} {"level":"info","ts":"2026-05-27T19:43:02.145817Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","added-peer-id":"f823adf772f78a4c","added-peer-peer-urls":["https://199.19.213.84:2380"]} {"level":"info","ts":"2026-05-27T19:43:02.149104Z","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-05-27T19:43:02.149219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-05-27T19:43:02.149263Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.84:2380"} {"level":"info","ts":"2026-05-27T19:43:02.149366Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f823adf772f78a4c","initial-advertise-peer-urls":["https://199.19.213.84:2380"],"listen-peer-urls":["https://199.19.213.84:2380"],"advertise-client-urls":["https://199.19.213.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:43:02.149407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:43:02.724281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:43:02.724348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:43:02.72437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgPreVoteResp from f823adf772f78a4c at term 1"} {"level":"info","ts":"2026-05-27T19:43:02.724386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:43:02.724394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c received MsgVoteResp from f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-05-27T19:43:02.724403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f823adf772f78a4c became leader at term 2"} {"level":"info","ts":"2026-05-27T19:43:02.724414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f823adf772f78a4c elected leader f823adf772f78a4c at term 2"} {"level":"info","ts":"2026-05-27T19:43:02.725401Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f823adf772f78a4c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.84:2379]}","request-path":"/0/members/f823adf772f78a4c/attributes","cluster-id":"b5473e4c769d66","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:43:02.725443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:43:02.725477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:43:02.72544Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:02.72593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:43:02.725962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:43:02.726669Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b5473e4c769d66","local-member-id":"f823adf772f78a4c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:02.726732Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:02.726753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:02.728061Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.84:2379"} {"level":"info","ts":"2026-05-27T19:43:02.734408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:43:04.444276Z","caller":"traceutil/trace.go:171","msg":"trace[1181193685] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"393.0272ms","start":"2026-05-27T19:43:04.051177Z","end":"2026-05-27T19:43:04.444205Z","steps":["trace[1181193685] 'read index received' (duration: 392.837076ms)","trace[1181193685] 'applied index is now lower than readState.Index' (duration: 188.994µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:04.444469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.262575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.444752Z","caller":"traceutil/trace.go:171","msg":"trace[1715117128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:2; }","duration":"393.543211ms","start":"2026-05-27T19:43:04.051143Z","end":"2026-05-27T19:43:04.444686Z","steps":["trace[1715117128] 'agreement among raft nodes before linearized reading' (duration: 393.139682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.444934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.051129Z","time spent":"393.770866ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:43:04.444577Z","caller":"traceutil/trace.go:171","msg":"trace[1779971294] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"397.14494ms","start":"2026-05-27T19:43:04.047106Z","end":"2026-05-27T19:43:04.44425Z","steps":["trace[1779971294] 'process raft request' (duration: 396.853343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.445845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.047094Z","time spent":"398.057459ms","remote":"127.0.0.1:52954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:04.779359Z","caller":"traceutil/trace.go:171","msg":"trace[501542420] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"722.40213ms","start":"2026-05-27T19:43:04.056897Z","end":"2026-05-27T19:43:04.779299Z","steps":["trace[501542420] 'process raft request' (duration: 722.276017ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:04.779432Z","caller":"traceutil/trace.go:171","msg":"trace[639931629] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"334.799589ms","start":"2026-05-27T19:43:04.444604Z","end":"2026-05-27T19:43:04.779404Z","steps":["trace[639931629] 'read index received' (duration: 334.435181ms)","trace[639931629] 'applied index is now lower than readState.Index' (duration: 363.748µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:04.779667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.056889Z","time spent":"722.562373ms","remote":"127.0.0.1:53156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:04.779696Z","caller":"traceutil/trace.go:171","msg":"trace[941211537] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"655.606331ms","start":"2026-05-27T19:43:04.12408Z","end":"2026-05-27T19:43:04.779686Z","steps":["trace[941211537] 'process raft request' (duration: 655.133161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.779794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124069Z","time spent":"655.653933ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:04.779971Z","caller":"traceutil/trace.go:171","msg":"trace[835572968] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"655.501509ms","start":"2026-05-27T19:43:04.124462Z","end":"2026-05-27T19:43:04.779963Z","steps":["trace[835572968] 'process raft request' (duration: 654.769964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.780011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124455Z","time spent":"655.53438ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:04.780036Z","caller":"traceutil/trace.go:171","msg":"trace[2128498257] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"726.816637ms","start":"2026-05-27T19:43:04.053203Z","end":"2026-05-27T19:43:04.78002Z","steps":["trace[2128498257] 'process raft request' (duration: 725.853376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.780157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.053192Z","time spent":"726.903898ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:04.780439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.186535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.78047Z","caller":"traceutil/trace.go:171","msg":"trace[1548876392] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"656.236025ms","start":"2026-05-27T19:43:04.124225Z","end":"2026-05-27T19:43:04.780461Z","steps":["trace[1548876392] 'agreement among raft nodes before linearized reading' (duration: 656.148713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.780492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124216Z","time spent":"656.270156ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:43:04.782049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.26262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.782228Z","caller":"traceutil/trace.go:171","msg":"trace[1979524184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"729.496875ms","start":"2026-05-27T19:43:04.05269Z","end":"2026-05-27T19:43:04.782187Z","steps":["trace[1979524184] 'agreement among raft nodes before linearized reading' (duration: 729.208069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.782299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.052685Z","time spent":"729.589967ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:43:04.782835Z","caller":"traceutil/trace.go:171","msg":"trace[1500401408] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"658.218759ms","start":"2026-05-27T19:43:04.124558Z","end":"2026-05-27T19:43:04.782777Z","steps":["trace[1500401408] 'process raft request' (duration: 654.723053ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:04.78291Z","caller":"traceutil/trace.go:171","msg":"trace[367233353] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"657.139955ms","start":"2026-05-27T19:43:04.125237Z","end":"2026-05-27T19:43:04.782377Z","steps":["trace[367233353] 'process raft request' (duration: 654.114889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.783136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.125226Z","time spent":"657.84295ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:04.783256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124534Z","time spent":"658.411143ms","remote":"127.0.0.1:53312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:04.783536Z","caller":"traceutil/trace.go:171","msg":"trace[842693351] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"658.490465ms","start":"2026-05-27T19:43:04.12501Z","end":"2026-05-27T19:43:04.783501Z","steps":["trace[842693351] 'process raft request' (duration: 654.315084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.783628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.599192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-27T19:43:04.783643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124998Z","time spent":"658.588217ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:04.783703Z","caller":"traceutil/trace.go:171","msg":"trace[2142374526] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"606.694593ms","start":"2026-05-27T19:43:04.17698Z","end":"2026-05-27T19:43:04.783675Z","steps":["trace[2142374526] 'agreement among raft nodes before linearized reading' (duration: 606.590241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.783747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.176962Z","time spent":"606.775176ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:43:04.783116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.683039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.783926Z","caller":"traceutil/trace.go:171","msg":"trace[1581398275] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"730.510296ms","start":"2026-05-27T19:43:04.053392Z","end":"2026-05-27T19:43:04.783903Z","steps":["trace[1581398275] 'agreement among raft nodes before linearized reading' (duration: 729.422113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.783981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.053388Z","time spent":"730.578999ms","remote":"127.0.0.1:53274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T19:43:04.784752Z","caller":"traceutil/trace.go:171","msg":"trace[90974569] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"649.634401ms","start":"2026-05-27T19:43:04.135074Z","end":"2026-05-27T19:43:04.784708Z","steps":["trace[90974569] 'process raft request' (duration: 644.298035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.784833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.135061Z","time spent":"649.731383ms","remote":"127.0.0.1:53076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:04.782197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.514326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.785034Z","caller":"traceutil/trace.go:171","msg":"trace[155127890] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"667.384119ms","start":"2026-05-27T19:43:04.117634Z","end":"2026-05-27T19:43:04.785018Z","steps":["trace[155127890] 'agreement among raft nodes before linearized reading' (duration: 664.474255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.785101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.117573Z","time spent":"667.509651ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:43:04.785825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.922111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.785872Z","caller":"traceutil/trace.go:171","msg":"trace[2075817164] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:0; response_revision:11; }","duration":"662.991153ms","start":"2026-05-27T19:43:04.12287Z","end":"2026-05-27T19:43:04.785861Z","steps":["trace[2075817164] 'agreement among raft nodes before linearized reading' (duration: 659.396505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.785914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.122855Z","time spent":"663.042664ms","remote":"127.0.0.1:52914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.84\" "} {"level":"info","ts":"2026-05-27T19:43:04.786283Z","caller":"traceutil/trace.go:171","msg":"trace[901102023] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"661.49612ms","start":"2026-05-27T19:43:04.124776Z","end":"2026-05-27T19:43:04.786272Z","steps":["trace[901102023] 'process raft request' (duration: 654.529718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.786323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.124741Z","time spent":"661.563742ms","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:04.786453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.005589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.786487Z","caller":"traceutil/trace.go:171","msg":"trace[382122611] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"661.092072ms","start":"2026-05-27T19:43:04.125385Z","end":"2026-05-27T19:43:04.786478Z","steps":["trace[382122611] 'agreement among raft nodes before linearized reading' (duration: 661.00537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:04.786539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.125374Z","time spent":"661.156723ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-27T19:43:04.789349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.937587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:04.789392Z","caller":"traceutil/trace.go:171","msg":"trace[898016475] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"289.015889ms","start":"2026-05-27T19:43:04.500356Z","end":"2026-05-27T19:43:04.789372Z","steps":["trace[898016475] 'agreement among raft nodes before linearized reading' (duration: 280.166516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.292096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698777990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:43:05.792574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698777990,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:43:05.842229Z","caller":"traceutil/trace.go:171","msg":"trace[485631196] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.051254269s","start":"2026-05-27T19:43:04.790957Z","end":"2026-05-27T19:43:05.842211Z","steps":["trace[485631196] 'process raft request' (duration: 1.041150038s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.842318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.790948Z","time spent":"1.05132684s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:05.842582Z","caller":"traceutil/trace.go:171","msg":"trace[1495025186] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.051456593s","start":"2026-05-27T19:43:04.791114Z","end":"2026-05-27T19:43:05.842571Z","steps":["trace[1495025186] 'process raft request' (duration: 1.05135059s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.842645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.7911Z","time spent":"1.051517065s","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:05.842743Z","caller":"traceutil/trace.go:171","msg":"trace[1399718355] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:14; }","duration":"1.051529124s","start":"2026-05-27T19:43:04.791201Z","end":"2026-05-27T19:43:05.84273Z","steps":["trace[1399718355] 'read index received' (duration: 1.040916433s)","trace[1399718355] 'applied index is now lower than readState.Index' (duration: 10.612051ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:05.842822Z","caller":"traceutil/trace.go:171","msg":"trace[136204325] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.051621307s","start":"2026-05-27T19:43:04.791192Z","end":"2026-05-27T19:43:05.842814Z","steps":["trace[136204325] 'process raft request' (duration: 1.051356901s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.842882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791175Z","time spent":"1.051673448s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:05.843036Z","caller":"traceutil/trace.go:171","msg":"trace[824803187] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.048228213s","start":"2026-05-27T19:43:04.794798Z","end":"2026-05-27T19:43:05.843027Z","steps":["trace[824803187] 'process raft request' (duration: 1.047890875s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.794791Z","time spent":"1.048274824s","remote":"127.0.0.1:53076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:05.843115Z","caller":"traceutil/trace.go:171","msg":"trace[2143098169] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.051863471s","start":"2026-05-27T19:43:04.791242Z","end":"2026-05-27T19:43:05.843105Z","steps":["trace[2143098169] 'process raft request' (duration: 1.05133659s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:05.843058Z","caller":"traceutil/trace.go:171","msg":"trace[303959779] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.051890873s","start":"2026-05-27T19:43:04.791146Z","end":"2026-05-27T19:43:05.843036Z","steps":["trace[303959779] 'process raft request' (duration: 1.051374442s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791236Z","time spent":"1.051902723s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:05.843258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052049126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-05-27T19:43:05.843278Z","caller":"traceutil/trace.go:171","msg":"trace[1319337879] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.052017335s","start":"2026-05-27T19:43:04.791252Z","end":"2026-05-27T19:43:05.843269Z","steps":["trace[1319337879] 'process raft request' (duration: 1.05134657s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:05.8433Z","caller":"traceutil/trace.go:171","msg":"trace[231768659] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"1.052099437s","start":"2026-05-27T19:43:04.791189Z","end":"2026-05-27T19:43:05.843289Z","steps":["trace[231768659] 'agreement among raft nodes before linearized reading' (duration: 1.052031556s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791121Z","time spent":"1.052045486s","remote":"127.0.0.1:53312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:05.84332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791247Z","time spent":"1.052051796s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:05.843375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0023261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-27T19:43:05.843408Z","caller":"traceutil/trace.go:171","msg":"trace[1999479337] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"1.002389382s","start":"2026-05-27T19:43:04.841011Z","end":"2026-05-27T19:43:05.843401Z","steps":["trace[1999479337] 'agreement among raft nodes before linearized reading' (duration: 1.002339801s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:05.84343Z","caller":"traceutil/trace.go:171","msg":"trace[961664405] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"1.052038505s","start":"2026-05-27T19:43:04.791384Z","end":"2026-05-27T19:43:05.843423Z","steps":["trace[961664405] 'process raft request' (duration: 1.051240618s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791379Z","time spent":"1.052080796s","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:05.843541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.910141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-27T19:43:05.843577Z","caller":"traceutil/trace.go:171","msg":"trace[1677110851] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"334.986823ms","start":"2026-05-27T19:43:05.508582Z","end":"2026-05-27T19:43:05.843569Z","steps":["trace[1677110851] 'agreement among raft nodes before linearized reading' (duration: 334.934761ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:05.843595Z","caller":"traceutil/trace.go:171","msg":"trace[1744127715] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.050823479s","start":"2026-05-27T19:43:04.792762Z","end":"2026-05-27T19:43:05.843586Z","steps":["trace[1744127715] 'process raft request' (duration: 1.049880798s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:05.508557Z","time spent":"335.039403ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:43:05.843643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.792757Z","time spent":"1.05086748s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:05.843736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.182218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:05.843772Z","caller":"traceutil/trace.go:171","msg":"trace[911748249] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"631.26695ms","start":"2026-05-27T19:43:05.212496Z","end":"2026-05-27T19:43:05.843763Z","steps":["trace[911748249] 'agreement among raft nodes before linearized reading' (duration: 631.214469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.843802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:05.212477Z","time spent":"631.314191ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-05-27T19:43:05.843434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.84099Z","time spent":"1.002436192s","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T19:43:05.843976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.605333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:05.844014Z","caller":"traceutil/trace.go:171","msg":"trace[764355292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"749.671615ms","start":"2026-05-27T19:43:05.094334Z","end":"2026-05-27T19:43:05.844005Z","steps":["trace[764355292] 'agreement among raft nodes before linearized reading' (duration: 749.620384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.84404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:05.094312Z","time spent":"749.721276ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:43:05.844159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.792057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:05.844197Z","caller":"traceutil/trace.go:171","msg":"trace[6248657] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19; }","duration":"820.855489ms","start":"2026-05-27T19:43:05.023333Z","end":"2026-05-27T19:43:05.844189Z","steps":["trace[6248657] 'agreement among raft nodes before linearized reading' (duration: 820.794188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.844226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:05.023324Z","time spent":"820.89394ms","remote":"127.0.0.1:53244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-27T19:43:05.843328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.791184Z","time spent":"1.052136898s","remote":"127.0.0.1:53076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3512,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-27T19:43:05.84436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.374911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:05.844395Z","caller":"traceutil/trace.go:171","msg":"trace[488345717] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:19; }","duration":"821.449421ms","start":"2026-05-27T19:43:05.022938Z","end":"2026-05-27T19:43:05.844387Z","steps":["trace[488345717] 'agreement among raft nodes before linearized reading' (duration: 821.40034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.844423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:05.022918Z","time spent":"821.496373ms","remote":"127.0.0.1:53254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-27T19:43:05.844505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.019879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T19:43:05.844543Z","caller":"traceutil/trace.go:171","msg":"trace[412474794] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"852.093611ms","start":"2026-05-27T19:43:04.992439Z","end":"2026-05-27T19:43:05.844533Z","steps":["trace[412474794] 'agreement among raft nodes before linearized reading' (duration: 852.041819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.844577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.992411Z","time spent":"852.158252ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-05-27T19:43:05.844647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.159786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-27T19:43:05.844729Z","caller":"traceutil/trace.go:171","msg":"trace[843532017] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"996.268379ms","start":"2026-05-27T19:43:04.848447Z","end":"2026-05-27T19:43:05.844715Z","steps":["trace[843532017] 'agreement among raft nodes before linearized reading' (duration: 996.127595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:05.84499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:04.848435Z","time spent":"996.535884ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-27T19:43:40.211791Z","caller":"traceutil/trace.go:171","msg":"trace[1808742800] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"144.626047ms","start":"2026-05-27T19:43:40.067125Z","end":"2026-05-27T19:43:40.211751Z","steps":["trace[1808742800] 'process raft request' (duration: 144.429043ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:40.35476Z","caller":"traceutil/trace.go:171","msg":"trace[83703161] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"130.719663ms","start":"2026-05-27T19:43:40.224012Z","end":"2026-05-27T19:43:40.354732Z","steps":["trace[83703161] 'read index received' (duration: 52.6955ms)","trace[83703161] 'applied index is now lower than readState.Index' (duration: 78.022813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:40.354916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.881268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:40.354961Z","caller":"traceutil/trace.go:171","msg":"trace[1447363162] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:527; }","duration":"130.940129ms","start":"2026-05-27T19:43:40.224007Z","end":"2026-05-27T19:43:40.354947Z","steps":["trace[1447363162] 'agreement among raft nodes before linearized reading' (duration: 130.826496ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:40.355112Z","caller":"traceutil/trace.go:171","msg":"trace[728950363] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"139.029505ms","start":"2026-05-27T19:43:40.216062Z","end":"2026-05-27T19:43:40.355092Z","steps":["trace[728950363] 'process raft request' (duration: 60.731816ms)","trace[728950363] 'compare' (duration: 77.829379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:41.096208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698779016,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:43:41.155193Z","caller":"traceutil/trace.go:171","msg":"trace[2013954614] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"610.997057ms","start":"2026-05-27T19:43:40.544169Z","end":"2026-05-27T19:43:41.155166Z","steps":["trace[2013954614] 'process raft request' (duration: 610.865694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:41.155477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.544151Z","time spent":"611.123411ms","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.641872Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.485887327s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:43:42.642838Z","caller":"traceutil/trace.go:171","msg":"trace[363786710] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.090856033s","start":"2026-05-27T19:43:40.551938Z","end":"2026-05-27T19:43:42.642794Z","steps":["trace[363786710] 'process raft request' (duration: 2.090460914s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.643039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.551919Z","time spent":"2.091041157s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:42.642983Z","caller":"traceutil/trace.go:171","msg":"trace[464502275] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"2.090946565s","start":"2026-05-27T19:43:40.552001Z","end":"2026-05-27T19:43:42.642947Z","steps":["trace[464502275] 'process raft request' (duration: 2.090668179s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.643307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.551995Z","time spent":"2.09115026s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:42.643694Z","caller":"traceutil/trace.go:171","msg":"trace[1019269192] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.075307223s","start":"2026-05-27T19:43:40.568372Z","end":"2026-05-27T19:43:42.643679Z","steps":["trace[1019269192] 'process raft request' (duration: 2.075086118s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.643779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.568356Z","time spent":"2.075373084s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:42.64382Z","caller":"traceutil/trace.go:171","msg":"trace[615401157] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.075303134s","start":"2026-05-27T19:43:40.568481Z","end":"2026-05-27T19:43:42.643784Z","steps":["trace[615401157] 'process raft request' (duration: 2.075175081s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.643917Z","caller":"traceutil/trace.go:171","msg":"trace[683893757] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.059552059s","start":"2026-05-27T19:43:40.584348Z","end":"2026-05-27T19:43:42.6439Z","steps":["trace[683893757] 'process raft request' (duration: 2.059387896s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.643986Z","caller":"traceutil/trace.go:171","msg":"trace[985617235] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:545; }","duration":"2.048007767s","start":"2026-05-27T19:43:40.595955Z","end":"2026-05-27T19:43:42.643963Z","steps":["trace[985617235] 'read index received' (duration: 559.163916ms)","trace[985617235] 'applied index is now lower than readState.Index' (duration: 1.488843281s)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:42.64404Z","caller":"traceutil/trace.go:171","msg":"trace[1033081816] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.07560692s","start":"2026-05-27T19:43:40.568423Z","end":"2026-05-27T19:43:42.64403Z","steps":["trace[1033081816] 'process raft request' (duration: 2.07516008s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.644072Z","caller":"traceutil/trace.go:171","msg":"trace[1693367198] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"2.054340426s","start":"2026-05-27T19:43:40.589724Z","end":"2026-05-27T19:43:42.644065Z","steps":["trace[1693367198] 'process raft request' (duration: 2.054045519s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.644052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.584321Z","time spent":"2.059670442s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.644091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.56842Z","time spent":"2.075650941s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.644134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.589708Z","time spent":"2.054381486s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:42.644215Z","caller":"traceutil/trace.go:171","msg":"trace[1518492362] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.926548916s","start":"2026-05-27T19:43:40.717659Z","end":"2026-05-27T19:43:42.644207Z","steps":["trace[1518492362] 'process raft request' (duration: 1.9262674s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.644245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.717629Z","time spent":"1.926602587s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:42.644343Z","caller":"traceutil/trace.go:171","msg":"trace[531926526] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"2.052358912s","start":"2026-05-27T19:43:40.591963Z","end":"2026-05-27T19:43:42.644322Z","steps":["trace[531926526] 'process raft request' (duration: 2.051847671s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.644433Z","caller":"traceutil/trace.go:171","msg":"trace[1218493361] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"2.029453103s","start":"2026-05-27T19:43:40.614973Z","end":"2026-05-27T19:43:42.644426Z","steps":["trace[1218493361] 'process raft request' (duration: 2.02889837s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.644435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.048469207s","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-05-27T19:43:42.644093Z","caller":"traceutil/trace.go:171","msg":"trace[1544147646] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.072045662s","start":"2026-05-27T19:43:40.572037Z","end":"2026-05-27T19:43:42.644083Z","steps":["trace[1544147646] 'process raft request' (duration: 2.071655574s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.644482Z","caller":"traceutil/trace.go:171","msg":"trace[1167021228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"2.048544129s","start":"2026-05-27T19:43:40.595927Z","end":"2026-05-27T19:43:42.644471Z","steps":["trace[1167021228] 'agreement among raft nodes before linearized reading' (duration: 2.048354174s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.64452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.595913Z","time spent":"2.04860006s","remote":"127.0.0.1:53158","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-05-27T19:43:42.644522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.591938Z","time spent":"2.052492485s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.643996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.568477Z","time spent":"2.075452117s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.644456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.614948Z","time spent":"2.029497733s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:42.645549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:40.57203Z","time spent":"2.073489393s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:43.14497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698779033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:43:43.609162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999999448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-27T19:43:43.609277Z","caller":"traceutil/trace.go:171","msg":"trace[109970602] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000168912s","start":"2026-05-27T19:43:41.609088Z","end":"2026-05-27T19:43:43.609257Z","steps":["trace[109970602] 'agreement among raft nodes before linearized reading' (duration: 2.000030109s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:43.609326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:41.609068Z","time spent":"2.000247224s","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/27 19:43:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T19:43:43.645887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698779033,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:43:44.094276Z","caller":"traceutil/trace.go:171","msg":"trace[1000663436] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"2.93368761s","start":"2026-05-27T19:43:41.160547Z","end":"2026-05-27T19:43:44.094235Z","steps":["trace[1000663436] 'process raft request' (duration: 2.933432275s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.094403Z","caller":"traceutil/trace.go:171","msg":"trace[1304664960] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"2.932849342s","start":"2026-05-27T19:43:41.161513Z","end":"2026-05-27T19:43:44.094362Z","steps":["trace[1304664960] 'process raft request' (duration: 2.932614527s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.094452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:41.160532Z","time spent":"2.933850335s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:44.094484Z","caller":"traceutil/trace.go:171","msg":"trace[1402075078] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"2.931944232s","start":"2026-05-27T19:43:41.16251Z","end":"2026-05-27T19:43:44.094455Z","steps":["trace[1402075078] 'process raft request' (duration: 2.931668046s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.094573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:41.161504Z","time spent":"2.932977235s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.094662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:41.162502Z","time spent":"2.932057645s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.35068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.342346ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-27T19:43:44.351582Z","caller":"traceutil/trace.go:171","msg":"trace[1381931078] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.698550148s","start":"2026-05-27T19:43:42.652963Z","end":"2026-05-27T19:43:44.351513Z","steps":["trace[1381931078] 'process raft request' (duration: 1.698158269s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.351683Z","caller":"traceutil/trace.go:171","msg":"trace[1702147440] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.686537136s","start":"2026-05-27T19:43:42.665117Z","end":"2026-05-27T19:43:44.351654Z","steps":["trace[1702147440] 'process raft request' (duration: 1.686234949s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.35177Z","caller":"traceutil/trace.go:171","msg":"trace[196728034] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.700618913s","start":"2026-05-27T19:43:42.651072Z","end":"2026-05-27T19:43:44.351691Z","steps":["trace[196728034] 'process raft request' (duration: 1.699707243s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.351773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.652953Z","time spent":"1.698701741s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:44.351932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.665107Z","time spent":"1.686693869s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.352076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.305391785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:43:44.352142Z","caller":"traceutil/trace.go:171","msg":"trace[896664788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"3.305494207s","start":"2026-05-27T19:43:41.046636Z","end":"2026-05-27T19:43:44.35213Z","steps":["trace[896664788] 'agreement among raft nodes before linearized reading' (duration: 3.305240392s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.352174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:41.046616Z","time spent":"3.305548998s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:43:44.35209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.651056Z","time spent":"1.700838428s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:44.352332Z","caller":"traceutil/trace.go:171","msg":"trace[1197565175] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.690512603s","start":"2026-05-27T19:43:42.661678Z","end":"2026-05-27T19:43:44.352191Z","steps":["trace[1197565175] 'process raft request' (duration: 1.68946595s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.352385Z","caller":"traceutil/trace.go:171","msg":"trace[1896761298] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.699917797s","start":"2026-05-27T19:43:42.652449Z","end":"2026-05-27T19:43:44.352367Z","steps":["trace[1896761298] 'process raft request' (duration: 1.698633369s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.352488Z","caller":"traceutil/trace.go:171","msg":"trace[1367250455] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.684849218s","start":"2026-05-27T19:43:42.667605Z","end":"2026-05-27T19:43:44.352454Z","steps":["trace[1367250455] 'process raft request' (duration: 1.683878047s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.352421Z","caller":"traceutil/trace.go:171","msg":"trace[1377565239] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.690455891s","start":"2026-05-27T19:43:42.661954Z","end":"2026-05-27T19:43:44.352409Z","steps":["trace[1377565239] 'process raft request' (duration: 1.689259525s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.352505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.652441Z","time spent":"1.70000693s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:44.352584Z","caller":"traceutil/trace.go:171","msg":"trace[595656607] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.690769728s","start":"2026-05-27T19:43:42.661807Z","end":"2026-05-27T19:43:44.352577Z","steps":["trace[595656607] 'process raft request' (duration: 1.689360177s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.352621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.661785Z","time spent":"1.690816949s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:44.352557Z","caller":"traceutil/trace.go:171","msg":"trace[632270481] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.648158917s","start":"2026-05-27T19:43:42.704391Z","end":"2026-05-27T19:43:44.352549Z","steps":["trace[632270481] 'process raft request' (duration: 1.647260738s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.352369Z","caller":"traceutil/trace.go:171","msg":"trace[666470053] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.68625431s","start":"2026-05-27T19:43:42.666081Z","end":"2026-05-27T19:43:44.352335Z","steps":["trace[666470053] 'process raft request' (duration: 1.68533309s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.35275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993732252s","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-05-27T19:43:44.352779Z","caller":"traceutil/trace.go:171","msg":"trace[1889724949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.993797833s","start":"2026-05-27T19:43:42.358974Z","end":"2026-05-27T19:43:44.352772Z","steps":["trace[1889724949] 'agreement among raft nodes before linearized reading' (duration: 1.993709721s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.352803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.358955Z","time spent":"1.993843404s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:43:44.352795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.666072Z","time spent":"1.686666279s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.352699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.704369Z","time spent":"1.648303201s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:44.352788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135774972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-27T19:43:44.35291Z","caller":"traceutil/trace.go:171","msg":"trace[1353984120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"2.135931176s","start":"2026-05-27T19:43:42.216969Z","end":"2026-05-27T19:43:44.3529Z","steps":["trace[1353984120] 'agreement among raft nodes before linearized reading' (duration: 2.135750462s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.353126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.216949Z","time spent":"2.136167401s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:43:44.351835Z","caller":"traceutil/trace.go:171","msg":"trace[1516254639] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:557; }","duration":"1.707765279s","start":"2026-05-27T19:43:42.644042Z","end":"2026-05-27T19:43:44.351807Z","steps":["trace[1516254639] 'read index received' (duration: 1.450031513s)","trace[1516254639] 'applied index is now lower than readState.Index' (duration: 257.732386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:44.353513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.661658Z","time spent":"1.690831119s","remote":"127.0.0.1:53394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:43:44.353712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.667596Z","time spent":"1.684931521s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.353839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.661949Z","time spent":"1.690592754s","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:43:44.354388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.920441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:44.35443Z","caller":"traceutil/trace.go:171","msg":"trace[395040200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"744.991273ms","start":"2026-05-27T19:43:43.609428Z","end":"2026-05-27T19:43:44.354419Z","steps":["trace[395040200] 'agreement among raft nodes before linearized reading' (duration: 744.915262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.354455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:43.609412Z","time spent":"745.036894ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:43:44.355219Z","caller":"traceutil/trace.go:171","msg":"trace[362602664] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"250.771374ms","start":"2026-05-27T19:43:44.104437Z","end":"2026-05-27T19:43:44.355209Z","steps":["trace[362602664] 'process raft request' (duration: 249.728692ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:44.354428Z","caller":"traceutil/trace.go:171","msg":"trace[1617924357] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"247.536624ms","start":"2026-05-27T19:43:44.106873Z","end":"2026-05-27T19:43:44.354409Z","steps":["trace[1617924357] 'process raft request' (duration: 247.424592ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:45.932853Z","caller":"traceutil/trace.go:171","msg":"trace[425608142] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"888.300191ms","start":"2026-05-27T19:43:45.044516Z","end":"2026-05-27T19:43:45.932816Z","steps":["trace[425608142] 'process raft request' (duration: 887.162347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:45.933037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:45.044478Z","time spent":"888.468925ms","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:45.941203Z","caller":"traceutil/trace.go:171","msg":"trace[1111740935] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"332.56143ms","start":"2026-05-27T19:43:45.60862Z","end":"2026-05-27T19:43:45.941181Z","steps":["trace[1111740935] 'read index received' (duration: 323.123904ms)","trace[1111740935] 'applied index is now lower than readState.Index' (duration: 9.436236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:45.941317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.695593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:45.941354Z","caller":"traceutil/trace.go:171","msg":"trace[1898445068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"332.774275ms","start":"2026-05-27T19:43:45.60857Z","end":"2026-05-27T19:43:45.941345Z","steps":["trace[1898445068] 'agreement among raft nodes before linearized reading' (duration: 332.688793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:45.941407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:45.608551Z","time spent":"332.849276ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:43:45.941388Z","caller":"traceutil/trace.go:171","msg":"trace[1571937305] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"835.232063ms","start":"2026-05-27T19:43:45.106125Z","end":"2026-05-27T19:43:45.941357Z","steps":["trace[1571937305] 'process raft request' (duration: 833.393233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:45.941511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:45.106108Z","time spent":"835.343776ms","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:43:45.941597Z","caller":"traceutil/trace.go:171","msg":"trace[1722029480] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"742.369166ms","start":"2026-05-27T19:43:45.199217Z","end":"2026-05-27T19:43:45.941586Z","steps":["trace[1722029480] 'process raft request' (duration: 741.19129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:45.941679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:45.199192Z","time spent":"742.439058ms","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:52.602548Z","caller":"traceutil/trace.go:171","msg":"trace[891839335] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"175.501534ms","start":"2026-05-27T19:43:52.427024Z","end":"2026-05-27T19:43:52.602526Z","steps":["trace[891839335] 'read index received' (duration: 175.378521ms)","trace[891839335] 'applied index is now lower than readState.Index' (duration: 121.853µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:52.602785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.735569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:43:52.602851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.218763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T19:43:52.60286Z","caller":"traceutil/trace.go:171","msg":"trace[820752322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"175.845601ms","start":"2026-05-27T19:43:52.426994Z","end":"2026-05-27T19:43:52.60284Z","steps":["trace[820752322] 'agreement among raft nodes before linearized reading' (duration: 175.625526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:52.602936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.217763ms","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-05-27T19:43:52.602913Z","caller":"traceutil/trace.go:171","msg":"trace[1485149388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"174.293175ms","start":"2026-05-27T19:43:52.428601Z","end":"2026-05-27T19:43:52.602894Z","steps":["trace[1485149388] 'agreement among raft nodes before linearized reading' (duration: 174.122891ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:52.602979Z","caller":"traceutil/trace.go:171","msg":"trace[1673540724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"174.319416ms","start":"2026-05-27T19:43:52.428647Z","end":"2026-05-27T19:43:52.602967Z","steps":["trace[1673540724] 'agreement among raft nodes before linearized reading' (duration: 174.124761ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:52.602662Z","caller":"traceutil/trace.go:171","msg":"trace[2021395983] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"215.041742ms","start":"2026-05-27T19:43:52.387551Z","end":"2026-05-27T19:43:52.602592Z","steps":["trace[2021395983] 'process raft request' (duration: 214.811397ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:52.832918Z","caller":"traceutil/trace.go:171","msg":"trace[1284578508] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"225.346011ms","start":"2026-05-27T19:43:52.607544Z","end":"2026-05-27T19:43:52.83289Z","steps":["trace[1284578508] 'process raft request' (duration: 178.411764ms)","trace[1284578508] 'compare' (duration: 46.797973ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:52.9255Z","caller":"traceutil/trace.go:171","msg":"trace[2012378425] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"317.914583ms","start":"2026-05-27T19:43:52.607553Z","end":"2026-05-27T19:43:52.925467Z","steps":["trace[2012378425] 'process raft request' (duration: 317.699708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:52.925684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:52.607528Z","time spent":"318.066106ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:52.925892Z","caller":"traceutil/trace.go:171","msg":"trace[358770574] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"316.832047ms","start":"2026-05-27T19:43:52.609034Z","end":"2026-05-27T19:43:52.925866Z","steps":["trace[358770574] 'read index received' (duration: 176.939908ms)","trace[358770574] 'applied index is now lower than readState.Index' (duration: 139.891249ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:52.925901Z","caller":"traceutil/trace.go:171","msg":"trace[978668648] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"317.029572ms","start":"2026-05-27T19:43:52.608831Z","end":"2026-05-27T19:43:52.92586Z","steps":["trace[978668648] 'process raft request' (duration: 316.580251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:52.926039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.99069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:52.926078Z","caller":"traceutil/trace.go:171","msg":"trace[147796418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"317.071713ms","start":"2026-05-27T19:43:52.608997Z","end":"2026-05-27T19:43:52.926069Z","steps":["trace[147796418] 'agreement among raft nodes before linearized reading' (duration: 316.96857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:52.926109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:52.608984Z","time spent":"317.118604ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:43:52.926226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:52.608817Z","time spent":"317.180575ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:52.926263Z","caller":"traceutil/trace.go:171","msg":"trace[2068882304] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"309.90026ms","start":"2026-05-27T19:43:52.61634Z","end":"2026-05-27T19:43:52.92624Z","steps":["trace[2068882304] 'process raft request' (duration: 309.402888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:52.92636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:52.616313Z","time spent":"310.006093ms","remote":"127.0.0.1:53078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:00.034424Z","caller":"traceutil/trace.go:171","msg":"trace[1104275471] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"113.902654ms","start":"2026-05-27T19:43:59.920499Z","end":"2026-05-27T19:44:00.034402Z","steps":["trace[1104275471] 'process raft request' (duration: 113.787812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:00.316027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.487072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:00.316434Z","caller":"traceutil/trace.go:171","msg":"trace[137693430] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"218.625059ms","start":"2026-05-27T19:44:00.097749Z","end":"2026-05-27T19:44:00.316374Z","steps":["trace[137693430] 'process raft request' (duration: 77.659515ms)","trace[137693430] 'compare' (duration: 140.319408ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:07.711161Z","caller":"traceutil/trace.go:171","msg":"trace[2102261963] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"211.758584ms","start":"2026-05-27T19:44:07.499378Z","end":"2026-05-27T19:44:07.711136Z","steps":["trace[2102261963] 'read index received' (duration: 211.559229ms)","trace[2102261963] 'applied index is now lower than readState.Index' (duration: 198.465µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:07.711353Z","caller":"traceutil/trace.go:171","msg":"trace[439344239] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"277.475431ms","start":"2026-05-27T19:44:07.433846Z","end":"2026-05-27T19:44:07.711321Z","steps":["trace[439344239] 'process raft request' (duration: 277.143413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:07.711334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.297901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:07.711497Z","caller":"traceutil/trace.go:171","msg":"trace[4483870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"101.483876ms","start":"2026-05-27T19:44:07.609968Z","end":"2026-05-27T19:44:07.711452Z","steps":["trace[4483870] 'agreement among raft nodes before linearized reading' (duration: 101.310841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:07.711494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.103042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:44:07.711609Z","caller":"traceutil/trace.go:171","msg":"trace[2006133924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"212.286897ms","start":"2026-05-27T19:44:07.499304Z","end":"2026-05-27T19:44:07.711591Z","steps":["trace[2006133924] 'agreement among raft nodes before linearized reading' (duration: 211.971799ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:10.94506Z","caller":"traceutil/trace.go:171","msg":"trace[320443425] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"287.083223ms","start":"2026-05-27T19:44:10.657942Z","end":"2026-05-27T19:44:10.945025Z","steps":["trace[320443425] 'process raft request' (duration: 286.784126ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:40.536793Z","caller":"traceutil/trace.go:171","msg":"trace[361380916] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"523.800955ms","start":"2026-05-27T19:44:40.012962Z","end":"2026-05-27T19:44:40.536763Z","steps":["trace[361380916] 'process raft request' (duration: 523.61167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:40.536977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:40.012932Z","time spent":"523.951199ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:40.600258Z","caller":"traceutil/trace.go:171","msg":"trace[927524289] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"455.083125ms","start":"2026-05-27T19:44:40.145153Z","end":"2026-05-27T19:44:40.600237Z","steps":["trace[927524289] 'process raft request' (duration: 454.525582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:40.600385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:40.145133Z","time spent":"455.184267ms","remote":"127.0.0.1:53076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:43.482202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.723447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:43.48231Z","caller":"traceutil/trace.go:171","msg":"trace[465071698] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"243.754122ms","start":"2026-05-27T19:44:43.238537Z","end":"2026-05-27T19:44:43.482291Z","steps":["trace[465071698] 'process raft request' (duration: 30.84413ms)","trace[465071698] 'compare' (duration: 212.510232ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:45.809409Z","caller":"traceutil/trace.go:171","msg":"trace[1904307863] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"199.655124ms","start":"2026-05-27T19:44:45.609729Z","end":"2026-05-27T19:44:45.809384Z","steps":["trace[1904307863] 'read index received' (duration: 199.433439ms)","trace[1904307863] 'applied index is now lower than readState.Index' (duration: 220.765µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:45.809531Z","caller":"traceutil/trace.go:171","msg":"trace[1590616433] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"317.614486ms","start":"2026-05-27T19:44:45.491884Z","end":"2026-05-27T19:44:45.809499Z","steps":["trace[1590616433] 'process raft request' (duration: 317.35731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:45.809716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:45.491868Z","time spent":"317.770359ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:45.809559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.807477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:45.809867Z","caller":"traceutil/trace.go:171","msg":"trace[801251373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"200.109595ms","start":"2026-05-27T19:44:45.609687Z","end":"2026-05-27T19:44:45.809797Z","steps":["trace[801251373] 'agreement among raft nodes before linearized reading' (duration: 199.790337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.203405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.341412ms","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-05-27T19:44:47.203504Z","caller":"traceutil/trace.go:171","msg":"trace[285286004] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1028; }","duration":"929.507547ms","start":"2026-05-27T19:44:46.273979Z","end":"2026-05-27T19:44:47.203486Z","steps":["trace[285286004] 'count revisions from in-memory index tree' (duration: 929.25248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.203547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:46.27396Z","time spent":"929.576198ms","remote":"127.0.0.1:52940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:44:47.203671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.833324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T19:44:47.203741Z","caller":"traceutil/trace.go:171","msg":"trace[759496295] range","detail":"{range_begin:/registry/masterleases/199.19.213.84; range_end:; response_count:1; response_revision:1028; }","duration":"500.939806ms","start":"2026-05-27T19:44:46.702783Z","end":"2026-05-27T19:44:47.203723Z","steps":["trace[759496295] 'range keys from in-memory index tree' (duration: 500.681241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.203777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:46.702764Z","time spent":"501.004318ms","remote":"127.0.0.1:52914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.84\" "} {"level":"warn","ts":"2026-05-27T19:44:47.203798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250472273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:44:47.203836Z","caller":"traceutil/trace.go:171","msg":"trace[1116157396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"1.250548054s","start":"2026-05-27T19:44:45.953278Z","end":"2026-05-27T19:44:47.203826Z","steps":["trace[1116157396] 'range keys from in-memory index tree' (duration: 1.250327319s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.203893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:45.953259Z","time spent":"1.250625376s","remote":"127.0.0.1:53158","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-05-27T19:44:47.204107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.946632ms","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":"info","ts":"2026-05-27T19:44:47.204143Z","caller":"traceutil/trace.go:171","msg":"trace[1819163412] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1028; }","duration":"183.013444ms","start":"2026-05-27T19:44:47.021122Z","end":"2026-05-27T19:44:47.204136Z","steps":["trace[1819163412] 'count revisions from in-memory index tree' (duration: 182.896261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.714352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780081,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:44:47.869682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.205481Z","time spent":"664.196995ms","remote":"127.0.0.1:52914","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T19:44:48.269437Z","caller":"traceutil/trace.go:171","msg":"trace[2029261679] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1068; }","duration":"1.055853219s","start":"2026-05-27T19:44:47.213566Z","end":"2026-05-27T19:44:48.269419Z","steps":["trace[2029261679] 'read index received' (duration: 655.953187ms)","trace[2029261679] 'applied index is now lower than readState.Index' (duration: 399.898991ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:48.269508Z","caller":"traceutil/trace.go:171","msg":"trace[2010719900] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.060933721s","start":"2026-05-27T19:44:47.208528Z","end":"2026-05-27T19:44:48.269462Z","steps":["trace[2010719900] 'process raft request' (duration: 1.056073834s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:48.269569Z","caller":"traceutil/trace.go:171","msg":"trace[193672283] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"1.058604925s","start":"2026-05-27T19:44:47.210957Z","end":"2026-05-27T19:44:48.269562Z","steps":["trace[193672283] 'process raft request' (duration: 1.05841806s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.26963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.208505Z","time spent":"1.061064464s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:48.269658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.210948Z","time spent":"1.058645066s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:48.269844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056275989s","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-05-27T19:44:48.269881Z","caller":"traceutil/trace.go:171","msg":"trace[397385591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"1.05635028s","start":"2026-05-27T19:44:47.213523Z","end":"2026-05-27T19:44:48.269873Z","steps":["trace[397385591] 'agreement among raft nodes before linearized reading' (duration: 1.056227297s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.269903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.213504Z","time spent":"1.056393372s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:44:48.269543Z","caller":"traceutil/trace.go:171","msg":"trace[13791578] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.06009852s","start":"2026-05-27T19:44:47.209435Z","end":"2026-05-27T19:44:48.269534Z","steps":["trace[13791578] 'process raft request' (duration: 1.059862995s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.270023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.209423Z","time spent":"1.060572102s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:48.270954Z","caller":"traceutil/trace.go:171","msg":"trace[621832688] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"387.792331ms","start":"2026-05-27T19:44:47.883132Z","end":"2026-05-27T19:44:48.270924Z","steps":["trace[621832688] 'process raft request' (duration: 387.714189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.270998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.363962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:44:48.271019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.883108Z","time spent":"387.877002ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:48.27104Z","caller":"traceutil/trace.go:171","msg":"trace[1482660940] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1033; }","duration":"370.432773ms","start":"2026-05-27T19:44:47.900599Z","end":"2026-05-27T19:44:48.271032Z","steps":["trace[1482660940] 'agreement among raft nodes before linearized reading' (duration: 370.379392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.271066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.900586Z","time spent":"370.473025ms","remote":"127.0.0.1:53274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:44:48.271169Z","caller":"traceutil/trace.go:171","msg":"trace[1782324633] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"400.106256ms","start":"2026-05-27T19:44:47.871049Z","end":"2026-05-27T19:44:48.271155Z","steps":["trace[1782324633] 'process raft request' (duration: 399.743498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.271238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.657468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:44:48.271242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.871024Z","time spent":"400.174887ms","remote":"127.0.0.1:52914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:48.271272Z","caller":"traceutil/trace.go:171","msg":"trace[841260584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"457.72852ms","start":"2026-05-27T19:44:47.813537Z","end":"2026-05-27T19:44:48.271265Z","steps":["trace[841260584] 'agreement among raft nodes before linearized reading' (duration: 457.649938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.271295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.813517Z","time spent":"457.771841ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:44:48.2714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.495372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:48.271435Z","caller":"traceutil/trace.go:171","msg":"trace[1486859427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"661.558584ms","start":"2026-05-27T19:44:47.609869Z","end":"2026-05-27T19:44:48.271428Z","steps":["trace[1486859427] 'agreement among raft nodes before linearized reading' (duration: 661.512743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.270958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042329034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:48.271485Z","caller":"traceutil/trace.go:171","msg":"trace[197758186] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1033; }","duration":"1.042893218s","start":"2026-05-27T19:44:47.228578Z","end":"2026-05-27T19:44:48.271471Z","steps":["trace[197758186] 'agreement among raft nodes before linearized reading' (duration: 1.042332834s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:48.27156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.228559Z","time spent":"1.04298914s","remote":"127.0.0.1:53284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:44:48.271454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.609853Z","time spent":"661.596244ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:45:11.009167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780265,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:45:11.095384Z","caller":"traceutil/trace.go:171","msg":"trace[666255362] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"586.961553ms","start":"2026-05-27T19:45:10.508393Z","end":"2026-05-27T19:45:11.095354Z","steps":["trace[666255362] 'read index received' (duration: 586.84318ms)","trace[666255362] 'applied index is now lower than readState.Index' (duration: 117.333µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:11.09544Z","caller":"traceutil/trace.go:171","msg":"trace[670480209] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"621.031544ms","start":"2026-05-27T19:45:10.474371Z","end":"2026-05-27T19:45:11.095403Z","steps":["trace[670480209] 'process raft request' (duration: 620.83603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:11.095741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.043946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T19:45:11.095765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.308278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:45:11.095771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.74774ms","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-05-27T19:45:11.095902Z","caller":"traceutil/trace.go:171","msg":"trace[1792016739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"576.912114ms","start":"2026-05-27T19:45:10.518969Z","end":"2026-05-27T19:45:11.095881Z","steps":["trace[1792016739] 'agreement among raft nodes before linearized reading' (duration: 576.585907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:11.095939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.474347Z","time spent":"621.169547ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:11.095783Z","caller":"traceutil/trace.go:171","msg":"trace[397255859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"559.134828ms","start":"2026-05-27T19:45:10.536637Z","end":"2026-05-27T19:45:11.095772Z","steps":["trace[397255859] 'agreement among raft nodes before linearized reading' (duration: 558.963154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:11.095973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.518947Z","time spent":"577.014007ms","remote":"127.0.0.1:53158","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-05-27T19:45:11.095966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.794558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:45:11.096014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.536617Z","time spent":"559.383253ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:45:11.095811Z","caller":"traceutil/trace.go:171","msg":"trace[82082477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"557.4132ms","start":"2026-05-27T19:45:10.538385Z","end":"2026-05-27T19:45:11.095798Z","steps":["trace[82082477] 'agreement among raft nodes before linearized reading' (duration: 557.233067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:11.096039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.362072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:45:11.096093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.538333Z","time spent":"557.751588ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:45:11.096069Z","caller":"traceutil/trace.go:171","msg":"trace[329850907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"574.944142ms","start":"2026-05-27T19:45:10.521098Z","end":"2026-05-27T19:45:11.096043Z","steps":["trace[329850907] 'agreement among raft nodes before linearized reading' (duration: 574.596834ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:11.096189Z","caller":"traceutil/trace.go:171","msg":"trace[513914023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"587.799481ms","start":"2026-05-27T19:45:10.50835Z","end":"2026-05-27T19:45:11.096149Z","steps":["trace[513914023] 'agreement among raft nodes before linearized reading' (duration: 587.162727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:11.096163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.521078Z","time spent":"575.071644ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:45:11.096239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:10.508336Z","time spent":"587.889464ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:45:11.206134Z","caller":"traceutil/trace.go:171","msg":"trace[1560798948] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"105.706099ms","start":"2026-05-27T19:45:11.100398Z","end":"2026-05-27T19:45:11.206104Z","steps":["trace[1560798948] 'process raft request' (duration: 104.006072ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:11.327333Z","caller":"traceutil/trace.go:171","msg":"trace[558992894] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"226.046795ms","start":"2026-05-27T19:45:11.101256Z","end":"2026-05-27T19:45:11.327303Z","steps":["trace[558992894] 'process raft request' (duration: 219.719887ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:11.327595Z","caller":"traceutil/trace.go:171","msg":"trace[69394430] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"226.211499ms","start":"2026-05-27T19:45:11.101353Z","end":"2026-05-27T19:45:11.327564Z","steps":["trace[69394430] 'process raft request' (duration: 225.80546ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:11.32781Z","caller":"traceutil/trace.go:171","msg":"trace[182714775] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"226.394013ms","start":"2026-05-27T19:45:11.101408Z","end":"2026-05-27T19:45:11.327802Z","steps":["trace[182714775] 'process raft request' (duration: 226.068646ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:11.327773Z","caller":"traceutil/trace.go:171","msg":"trace[1086618853] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"226.307711ms","start":"2026-05-27T19:45:11.101452Z","end":"2026-05-27T19:45:11.327759Z","steps":["trace[1086618853] 'process raft request' (duration: 226.056806ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:15.329491Z","caller":"traceutil/trace.go:171","msg":"trace[2024157475] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"101.781563ms","start":"2026-05-27T19:45:15.227684Z","end":"2026-05-27T19:45:15.329466Z","steps":["trace[2024157475] 'process raft request' (duration: 99.044034ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.591987Z","caller":"traceutil/trace.go:171","msg":"trace[522406939] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"222.476777ms","start":"2026-05-27T19:45:19.369485Z","end":"2026-05-27T19:45:19.591962Z","steps":["trace[522406939] 'read index received' (duration: 127.288837ms)","trace[522406939] 'applied index is now lower than readState.Index' (duration: 95.18644ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:19.592264Z","caller":"traceutil/trace.go:171","msg":"trace[115361092] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"242.928072ms","start":"2026-05-27T19:45:19.349321Z","end":"2026-05-27T19:45:19.592249Z","steps":["trace[115361092] 'process raft request' (duration: 147.518818ms)","trace[115361092] 'compare' (duration: 94.898383ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:19.592495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.35762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:45:19.592763Z","caller":"traceutil/trace.go:171","msg":"trace[1691464245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1130; }","duration":"179.736998ms","start":"2026-05-27T19:45:19.413011Z","end":"2026-05-27T19:45:19.592748Z","steps":["trace[1691464245] 'agreement among raft nodes before linearized reading' (duration: 179.3647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:19.592691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.292981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:45:19.592697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.217604ms","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-05-27T19:45:19.59298Z","caller":"traceutil/trace.go:171","msg":"trace[862423783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1130; }","duration":"223.552811ms","start":"2026-05-27T19:45:19.369413Z","end":"2026-05-27T19:45:19.592965Z","steps":["trace[862423783] 'agreement among raft nodes before linearized reading' (duration: 223.208523ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.59306Z","caller":"traceutil/trace.go:171","msg":"trace[699581318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1130; }","duration":"175.70469ms","start":"2026-05-27T19:45:19.417339Z","end":"2026-05-27T19:45:19.593043Z","steps":["trace[699581318] 'agreement among raft nodes before linearized reading' (duration: 175.267901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:19.593506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.409723ms","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-05-27T19:45:19.593546Z","caller":"traceutil/trace.go:171","msg":"trace[1624354684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1130; }","duration":"175.199839ms","start":"2026-05-27T19:45:19.418336Z","end":"2026-05-27T19:45:19.593536Z","steps":["trace[1624354684] 'agreement among raft nodes before linearized reading' (duration: 174.247968ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.805548Z","caller":"traceutil/trace.go:171","msg":"trace[1692652863] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"208.545513ms","start":"2026-05-27T19:45:19.596975Z","end":"2026-05-27T19:45:19.80552Z","steps":["trace[1692652863] 'process raft request' (duration: 203.678398ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.886605Z","caller":"traceutil/trace.go:171","msg":"trace[1877567228] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"287.639283ms","start":"2026-05-27T19:45:19.598941Z","end":"2026-05-27T19:45:19.88658Z","steps":["trace[1877567228] 'process raft request' (duration: 287.559231ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.886625Z","caller":"traceutil/trace.go:171","msg":"trace[451826962] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"289.536734ms","start":"2026-05-27T19:45:19.597062Z","end":"2026-05-27T19:45:19.886599Z","steps":["trace[451826962] 'process raft request' (duration: 289.272649ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.886746Z","caller":"traceutil/trace.go:171","msg":"trace[1945186779] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"287.93846ms","start":"2026-05-27T19:45:19.598769Z","end":"2026-05-27T19:45:19.886707Z","steps":["trace[1945186779] 'process raft request' (duration: 287.692454ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.887548Z","caller":"traceutil/trace.go:171","msg":"trace[1213299028] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1177; }","duration":"281.02382ms","start":"2026-05-27T19:45:19.606509Z","end":"2026-05-27T19:45:19.887532Z","steps":["trace[1213299028] 'read index received' (duration: 194.238833ms)","trace[1213299028] 'applied index is now lower than readState.Index' (duration: 86.784087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:19.887665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.139392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:19.887703Z","caller":"traceutil/trace.go:171","msg":"trace[1383396999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"281.229804ms","start":"2026-05-27T19:45:19.606464Z","end":"2026-05-27T19:45:19.887693Z","steps":["trace[1383396999] 'agreement among raft nodes before linearized reading' (duration: 281.130962ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:19.88781Z","caller":"traceutil/trace.go:171","msg":"trace[514333156] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"277.155655ms","start":"2026-05-27T19:45:19.610627Z","end":"2026-05-27T19:45:19.887783Z","steps":["trace[514333156] 'process raft request' (duration: 275.925398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:30.110332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:45:30.611093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780507,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:45:31.003675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.634142581s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:45:31.004174Z","caller":"traceutil/trace.go:171","msg":"trace[2131216435] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.634762415s","start":"2026-05-27T19:45:29.36938Z","end":"2026-05-27T19:45:31.004143Z","steps":["trace[2131216435] 'process raft request' (duration: 1.634572991s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.004352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.369354Z","time spent":"1.634901927s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:31.037552Z","caller":"traceutil/trace.go:171","msg":"trace[1700296918] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1263; }","duration":"1.427917827s","start":"2026-05-27T19:45:29.609608Z","end":"2026-05-27T19:45:31.037526Z","steps":["trace[1700296918] 'read index received' (duration: 1.394307886s)","trace[1700296918] 'applied index is now lower than readState.Index' (duration: 33.609341ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:31.037617Z","caller":"traceutil/trace.go:171","msg":"trace[1723840022] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"852.827752ms","start":"2026-05-27T19:45:30.184759Z","end":"2026-05-27T19:45:31.037587Z","steps":["trace[1723840022] 'process raft request' (duration: 848.715363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.037702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428068981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:45:31.037779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:30.184705Z","time spent":"852.967676ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:45:31.037868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105518218s","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-05-27T19:45:31.037878Z","caller":"traceutil/trace.go:171","msg":"trace[1552039523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"1.428209713s","start":"2026-05-27T19:45:29.609564Z","end":"2026-05-27T19:45:31.037774Z","steps":["trace[1552039523] 'agreement among raft nodes before linearized reading' (duration: 1.42804779s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.037879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110557947s","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-05-27T19:45:31.037906Z","caller":"traceutil/trace.go:171","msg":"trace[1950594163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.105592349s","start":"2026-05-27T19:45:29.932302Z","end":"2026-05-27T19:45:31.037895Z","steps":["trace[1950594163] 'agreement among raft nodes before linearized reading' (duration: 1.105433105s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.037935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.932288Z","time spent":"1.10564031s","remote":"127.0.0.1:53158","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-05-27T19:45:31.037929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.609545Z","time spent":"1.428374257s","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:45:31.037933Z","caller":"traceutil/trace.go:171","msg":"trace[1489016440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"1.110614158s","start":"2026-05-27T19:45:29.927302Z","end":"2026-05-27T19:45:31.037916Z","steps":["trace[1489016440] 'agreement among raft nodes before linearized reading' (duration: 1.110404534s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.037942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112839096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:45:31.037976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192940147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T19:45:31.037977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.92726Z","time spent":"1.110707699s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:45:31.038004Z","caller":"traceutil/trace.go:171","msg":"trace[1687512021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.112978959s","start":"2026-05-27T19:45:29.92501Z","end":"2026-05-27T19:45:31.037989Z","steps":["trace[1687512021] 'agreement among raft nodes before linearized reading' (duration: 1.112817365s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.037861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401912361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:45:31.038037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.924869Z","time spent":"1.113162063s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:45:31.038031Z","caller":"traceutil/trace.go:171","msg":"trace[653295567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1214; }","duration":"1.193011449s","start":"2026-05-27T19:45:29.844999Z","end":"2026-05-27T19:45:31.038011Z","steps":["trace[653295567] 'agreement among raft nodes before linearized reading' (duration: 1.192907397s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.038077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.844983Z","time spent":"1.193085461s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:45:31.038055Z","caller":"traceutil/trace.go:171","msg":"trace[117460894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.402175057s","start":"2026-05-27T19:45:29.635869Z","end":"2026-05-27T19:45:31.038044Z","steps":["trace[117460894] 'agreement among raft nodes before linearized reading' (duration: 1.401821329s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:31.038121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:29.635804Z","time spent":"1.402309861s","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T19:45:31.207077Z","caller":"traceutil/trace.go:171","msg":"trace[1008944673] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"164.456846ms","start":"2026-05-27T19:45:31.042588Z","end":"2026-05-27T19:45:31.207045Z","steps":["trace[1008944673] 'process raft request' (duration: 164.242321ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:31.207131Z","caller":"traceutil/trace.go:171","msg":"trace[440772214] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"162.450862ms","start":"2026-05-27T19:45:31.044657Z","end":"2026-05-27T19:45:31.207108Z","steps":["trace[440772214] 'process raft request' (duration: 162.227167ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:31.207232Z","caller":"traceutil/trace.go:171","msg":"trace[405179811] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"162.287299ms","start":"2026-05-27T19:45:31.044904Z","end":"2026-05-27T19:45:31.207191Z","steps":["trace[405179811] 'process raft request' (duration: 162.009003ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:31.206954Z","caller":"traceutil/trace.go:171","msg":"trace[2438393] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1266; }","duration":"157.144027ms","start":"2026-05-27T19:45:31.049793Z","end":"2026-05-27T19:45:31.206937Z","steps":["trace[2438393] 'read index received' (duration: 156.80075ms)","trace[2438393] 'applied index is now lower than readState.Index' (duration: 342.617µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:31.207386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.577967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:31.207418Z","caller":"traceutil/trace.go:171","msg":"trace[1225948589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"157.656368ms","start":"2026-05-27T19:45:31.049752Z","end":"2026-05-27T19:45:31.207409Z","steps":["trace[1225948589] 'agreement among raft nodes before linearized reading' (duration: 157.581956ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:31.207484Z","caller":"traceutil/trace.go:171","msg":"trace[1303376915] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"165.355636ms","start":"2026-05-27T19:45:31.04211Z","end":"2026-05-27T19:45:31.207465Z","steps":["trace[1303376915] 'process raft request' (duration: 164.542608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:32.514321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780521,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:45:32.550807Z","caller":"traceutil/trace.go:171","msg":"trace[1357764604] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"591.557832ms","start":"2026-05-27T19:45:31.959222Z","end":"2026-05-27T19:45:32.55078Z","steps":["trace[1357764604] 'process raft request' (duration: 591.377888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:32.550994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:31.959197Z","time spent":"591.717316ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:33.256284Z","caller":"traceutil/trace.go:171","msg":"trace[1372477114] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"245.538519ms","start":"2026-05-27T19:45:33.010724Z","end":"2026-05-27T19:45:33.256262Z","steps":["trace[1372477114] 'process raft request' (duration: 245.427637ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:33.256492Z","caller":"traceutil/trace.go:171","msg":"trace[1354415911] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1270; }","duration":"1.243046459s","start":"2026-05-27T19:45:32.013282Z","end":"2026-05-27T19:45:33.256329Z","steps":["trace[1354415911] 'read index received' (duration: 537.424046ms)","trace[1354415911] 'applied index is now lower than readState.Index' (duration: 705.617052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:33.256588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243279273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:33.256668Z","caller":"traceutil/trace.go:171","msg":"trace[1803009051] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1221; }","duration":"1.243414477s","start":"2026-05-27T19:45:32.013235Z","end":"2026-05-27T19:45:33.25665Z","steps":["trace[1803009051] 'agreement among raft nodes before linearized reading' (duration: 1.243268653s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:33.256718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:32.013215Z","time spent":"1.243492478s","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:45:33.256857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.211615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:33.256912Z","caller":"traceutil/trace.go:171","msg":"trace[1809489249] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1221; }","duration":"310.329638ms","start":"2026-05-27T19:45:32.946572Z","end":"2026-05-27T19:45:33.256902Z","steps":["trace[1809489249] 'agreement among raft nodes before linearized reading' (duration: 310.220026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:33.256959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:32.946553Z","time spent":"310.395229ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:45:33.256961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.778222ms","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-05-27T19:45:33.257019Z","caller":"traceutil/trace.go:171","msg":"trace[626032482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"204.883694ms","start":"2026-05-27T19:45:33.052122Z","end":"2026-05-27T19:45:33.257005Z","steps":["trace[626032482] 'agreement among raft nodes before linearized reading' (duration: 204.652739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:33.257777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.222268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:33.257838Z","caller":"traceutil/trace.go:171","msg":"trace[1896977337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"650.31591ms","start":"2026-05-27T19:45:32.607513Z","end":"2026-05-27T19:45:33.257829Z","steps":["trace[1896977337] 'agreement among raft nodes before linearized reading' (duration: 649.125664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:33.257879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:32.607494Z","time spent":"650.378361ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:45:49.805235Z","caller":"traceutil/trace.go:171","msg":"trace[1239611705] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"473.798252ms","start":"2026-05-27T19:45:49.331411Z","end":"2026-05-27T19:45:49.805209Z","steps":["trace[1239611705] 'read index received' (duration: 473.348462ms)","trace[1239611705] 'applied index is now lower than readState.Index' (duration: 448.31µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:49.805343Z","caller":"traceutil/trace.go:171","msg":"trace[2115722687] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"495.682868ms","start":"2026-05-27T19:45:49.30961Z","end":"2026-05-27T19:45:49.805293Z","steps":["trace[2115722687] 'process raft request' (duration: 495.157226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:49.80559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.607774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:45:49.805593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.309589Z","time spent":"495.870632ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:49.805644Z","caller":"traceutil/trace.go:171","msg":"trace[193405174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"437.688216ms","start":"2026-05-27T19:45:49.367943Z","end":"2026-05-27T19:45:49.805632Z","steps":["trace[193405174] 'agreement among raft nodes before linearized reading' (duration: 437.447711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:49.805685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.367891Z","time spent":"437.785409ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:45:49.806427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.373012ms","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-05-27T19:45:49.806479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.613755ms","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-05-27T19:45:49.806525Z","caller":"traceutil/trace.go:171","msg":"trace[2076349582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"380.701437ms","start":"2026-05-27T19:45:49.425813Z","end":"2026-05-27T19:45:49.806515Z","steps":["trace[2076349582] 'agreement among raft nodes before linearized reading' (duration: 380.580125ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:49.806533Z","caller":"traceutil/trace.go:171","msg":"trace[1752160585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"438.574666ms","start":"2026-05-27T19:45:49.367935Z","end":"2026-05-27T19:45:49.80651Z","steps":["trace[1752160585] 'agreement among raft nodes before linearized reading' (duration: 438.26522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:49.806575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.367909Z","time spent":"438.656527ms","remote":"127.0.0.1:53158","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-05-27T19:45:49.806579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.425793Z","time spent":"380.756519ms","remote":"127.0.0.1:53158","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-05-27T19:45:49.806521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.743077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:45:49.806155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.737563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:45:49.806917Z","caller":"traceutil/trace.go:171","msg":"trace[102952173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"196.197517ms","start":"2026-05-27T19:45:49.610699Z","end":"2026-05-27T19:45:49.806896Z","steps":["trace[102952173] 'agreement among raft nodes before linearized reading' (duration: 195.685266ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:49.806948Z","caller":"traceutil/trace.go:171","msg":"trace[410123634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"378.595422ms","start":"2026-05-27T19:45:49.428326Z","end":"2026-05-27T19:45:49.806922Z","steps":["trace[410123634] 'agreement among raft nodes before linearized reading' (duration: 377.518369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:49.807016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.428311Z","time spent":"378.691274ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:45:49.80731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.904587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:45:49.807359Z","caller":"traceutil/trace.go:171","msg":"trace[1697552417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"476.00802ms","start":"2026-05-27T19:45:49.331334Z","end":"2026-05-27T19:45:49.807342Z","steps":["trace[1697552417] 'agreement among raft nodes before linearized reading' (duration: 474.022487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:49.807401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.331304Z","time spent":"476.084431ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:45:50.36483Z","caller":"traceutil/trace.go:171","msg":"trace[383291824] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"551.234466ms","start":"2026-05-27T19:45:49.813571Z","end":"2026-05-27T19:45:50.364805Z","steps":["trace[383291824] 'process raft request' (duration: 551.009041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:50.364991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.813549Z","time spent":"551.357719ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:50.365247Z","caller":"traceutil/trace.go:171","msg":"trace[561783855] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"551.464701ms","start":"2026-05-27T19:45:49.813752Z","end":"2026-05-27T19:45:50.365217Z","steps":["trace[561783855] 'process raft request' (duration: 551.012621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:50.365431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.813741Z","time spent":"551.592114ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:50.366087Z","caller":"traceutil/trace.go:171","msg":"trace[1685051821] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"552.291669ms","start":"2026-05-27T19:45:49.813768Z","end":"2026-05-27T19:45:50.36606Z","steps":["trace[1685051821] 'process raft request' (duration: 551.335609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:50.366214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.813757Z","time spent":"552.382781ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:50.366174Z","caller":"traceutil/trace.go:171","msg":"trace[1687783983] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"552.32411ms","start":"2026-05-27T19:45:49.813812Z","end":"2026-05-27T19:45:50.366136Z","steps":["trace[1687783983] 'process raft request' (duration: 552.198887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:50.366352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:49.813807Z","time spent":"552.452813ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:51.577135Z","caller":"traceutil/trace.go:171","msg":"trace[505339904] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"255.835233ms","start":"2026-05-27T19:45:51.321271Z","end":"2026-05-27T19:45:51.577106Z","steps":["trace[505339904] 'process raft request' (duration: 255.601318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.387898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780687,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:45:52.732401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.351491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:45:52.732475Z","caller":"traceutil/trace.go:171","msg":"trace[1692312792] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"912.821677ms","start":"2026-05-27T19:45:51.819641Z","end":"2026-05-27T19:45:52.732463Z","steps":["trace[1692312792] 'process raft request' (duration: 681.341004ms)","trace[1692312792] 'compare' (duration: 231.045263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:52.732507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:51.819593Z","time spent":"912.900199ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:52.854555Z","caller":"traceutil/trace.go:171","msg":"trace[1890018852] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1343; }","duration":"967.449125ms","start":"2026-05-27T19:45:51.887068Z","end":"2026-05-27T19:45:52.854517Z","steps":["trace[1890018852] 'read index received' (duration: 613.928718ms)","trace[1890018852] 'applied index is now lower than readState.Index' (duration: 353.518597ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:52.854772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.060589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:45:52.854767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.660769ms","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-05-27T19:45:52.854824Z","caller":"traceutil/trace.go:171","msg":"trace[1639087375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"245.177491ms","start":"2026-05-27T19:45:52.609637Z","end":"2026-05-27T19:45:52.854814Z","steps":["trace[1639087375] 'agreement among raft nodes before linearized reading' (duration: 245.093289ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:52.854868Z","caller":"traceutil/trace.go:171","msg":"trace[426989004] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1289; }","duration":"967.817903ms","start":"2026-05-27T19:45:51.887015Z","end":"2026-05-27T19:45:52.854833Z","steps":["trace[426989004] 'agreement among raft nodes before linearized reading' (duration: 967.613628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.854928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:51.886994Z","time spent":"967.922965ms","remote":"127.0.0.1:33488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:45:52.854928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.928401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:45:52.855036Z","caller":"traceutil/trace.go:171","msg":"trace[1486414633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"483.099474ms","start":"2026-05-27T19:45:52.371915Z","end":"2026-05-27T19:45:52.855014Z","steps":["trace[1486414633] 'agreement among raft nodes before linearized reading' (duration: 482.795058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.855084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:52.371894Z","time spent":"483.176047ms","remote":"127.0.0.1:53158","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-05-27T19:45:52.855171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.674889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:45:52.855214Z","caller":"traceutil/trace.go:171","msg":"trace[1285314396] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1289; }","duration":"415.74982ms","start":"2026-05-27T19:45:52.439454Z","end":"2026-05-27T19:45:52.855204Z","steps":["trace[1285314396] 'agreement among raft nodes before linearized reading' (duration: 415.675068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.855244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:52.439439Z","time spent":"415.796261ms","remote":"127.0.0.1:53090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:45:52.855628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.518506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-27T19:45:52.855634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.703941ms","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-05-27T19:45:52.85567Z","caller":"traceutil/trace.go:171","msg":"trace[1902822862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"484.600557ms","start":"2026-05-27T19:45:52.371056Z","end":"2026-05-27T19:45:52.855657Z","steps":["trace[1902822862] 'agreement among raft nodes before linearized reading' (duration: 484.450764ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:52.855675Z","caller":"traceutil/trace.go:171","msg":"trace[1698297223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"485.769173ms","start":"2026-05-27T19:45:52.369894Z","end":"2026-05-27T19:45:52.855663Z","steps":["trace[1698297223] 'agreement among raft nodes before linearized reading' (duration: 485.608439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.8557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:52.371031Z","time spent":"484.662688ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:45:52.855708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:52.369875Z","time spent":"485.826493ms","remote":"127.0.0.1:53158","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-05-27T19:45:52.855902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.868733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:45:52.856327Z","caller":"traceutil/trace.go:171","msg":"trace[176065388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"485.322203ms","start":"2026-05-27T19:45:52.370984Z","end":"2026-05-27T19:45:52.856306Z","steps":["trace[176065388] 'agreement among raft nodes before linearized reading' (duration: 484.515525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.856521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:52.370975Z","time spent":"485.521697ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:45:52.856083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.476698ms","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":"warn","ts":"2026-05-27T19:45:52.856741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.406849ms","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-05-27T19:45:52.856744Z","caller":"traceutil/trace.go:171","msg":"trace[1534051589] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1289; }","duration":"275.196473ms","start":"2026-05-27T19:45:52.581514Z","end":"2026-05-27T19:45:52.856711Z","steps":["trace[1534051589] 'agreement among raft nodes before linearized reading' (duration: 273.20849ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:52.856795Z","caller":"traceutil/trace.go:171","msg":"trace[1684083910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"964.490541ms","start":"2026-05-27T19:45:51.892291Z","end":"2026-05-27T19:45:52.856781Z","steps":["trace[1684083910] 'agreement among raft nodes before linearized reading' (duration: 962.381885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:52.856834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:51.892277Z","time spent":"964.550162ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:45:53.016031Z","caller":"traceutil/trace.go:171","msg":"trace[299301637] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"154.928159ms","start":"2026-05-27T19:45:52.861068Z","end":"2026-05-27T19:45:53.015997Z","steps":["trace[299301637] 'process raft request' (duration: 119.979499ms)","trace[299301637] 'compare' (duration: 34.658753ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:53.016529Z","caller":"traceutil/trace.go:171","msg":"trace[359079666] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"154.99679ms","start":"2026-05-27T19:45:52.861504Z","end":"2026-05-27T19:45:53.016501Z","steps":["trace[359079666] 'process raft request' (duration: 154.432197ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:53.016739Z","caller":"traceutil/trace.go:171","msg":"trace[202819267] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"152.806683ms","start":"2026-05-27T19:45:52.863919Z","end":"2026-05-27T19:45:53.016726Z","steps":["trace[202819267] 'process raft request' (duration: 152.722091ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:53.016754Z","caller":"traceutil/trace.go:171","msg":"trace[939594380] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"152.975616ms","start":"2026-05-27T19:45:52.863767Z","end":"2026-05-27T19:45:53.016743Z","steps":["trace[939594380] 'process raft request' (duration: 152.594778ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:53.181754Z","caller":"traceutil/trace.go:171","msg":"trace[1182714143] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"123.008744ms","start":"2026-05-27T19:45:53.058719Z","end":"2026-05-27T19:45:53.181727Z","steps":["trace[1182714143] 'process raft request' (duration: 109.90322ms)","trace[1182714143] 'compare' (duration: 12.940581ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:53.360047Z","caller":"traceutil/trace.go:171","msg":"trace[782669905] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"207.085563ms","start":"2026-05-27T19:45:53.152927Z","end":"2026-05-27T19:45:53.360013Z","steps":["trace[782669905] 'process raft request' (duration: 160.188393ms)","trace[782669905] 'compare' (duration: 46.556193ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:03.32525Z","caller":"traceutil/trace.go:171","msg":"trace[736585458] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"343.724863ms","start":"2026-05-27T19:46:02.981502Z","end":"2026-05-27T19:46:03.325227Z","steps":["trace[736585458] 'read index received' (duration: 343.535959ms)","trace[736585458] 'applied index is now lower than readState.Index' (duration: 188.064µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:03.325402Z","caller":"traceutil/trace.go:171","msg":"trace[1651186129] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"399.812404ms","start":"2026-05-27T19:46:02.925477Z","end":"2026-05-27T19:46:03.325289Z","steps":["trace[1651186129] 'process raft request' (duration: 399.615459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:03.325565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.072052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:46:03.325567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.711206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-27T19:46:03.325577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.352057ms","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-05-27T19:46:03.325625Z","caller":"traceutil/trace.go:171","msg":"trace[1367865580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"184.172925ms","start":"2026-05-27T19:46:03.141438Z","end":"2026-05-27T19:46:03.325611Z","steps":["trace[1367865580] 'agreement among raft nodes before linearized reading' (duration: 183.97556ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.325653Z","caller":"traceutil/trace.go:171","msg":"trace[995551339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"183.459529ms","start":"2026-05-27T19:46:03.142175Z","end":"2026-05-27T19:46:03.325634Z","steps":["trace[995551339] 'agreement among raft nodes before linearized reading' (duration: 183.260475ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.325644Z","caller":"traceutil/trace.go:171","msg":"trace[160507441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"224.839429ms","start":"2026-05-27T19:46:03.100784Z","end":"2026-05-27T19:46:03.325623Z","steps":["trace[160507441] 'agreement among raft nodes before linearized reading' (duration: 224.608044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:03.325601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:02.925444Z","time spent":"400.068769ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:03.325461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.947679ms","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-05-27T19:46:03.325858Z","caller":"traceutil/trace.go:171","msg":"trace[771872882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1378; }","duration":"344.405239ms","start":"2026-05-27T19:46:02.98144Z","end":"2026-05-27T19:46:03.325845Z","steps":["trace[771872882] 'agreement among raft nodes before linearized reading' (duration: 343.904628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:03.325892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:02.981422Z","time spent":"344.45956ms","remote":"127.0.0.1:53158","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-05-27T19:46:03.326635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.263875ms","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-05-27T19:46:03.326731Z","caller":"traceutil/trace.go:171","msg":"trace[717464784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"223.404617ms","start":"2026-05-27T19:46:03.103284Z","end":"2026-05-27T19:46:03.326689Z","steps":["trace[717464784] 'agreement among raft nodes before linearized reading' (duration: 222.514228ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.565957Z","caller":"traceutil/trace.go:171","msg":"trace[2021204675] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"236.41103ms","start":"2026-05-27T19:46:03.329526Z","end":"2026-05-27T19:46:03.565937Z","steps":["trace[2021204675] 'process raft request' (duration: 223.492879ms)","trace[2021204675] 'compare' (duration: 12.195915ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:03.608089Z","caller":"traceutil/trace.go:171","msg":"trace[656919295] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"277.81037ms","start":"2026-05-27T19:46:03.330256Z","end":"2026-05-27T19:46:03.608066Z","steps":["trace[656919295] 'process raft request' (duration: 277.659847ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.608324Z","caller":"traceutil/trace.go:171","msg":"trace[356927388] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"277.949294ms","start":"2026-05-27T19:46:03.330356Z","end":"2026-05-27T19:46:03.608305Z","steps":["trace[356927388] 'process raft request' (duration: 277.664017ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.608507Z","caller":"traceutil/trace.go:171","msg":"trace[215880463] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"277.688038ms","start":"2026-05-27T19:46:03.330812Z","end":"2026-05-27T19:46:03.6085Z","steps":["trace[215880463] 'process raft request' (duration: 277.410602ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.608804Z","caller":"traceutil/trace.go:171","msg":"trace[1530998028] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"277.844881ms","start":"2026-05-27T19:46:03.330945Z","end":"2026-05-27T19:46:03.608789Z","steps":["trace[1530998028] 'process raft request' (duration: 277.529924ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.608931Z","caller":"traceutil/trace.go:171","msg":"trace[1893743807] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"106.192539ms","start":"2026-05-27T19:46:03.502724Z","end":"2026-05-27T19:46:03.608917Z","steps":["trace[1893743807] 'process raft request' (duration: 105.990154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:03.865838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.976564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:03.865984Z","caller":"traceutil/trace.go:171","msg":"trace[951918679] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1384; }","duration":"117.161178ms","start":"2026-05-27T19:46:03.748802Z","end":"2026-05-27T19:46:03.865963Z","steps":["trace[951918679] 'count revisions from in-memory index tree' (duration: 116.896312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.283704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:46:05.539221Z","caller":"traceutil/trace.go:171","msg":"trace[1475739618] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"756.382367ms","start":"2026-05-27T19:46:04.782816Z","end":"2026-05-27T19:46:05.539198Z","steps":["trace[1475739618] 'read index received' (duration: 756.192923ms)","trace[1475739618] 'applied index is now lower than readState.Index' (duration: 188.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:05.539318Z","caller":"traceutil/trace.go:171","msg":"trace[1862247838] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"768.00713ms","start":"2026-05-27T19:46:04.771273Z","end":"2026-05-27T19:46:05.53928Z","steps":["trace[1862247838] 'process raft request' (duration: 767.791905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.5396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.945881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-27T19:46:05.53964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.06642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:05.539685Z","caller":"traceutil/trace.go:171","msg":"trace[1220936855] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1387; }","duration":"326.172472ms","start":"2026-05-27T19:46:05.2135Z","end":"2026-05-27T19:46:05.539673Z","steps":["trace[1220936855] 'agreement among raft nodes before linearized reading' (duration: 326.0661ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:05.539678Z","caller":"traceutil/trace.go:171","msg":"trace[1028762022] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1387; }","duration":"208.056994ms","start":"2026-05-27T19:46:05.331601Z","end":"2026-05-27T19:46:05.539658Z","steps":["trace[1028762022] 'agreement among raft nodes before linearized reading' (duration: 207.87088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.539659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.075217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:46:05.539753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.285469ms","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":"warn","ts":"2026-05-27T19:46:05.539394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.568051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-27T19:46:05.539891Z","caller":"traceutil/trace.go:171","msg":"trace[1472562166] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1387; }","duration":"757.111913ms","start":"2026-05-27T19:46:04.782767Z","end":"2026-05-27T19:46:05.539879Z","steps":["trace[1472562166] 'agreement among raft nodes before linearized reading' (duration: 756.558451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.539927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:04.782739Z","time spent":"757.178494ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-27T19:46:05.539902Z","caller":"traceutil/trace.go:171","msg":"trace[572253888] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1387; }","duration":"690.485074ms","start":"2026-05-27T19:46:04.849387Z","end":"2026-05-27T19:46:05.539872Z","steps":["trace[572253888] 'agreement among raft nodes before linearized reading' (duration: 690.248539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.539808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.001192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:46:05.539724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.21347Z","time spent":"326.242613ms","remote":"127.0.0.1:53276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:05.539987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:04.849371Z","time spent":"690.599176ms","remote":"127.0.0.1:33586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:46:05.539833Z","caller":"traceutil/trace.go:171","msg":"trace[1559873636] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1387; }","duration":"731.28595ms","start":"2026-05-27T19:46:04.808526Z","end":"2026-05-27T19:46:05.539812Z","steps":["trace[1559873636] 'agreement among raft nodes before linearized reading' (duration: 730.907262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.540399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:04.808508Z","time spent":"731.877004ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:46:05.540659Z","caller":"traceutil/trace.go:171","msg":"trace[1410107291] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1387; }","duration":"156.505623ms","start":"2026-05-27T19:46:05.383596Z","end":"2026-05-27T19:46:05.540102Z","steps":["trace[1410107291] 'agreement among raft nodes before linearized reading' (duration: 155.958651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:05.539802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:04.771254Z","time spent":"768.209754ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:06.045703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9965514257698780872,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:46:06.347262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.774498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:06.347352Z","caller":"traceutil/trace.go:171","msg":"trace[337307418] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"802.683393ms","start":"2026-05-27T19:46:05.544654Z","end":"2026-05-27T19:46:06.347337Z","steps":["trace[337307418] 'read index received' (duration: 691.831072ms)","trace[337307418] 'applied index is now lower than readState.Index' (duration: 110.851221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:06.34752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.906394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:46:06.347518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.834926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-27T19:46:06.34745Z","caller":"traceutil/trace.go:171","msg":"trace[1910590061] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"803.768156ms","start":"2026-05-27T19:46:05.543642Z","end":"2026-05-27T19:46:06.34741Z","steps":["trace[1910590061] 'process raft request' (duration: 692.771353ms)","trace[1910590061] 'compare' (duration: 109.833058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:06.347592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.935626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:06.347593Z","caller":"traceutil/trace.go:171","msg":"trace[663993955] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1388; }","duration":"802.938378ms","start":"2026-05-27T19:46:05.544637Z","end":"2026-05-27T19:46:06.347575Z","steps":["trace[663993955] 'agreement among raft nodes before linearized reading' (duration: 802.769974ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:06.347617Z","caller":"traceutil/trace.go:171","msg":"trace[885448904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"738.995428ms","start":"2026-05-27T19:46:05.608614Z","end":"2026-05-27T19:46:06.34761Z","steps":["trace[885448904] 'agreement among raft nodes before linearized reading' (duration: 738.957107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.347636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.54463Z","time spent":"802.997949ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-27T19:46:06.347656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.608591Z","time spent":"739.054628ms","remote":"127.0.0.1:52860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:46:06.347685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.54362Z","time spent":"803.989271ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:06.347672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.795944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-27T19:46:06.347698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.887818ms","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-05-27T19:46:06.347752Z","caller":"traceutil/trace.go:171","msg":"trace[95153677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1388; }","duration":"733.901846ms","start":"2026-05-27T19:46:05.613832Z","end":"2026-05-27T19:46:06.347734Z","steps":["trace[95153677] 'agreement among raft nodes before linearized reading' (duration: 733.633261ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:06.347775Z","caller":"traceutil/trace.go:171","msg":"trace[1765296708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1388; }","duration":"735.016611ms","start":"2026-05-27T19:46:05.612742Z","end":"2026-05-27T19:46:06.347758Z","steps":["trace[1765296708] 'agreement among raft nodes before linearized reading' (duration: 734.821327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.347805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.613827Z","time spent":"733.969488ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:06.347818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.612719Z","time spent":"735.090522ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:06.347919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.029041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-27T19:46:06.347554Z","caller":"traceutil/trace.go:171","msg":"trace[1340941336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1388; }","duration":"777.974125ms","start":"2026-05-27T19:46:05.569571Z","end":"2026-05-27T19:46:06.347545Z","steps":["trace[1340941336] 'agreement among raft nodes before linearized reading' (duration: 777.853453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.347997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.394734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:06.348079Z","caller":"traceutil/trace.go:171","msg":"trace[295533484] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1388; }","duration":"388.507837ms","start":"2026-05-27T19:46:05.959555Z","end":"2026-05-27T19:46:06.348063Z","steps":["trace[295533484] 'agreement among raft nodes before linearized reading' (duration: 388.408004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.348126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.959537Z","time spent":"388.576728ms","remote":"127.0.0.1:33718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:06.347999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.569556Z","time spent":"778.433245ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:46:06.348019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.434648ms","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-05-27T19:46:06.348418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.196348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T19:46:06.348505Z","caller":"traceutil/trace.go:171","msg":"trace[538534773] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1388; }","duration":"576.324881ms","start":"2026-05-27T19:46:05.772164Z","end":"2026-05-27T19:46:06.348489Z","steps":["trace[538534773] 'agreement among raft nodes before linearized reading' (duration: 575.805969ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:06.348091Z","caller":"traceutil/trace.go:171","msg":"trace[1806090103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"735.121503ms","start":"2026-05-27T19:46:05.612858Z","end":"2026-05-27T19:46:06.34798Z","steps":["trace[1806090103] 'agreement among raft nodes before linearized reading' (duration: 734.612592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.349311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.612852Z","time spent":"736.423472ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:06.349924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.772145Z","time spent":"576.398622ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T19:46:06.348324Z","caller":"traceutil/trace.go:171","msg":"trace[775502054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1388; }","duration":"734.750195ms","start":"2026-05-27T19:46:05.613562Z","end":"2026-05-27T19:46:06.348313Z","steps":["trace[775502054] 'agreement among raft nodes before linearized reading' (duration: 734.385087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.351412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:05.613556Z","time spent":"737.832162ms","remote":"127.0.0.1:53158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T19:46:08.613807Z","caller":"traceutil/trace.go:171","msg":"trace[254959493] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"210.921427ms","start":"2026-05-27T19:46:08.402861Z","end":"2026-05-27T19:46:08.613783Z","steps":["trace[254959493] 'process raft request' (duration: 210.847715ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:08.613848Z","caller":"traceutil/trace.go:171","msg":"trace[1475695657] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"211.829656ms","start":"2026-05-27T19:46:08.401988Z","end":"2026-05-27T19:46:08.613818Z","steps":["trace[1475695657] 'process raft request' (duration: 211.578211ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:08.61414Z","caller":"traceutil/trace.go:171","msg":"trace[735923519] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"210.990378ms","start":"2026-05-27T19:46:08.403137Z","end":"2026-05-27T19:46:08.614128Z","steps":["trace[735923519] 'process raft request' (duration: 210.859605ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:08.614112Z","caller":"traceutil/trace.go:171","msg":"trace[2005836858] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"211.011328ms","start":"2026-05-27T19:46:08.403075Z","end":"2026-05-27T19:46:08.614086Z","steps":["trace[2005836858] 'process raft request' (duration: 210.672261ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:40.971953Z","caller":"traceutil/trace.go:171","msg":"trace[1224971489] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"126.076362ms","start":"2026-05-27T19:46:40.845843Z","end":"2026-05-27T19:46:40.971919Z","steps":["trace[1224971489] 'process raft request' (duration: 107.263763ms)","trace[1224971489] 'compare' (duration: 18.609914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:45.108698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.559288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T19:46:45.108784Z","caller":"traceutil/trace.go:171","msg":"trace[1742255379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1570; }","duration":"124.700541ms","start":"2026-05-27T19:46:44.984063Z","end":"2026-05-27T19:46:45.108764Z","steps":["trace[1742255379] 'range keys from in-memory index tree' (duration: 124.386664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:45.963087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.820743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:45.963186Z","caller":"traceutil/trace.go:171","msg":"trace[1037739350] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1572; }","duration":"106.972315ms","start":"2026-05-27T19:46:45.856195Z","end":"2026-05-27T19:46:45.963167Z","steps":["trace[1037739350] 'count revisions from in-memory index tree' (duration: 106.73808ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:53.408548Z","caller":"traceutil/trace.go:171","msg":"trace[2132737043] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"213.743128ms","start":"2026-05-27T19:46:53.194782Z","end":"2026-05-27T19:46:53.408525Z","steps":["trace[2132737043] 'process raft request' (duration: 213.608925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:54.913112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.062255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-27T19:46:54.913191Z","caller":"traceutil/trace.go:171","msg":"trace[1085122916] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1611; }","duration":"117.193358ms","start":"2026-05-27T19:46:54.79598Z","end":"2026-05-27T19:46:54.913173Z","steps":["trace[1085122916] 'range keys from in-memory index tree' (duration: 116.893152ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:11.26594Z","caller":"traceutil/trace.go:171","msg":"trace[1391418564] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"166.956689ms","start":"2026-05-27T19:47:11.098934Z","end":"2026-05-27T19:47:11.265891Z","steps":["trace[1391418564] 'process raft request' (duration: 97.586546ms)","trace[1391418564] 'compare' (duration: 69.072036ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:11.266228Z","caller":"traceutil/trace.go:171","msg":"trace[245203864] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"141.899001ms","start":"2026-05-27T19:47:11.124296Z","end":"2026-05-27T19:47:11.266195Z","steps":["trace[245203864] 'process raft request' (duration: 141.476351ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:11.664405Z","caller":"traceutil/trace.go:171","msg":"trace[1148987375] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"154.6146ms","start":"2026-05-27T19:47:11.509764Z","end":"2026-05-27T19:47:11.664379Z","steps":["trace[1148987375] 'process raft request' (duration: 154.455656ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:11.700926Z","caller":"traceutil/trace.go:171","msg":"trace[1982163628] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"189.992118ms","start":"2026-05-27T19:47:11.51091Z","end":"2026-05-27T19:47:11.700902Z","steps":["trace[1982163628] 'process raft request' (duration: 189.826574ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:13.217775Z","caller":"traceutil/trace.go:171","msg":"trace[363836137] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"139.930853ms","start":"2026-05-27T19:47:13.077808Z","end":"2026-05-27T19:47:13.217739Z","steps":["trace[363836137] 'process raft request' (duration: 139.624946ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:08.146058Z","caller":"traceutil/trace.go:171","msg":"trace[862569105] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"130.724377ms","start":"2026-05-27T19:50:08.01529Z","end":"2026-05-27T19:50:08.146014Z","steps":["trace[862569105] 'process raft request' (duration: 130.602294ms)"],"step_count":1}