{"level":"warn","ts":"2026-06-02T12:20:46.475098Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T12:20:46.475184Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-02T12:20:46.475248Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T12:20:46.47526Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-02T12:20:46.475279Z","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-06-02T12:20:46.475921Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-06-02T12:20:46.476001Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-02T12:20:46.480671Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.444794ms"} {"level":"info","ts":"2026-06-02T12:20:46.490323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-06-02T12:20:46.490385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T12:20:46.490405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-06-02T12:20:46.490417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T12:20:46.490425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-06-02T12:20:46.490457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-06-02T12:20:46.496878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T12:20:46.498227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T12:20:46.49946Z","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-06-02T12:20:46.502547Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T12:20:46.503133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T12:20:46.503203Z","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-06-02T12:20:46.503241Z","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-06-02T12:20:46.503277Z","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-06-02T12:20:46.50728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-06-02T12:20:46.509247Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-02T12:20:46.511549Z","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-06-02T12:20:46.511801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T12:20:46.511835Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T12:20:46.511882Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-02T12:20:46.511949Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-02T12:20:46.691243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T12:20:46.691427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T12:20:46.691784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-06-02T12:20:46.691822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-06-02T12:20:46.691978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-02T12:20:46.692097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-06-02T12:20:46.692121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-02T12:20:46.693353Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:20:46.694096Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T12:20:46.694107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T12:20:46.69413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T12:20:46.694519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T12:20:46.69457Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T12:20:46.694776Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:20:46.695015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:20:46.695191Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T12:20:46.695868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T12:20:46.697185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"warn","ts":"2026-06-02T12:21:12.467152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:12.967509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:12.985299Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045406096s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:21:12.985699Z","caller":"traceutil/trace.go:171","msg":"trace[1026860719] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"1.019628374s","start":"2026-06-02T12:21:11.966044Z","end":"2026-06-02T12:21:12.985672Z","steps":["trace[1026860719] 'read index received' (duration: 1.019377917s)","trace[1026860719] 'applied index is now lower than readState.Index' (duration: 249.167µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:12.985952Z","caller":"traceutil/trace.go:171","msg":"trace[810586551] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"1.046154195s","start":"2026-06-02T12:21:11.939786Z","end":"2026-06-02T12:21:12.98594Z","steps":["trace[810586551] 'process raft request' (duration: 1.045703643s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:12.98653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:11.939755Z","time spent":"1.046234028s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:12.986763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020713602s","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-06-02T12:21:12.986814Z","caller":"traceutil/trace.go:171","msg":"trace[1139486136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"1.020791584s","start":"2026-06-02T12:21:11.96601Z","end":"2026-06-02T12:21:12.986802Z","steps":["trace[1139486136] 'agreement among raft nodes before linearized reading' (duration: 1.020711302s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:12.986845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:11.965995Z","time spent":"1.020843105s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:21:12.986996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.562403ms","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-06-02T12:21:12.98703Z","caller":"traceutil/trace.go:171","msg":"trace[1142222190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"418.613244ms","start":"2026-06-02T12:21:12.568408Z","end":"2026-06-02T12:21:12.987021Z","steps":["trace[1142222190] 'agreement among raft nodes before linearized reading' (duration: 418.557302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:12.987059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:12.568393Z","time spent":"418.660296ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T12:21:12.987258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.034138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:21:12.987295Z","caller":"traceutil/trace.go:171","msg":"trace[1231967881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"200.10912ms","start":"2026-06-02T12:21:12.787177Z","end":"2026-06-02T12:21:12.987286Z","steps":["trace[1231967881] 'agreement among raft nodes before linearized reading' (duration: 200.048418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:12.98744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.838139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:21:12.987482Z","caller":"traceutil/trace.go:171","msg":"trace[1628904379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"244.919611ms","start":"2026-06-02T12:21:12.742554Z","end":"2026-06-02T12:21:12.987474Z","steps":["trace[1628904379] 'agreement among raft nodes before linearized reading' (duration: 244.85838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:13.497482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.254537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:13.497593Z","caller":"traceutil/trace.go:171","msg":"trace[985678878] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"503.123855ms","start":"2026-06-02T12:21:12.994451Z","end":"2026-06-02T12:21:13.497575Z","steps":["trace[985678878] 'process raft request' (duration: 379.188621ms)","trace[985678878] 'compare' (duration: 123.102763ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:13.497684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:12.994414Z","time spent":"503.227468ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:13.502253Z","caller":"traceutil/trace.go:171","msg":"trace[1332420776] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"507.642722ms","start":"2026-06-02T12:21:12.994581Z","end":"2026-06-02T12:21:13.502224Z","steps":["trace[1332420776] 'process raft request' (duration: 506.43931ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:13.502286Z","caller":"traceutil/trace.go:171","msg":"trace[846646606] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"507.563079ms","start":"2026-06-02T12:21:12.994675Z","end":"2026-06-02T12:21:13.502238Z","steps":["trace[846646606] 'process raft request' (duration: 507.501558ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:13.502317Z","caller":"traceutil/trace.go:171","msg":"trace[753008147] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"507.630791ms","start":"2026-06-02T12:21:12.994636Z","end":"2026-06-02T12:21:13.502267Z","steps":["trace[753008147] 'process raft request' (duration: 507.461917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:13.502388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:12.99455Z","time spent":"507.784525ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:13.50249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:12.994665Z","time spent":"507.711853ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:13.502533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:12.994599Z","time spent":"507.818286ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:15.827393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.867995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:15.827592Z","caller":"traceutil/trace.go:171","msg":"trace[989944711] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:488; }","duration":"109.140274ms","start":"2026-06-02T12:21:15.718425Z","end":"2026-06-02T12:21:15.827566Z","steps":["trace[989944711] 'read index received' (duration: 63.062µs)","trace[989944711] 'applied index is now lower than readState.Index' (duration: 109.076292ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:15.827699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.278507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:15.827731Z","caller":"traceutil/trace.go:171","msg":"trace[994279144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"109.349099ms","start":"2026-06-02T12:21:15.718372Z","end":"2026-06-02T12:21:15.827721Z","steps":["trace[994279144] 'agreement among raft nodes before linearized reading' (duration: 109.255776ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:15.82784Z","caller":"traceutil/trace.go:171","msg":"trace[718089198] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"316.88355ms","start":"2026-06-02T12:21:15.510931Z","end":"2026-06-02T12:21:15.827815Z","steps":["trace[718089198] 'process raft request' (duration: 174.514703ms)","trace[718089198] 'compare' (duration: 141.741021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:15.827959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:15.510912Z","time spent":"316.984763ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:15.828176Z","caller":"traceutil/trace.go:171","msg":"trace[20448169] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"317.185728ms","start":"2026-06-02T12:21:15.510983Z","end":"2026-06-02T12:21:15.828169Z","steps":["trace[20448169] 'process raft request' (duration: 316.547662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:15.828218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:15.510968Z","time spent":"317.223449ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:15.828232Z","caller":"traceutil/trace.go:171","msg":"trace[1985118928] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"317.222649ms","start":"2026-06-02T12:21:15.510949Z","end":"2026-06-02T12:21:15.828172Z","steps":["trace[1985118928] 'process raft request' (duration: 316.535342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:15.828437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:15.510933Z","time spent":"317.383373ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:18.904123Z","caller":"traceutil/trace.go:171","msg":"trace[1642337673] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"136.928628ms","start":"2026-06-02T12:21:18.767166Z","end":"2026-06-02T12:21:18.904095Z","steps":["trace[1642337673] 'process raft request' (duration: 136.778524ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:25.061817Z","caller":"traceutil/trace.go:171","msg":"trace[1659866429] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"181.571525ms","start":"2026-06-02T12:21:24.880179Z","end":"2026-06-02T12:21:25.06175Z","steps":["trace[1659866429] 'process raft request' (duration: 175.794377ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:25.062075Z","caller":"traceutil/trace.go:171","msg":"trace[870621378] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"165.656636ms","start":"2026-06-02T12:21:24.896412Z","end":"2026-06-02T12:21:25.062069Z","steps":["trace[870621378] 'process raft request' (duration: 165.355879ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:25.061967Z","caller":"traceutil/trace.go:171","msg":"trace[2052187306] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"180.6043ms","start":"2026-06-02T12:21:24.881296Z","end":"2026-06-02T12:21:25.0619Z","steps":["trace[2052187306] 'process raft request' (duration: 180.350193ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:25.062431Z","caller":"traceutil/trace.go:171","msg":"trace[364679636] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"165.848051ms","start":"2026-06-02T12:21:24.896575Z","end":"2026-06-02T12:21:25.062423Z","steps":["trace[364679636] 'process raft request' (duration: 165.468162ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:29.047878Z","caller":"traceutil/trace.go:171","msg":"trace[1226092207] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"116.976836ms","start":"2026-06-02T12:21:28.930866Z","end":"2026-06-02T12:21:29.047843Z","steps":["trace[1226092207] 'process raft request' (duration: 84.928003ms)","trace[1226092207] 'compare' (duration: 31.844637ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:29.848259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.452786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T12:21:29.848348Z","caller":"traceutil/trace.go:171","msg":"trace[45704282] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:620; }","duration":"101.583539ms","start":"2026-06-02T12:21:29.746744Z","end":"2026-06-02T12:21:29.848327Z","steps":["trace[45704282] 'range keys from in-memory index tree' (duration: 101.342283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:30.095704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.424576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:30.095982Z","caller":"traceutil/trace.go:171","msg":"trace[1955289527] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"170.570943ms","start":"2026-06-02T12:21:29.925383Z","end":"2026-06-02T12:21:30.095954Z","steps":["trace[1955289527] 'process raft request' (duration: 61.753247ms)","trace[1955289527] 'compare' (duration: 108.239281ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:30.096376Z","caller":"traceutil/trace.go:171","msg":"trace[1239582128] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"161.075078ms","start":"2026-06-02T12:21:29.935288Z","end":"2026-06-02T12:21:30.096363Z","steps":["trace[1239582128] 'read index received' (duration: 51.924054ms)","trace[1239582128] 'applied index is now lower than readState.Index' (duration: 109.149294ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:30.096599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.291053ms","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-06-02T12:21:30.096637Z","caller":"traceutil/trace.go:171","msg":"trace[356926034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"161.383136ms","start":"2026-06-02T12:21:29.935244Z","end":"2026-06-02T12:21:30.096627Z","steps":["trace[356926034] 'agreement among raft nodes before linearized reading' (duration: 161.195521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:30.097923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.269671ms","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-06-02T12:21:30.098137Z","caller":"traceutil/trace.go:171","msg":"trace[1195943963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"136.505178ms","start":"2026-06-02T12:21:29.961613Z","end":"2026-06-02T12:21:30.098118Z","steps":["trace[1195943963] 'agreement among raft nodes before linearized reading' (duration: 136.151729ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:30.097919Z","caller":"traceutil/trace.go:171","msg":"trace[1333378520] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"142.574143ms","start":"2026-06-02T12:21:29.955281Z","end":"2026-06-02T12:21:30.097855Z","steps":["trace[1333378520] 'process raft request' (duration: 141.30026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:30.098538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.701336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:21:30.098602Z","caller":"traceutil/trace.go:171","msg":"trace[1430244479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"135.821098ms","start":"2026-06-02T12:21:29.962765Z","end":"2026-06-02T12:21:30.098586Z","steps":["trace[1430244479] 'agreement among raft nodes before linearized reading' (duration: 135.406668ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:30.796165Z","caller":"traceutil/trace.go:171","msg":"trace[1771013948] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"211.317058ms","start":"2026-06-02T12:21:30.584807Z","end":"2026-06-02T12:21:30.796124Z","steps":["trace[1771013948] 'process raft request' (duration: 210.368024ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:31.449452Z","caller":"traceutil/trace.go:171","msg":"trace[422911614] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"126.437788ms","start":"2026-06-02T12:21:31.322988Z","end":"2026-06-02T12:21:31.449426Z","steps":["trace[422911614] 'process raft request' (duration: 120.099846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:31.744743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.645452ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:31.744845Z","caller":"traceutil/trace.go:171","msg":"trace[643828266] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:629; }","duration":"138.757756ms","start":"2026-06-02T12:21:31.606072Z","end":"2026-06-02T12:21:31.744829Z","steps":["trace[643828266] 'range keys from in-memory index tree' (duration: 138.621001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:31.744992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.196458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:31.745209Z","caller":"traceutil/trace.go:171","msg":"trace[511037036] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"239.410161ms","start":"2026-06-02T12:21:31.505758Z","end":"2026-06-02T12:21:31.745169Z","steps":["trace[511037036] 'process raft request' (duration: 97.859454ms)","trace[511037036] 'compare' (duration: 140.983533ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:32.785154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.535905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:32.785902Z","caller":"traceutil/trace.go:171","msg":"trace[553814454] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"411.720387ms","start":"2026-06-02T12:21:32.37401Z","end":"2026-06-02T12:21:32.78573Z","steps":["trace[553814454] 'process raft request' (duration: 411.313936ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:32.785975Z","caller":"traceutil/trace.go:171","msg":"trace[835141189] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"412.356733ms","start":"2026-06-02T12:21:32.373573Z","end":"2026-06-02T12:21:32.78593Z","steps":["trace[835141189] 'process raft request' (duration: 411.686026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:32.786016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:32.374003Z","time spent":"411.958463ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:32.785407Z","caller":"traceutil/trace.go:171","msg":"trace[2008627914] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"412.611809ms","start":"2026-06-02T12:21:32.372746Z","end":"2026-06-02T12:21:32.785358Z","steps":["trace[2008627914] 'process raft request' (duration: 272.805838ms)","trace[2008627914] 'compare' (duration: 139.418452ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:32.786192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:32.373565Z","time spent":"412.497337ms","remote":"127.0.0.1:57672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:32.786321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:32.372721Z","time spent":"413.458132ms","remote":"127.0.0.1:57542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T12:21:35.888196Z","caller":"traceutil/trace.go:171","msg":"trace[833293402] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"110.236512ms","start":"2026-06-02T12:21:35.777938Z","end":"2026-06-02T12:21:35.888174Z","steps":["trace[833293402] 'read index received' (duration: 110.024247ms)","trace[833293402] 'applied index is now lower than readState.Index' (duration: 209.075µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:35.888382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.430267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:21:35.888413Z","caller":"traceutil/trace.go:171","msg":"trace[82356261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"110.513079ms","start":"2026-06-02T12:21:35.77789Z","end":"2026-06-02T12:21:35.888403Z","steps":["trace[82356261] 'agreement among raft nodes before linearized reading' (duration: 110.377376ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:35.888393Z","caller":"traceutil/trace.go:171","msg":"trace[558452259] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"142.191543ms","start":"2026-06-02T12:21:35.746167Z","end":"2026-06-02T12:21:35.888358Z","steps":["trace[558452259] 'process raft request' (duration: 141.854184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:36.201025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.356248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:36.201342Z","caller":"traceutil/trace.go:171","msg":"trace[1187479494] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"309.273856ms","start":"2026-06-02T12:21:35.892034Z","end":"2026-06-02T12:21:36.201307Z","steps":["trace[1187479494] 'process raft request' (duration: 192.535587ms)","trace[1187479494] 'compare' (duration: 116.147913ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:36.20164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:35.89201Z","time spent":"309.495761ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:36.746898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:37.24711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:37.442339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23277117s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:21:37.458425Z","caller":"traceutil/trace.go:171","msg":"trace[539277769] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"1.211964505s","start":"2026-06-02T12:21:36.246443Z","end":"2026-06-02T12:21:37.458407Z","steps":["trace[539277769] 'read index received' (duration: 1.196023436s)","trace[539277769] 'applied index is now lower than readState.Index' (duration: 15.940409ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:37.458483Z","caller":"traceutil/trace.go:171","msg":"trace[1057095665] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.253138532s","start":"2026-06-02T12:21:36.205311Z","end":"2026-06-02T12:21:37.458449Z","steps":["trace[1057095665] 'process raft request' (duration: 1.237229454s)","trace[1057095665] 'compare' (duration: 15.364064ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:37.458594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212148979s","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-06-02T12:21:37.458626Z","caller":"traceutil/trace.go:171","msg":"trace[1856260007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"1.212227761s","start":"2026-06-02T12:21:36.246388Z","end":"2026-06-02T12:21:37.458616Z","steps":["trace[1856260007] 'agreement among raft nodes before linearized reading' (duration: 1.212114229s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:37.458625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:36.20529Z","time spent":"1.253254186s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:37.458662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211294247s","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-06-02T12:21:37.458738Z","caller":"traceutil/trace.go:171","msg":"trace[1093108291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"1.211420991s","start":"2026-06-02T12:21:36.2473Z","end":"2026-06-02T12:21:37.458721Z","steps":["trace[1093108291] 'agreement among raft nodes before linearized reading' (duration: 1.211253797s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:37.458793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:36.247271Z","time spent":"1.211508923s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:21:37.458686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.869812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:37.458933Z","caller":"traceutil/trace.go:171","msg":"trace[297584361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"741.14648ms","start":"2026-06-02T12:21:36.717774Z","end":"2026-06-02T12:21:37.45892Z","steps":["trace[297584361] 'agreement among raft nodes before linearized reading' (duration: 740.895613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:37.458978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:36.717752Z","time spent":"741.214661ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:21:37.458648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:36.246367Z","time spent":"1.212276222s","remote":"127.0.0.1:57694","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-06-02T12:21:38.70628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:39.176088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.668216647s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T12:21:39.469026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.641498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:39.4692Z","caller":"traceutil/trace.go:171","msg":"trace[1372016101] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"185.420623ms","start":"2026-06-02T12:21:39.283763Z","end":"2026-06-02T12:21:39.469184Z","steps":["trace[1372016101] 'process raft request' (duration: 185.360801ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:39.46926Z","caller":"traceutil/trace.go:171","msg":"trace[1469722516] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"1.263717956s","start":"2026-06-02T12:21:38.205511Z","end":"2026-06-02T12:21:39.469229Z","steps":["trace[1469722516] 'read index received' (duration: 970.710389ms)","trace[1469722516] 'applied index is now lower than readState.Index' (duration: 293.005547ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:39.469384Z","caller":"traceutil/trace.go:171","msg":"trace[1850730737] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"2.006041276s","start":"2026-06-02T12:21:37.463331Z","end":"2026-06-02T12:21:39.469373Z","steps":["trace[1850730737] 'process raft request' (duration: 1.712973327s)","trace[1850730737] 'compare' (duration: 292.075344ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:39.469484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:37.463322Z","time spent":"2.006088067s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:39.469763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.476006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:39.469844Z","caller":"traceutil/trace.go:171","msg":"trace[1042006613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"746.624141ms","start":"2026-06-02T12:21:38.723198Z","end":"2026-06-02T12:21:39.469822Z","steps":["trace[1042006613] 'agreement among raft nodes before linearized reading' (duration: 746.471577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:39.469884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:38.723139Z","time spent":"746.736914ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:21:39.469928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264420674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:21:39.469971Z","caller":"traceutil/trace.go:171","msg":"trace[1770090716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"1.264500636s","start":"2026-06-02T12:21:38.205457Z","end":"2026-06-02T12:21:39.469958Z","steps":["trace[1770090716] 'agreement among raft nodes before linearized reading' (duration: 1.264125876s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:39.470002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:38.205435Z","time spent":"1.264560587s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:21:40.536027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.520768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:21:40.536231Z","caller":"traceutil/trace.go:171","msg":"trace[2017933572] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"1.024356686s","start":"2026-06-02T12:21:39.51186Z","end":"2026-06-02T12:21:40.536216Z","steps":["trace[2017933572] 'read index received' (duration: 75.596592ms)","trace[2017933572] 'applied index is now lower than readState.Index' (duration: 948.759314ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:40.536316Z","caller":"traceutil/trace.go:171","msg":"trace[295281751] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.06265216s","start":"2026-06-02T12:21:39.473656Z","end":"2026-06-02T12:21:40.536308Z","steps":["trace[295281751] 'process raft request' (duration: 1.062458835s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:40.536374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:39.473634Z","time spent":"1.062711341s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:21:40.536542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024680074s","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-06-02T12:21:40.536576Z","caller":"traceutil/trace.go:171","msg":"trace[424594682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"1.024738826s","start":"2026-06-02T12:21:39.511827Z","end":"2026-06-02T12:21:40.536566Z","steps":["trace[424594682] 'agreement among raft nodes before linearized reading' (duration: 1.024636594s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:40.536601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:39.511814Z","time spent":"1.024781297s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T12:21:40.536649Z","caller":"traceutil/trace.go:171","msg":"trace[2140936797] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.063172862s","start":"2026-06-02T12:21:39.473445Z","end":"2026-06-02T12:21:40.536618Z","steps":["trace[2140936797] 'process raft request' (duration: 113.996598ms)","trace[2140936797] 'compare' (duration: 948.359474ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:40.536782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.6898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-02T12:21:40.536797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:39.473425Z","time spent":"1.063292236s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:21:40.536818Z","caller":"traceutil/trace.go:171","msg":"trace[1682617252] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:657; }","duration":"788.761042ms","start":"2026-06-02T12:21:39.748049Z","end":"2026-06-02T12:21:40.53681Z","steps":["trace[1682617252] 'agreement among raft nodes before linearized reading' (duration: 788.67715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:40.536843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:39.748027Z","time spent":"788.810764ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-06-02T12:21:45.119753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.522158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-06-02T12:21:45.11985Z","caller":"traceutil/trace.go:171","msg":"trace[1543608659] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:723; }","duration":"159.672152ms","start":"2026-06-02T12:21:44.960151Z","end":"2026-06-02T12:21:45.119824Z","steps":["trace[1543608659] 'range keys from in-memory index tree' (duration: 159.255241ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:51.556997Z","caller":"traceutil/trace.go:171","msg":"trace[156214078] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"114.45037ms","start":"2026-06-02T12:21:51.442503Z","end":"2026-06-02T12:21:51.556953Z","steps":["trace[156214078] 'process raft request' (duration: 114.225624ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:51.792274Z","caller":"traceutil/trace.go:171","msg":"trace[1761208942] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"181.027791ms","start":"2026-06-02T12:21:51.611222Z","end":"2026-06-02T12:21:51.79225Z","steps":["trace[1761208942] 'process raft request' (duration: 102.802011ms)","trace[1761208942] 'compare' (duration: 78.048986ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:21:51.792389Z","caller":"traceutil/trace.go:171","msg":"trace[1648964871] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"173.897697ms","start":"2026-06-02T12:21:51.618481Z","end":"2026-06-02T12:21:51.792378Z","steps":["trace[1648964871] 'process raft request' (duration: 173.705952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:56.098947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:56.599686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:57.100279Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.516094218s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T12:21:57.100366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839211738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:21:57.456787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.203731ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T12:21:57.45687Z","caller":"traceutil/trace.go:171","msg":"trace[982894588] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"1.858555206s","start":"2026-06-02T12:21:55.598302Z","end":"2026-06-02T12:21:57.456857Z","steps":["trace[982894588] 'read index received' (duration: 1.502119949s)","trace[982894588] 'applied index is now lower than readState.Index' (duration: 356.434047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:21:57.457047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.858745681s","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-06-02T12:21:57.457085Z","caller":"traceutil/trace.go:171","msg":"trace[984685450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:814; }","duration":"1.858822063s","start":"2026-06-02T12:21:55.598251Z","end":"2026-06-02T12:21:57.457073Z","steps":["trace[984685450] 'agreement among raft nodes before linearized reading' (duration: 1.858672399s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:55.59823Z","time spent":"1.858878345s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T12:21:57.457076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738838941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:57.457263Z","caller":"traceutil/trace.go:171","msg":"trace[1108242080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"1.739083297s","start":"2026-06-02T12:21:55.718166Z","end":"2026-06-02T12:21:57.457249Z","steps":["trace[1108242080] 'agreement among raft nodes before linearized reading' (duration: 1.73881559s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:55.718147Z","time spent":"1.739145719s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:21:57.457409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588197491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9kxrb\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-06-02T12:21:57.457425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.453781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:21:57.457448Z","caller":"traceutil/trace.go:171","msg":"trace[1512542875] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9kxrb; range_end:; response_count:1; response_revision:814; }","duration":"1.588268973s","start":"2026-06-02T12:21:55.869167Z","end":"2026-06-02T12:21:57.457436Z","steps":["trace[1512542875] 'agreement among raft nodes before linearized reading' (duration: 1.588184851s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:55.869146Z","time spent":"1.588322005s","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9kxrb\" "} {"level":"warn","ts":"2026-06-02T12:21:57.457474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.667627ms","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-06-02T12:21:57.457516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.324628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:21:57.457504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.11569ms","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-06-02T12:21:57.457544Z","caller":"traceutil/trace.go:171","msg":"trace[1810535676] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"116.354789ms","start":"2026-06-02T12:21:57.341183Z","end":"2026-06-02T12:21:57.457538Z","steps":["trace[1810535676] 'agreement among raft nodes before linearized reading' (duration: 116.309657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.607904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:21:57.457476Z","caller":"traceutil/trace.go:171","msg":"trace[229720831] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"116.539844ms","start":"2026-06-02T12:21:57.340924Z","end":"2026-06-02T12:21:57.457464Z","steps":["trace[229720831] 'agreement among raft nodes before linearized reading' (duration: 116.448851ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:57.457575Z","caller":"traceutil/trace.go:171","msg":"trace[563475721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"996.339856ms","start":"2026-06-02T12:21:56.461219Z","end":"2026-06-02T12:21:57.457559Z","steps":["trace[563475721] 'agreement among raft nodes before linearized reading' (duration: 996.053449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.4576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.665087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:21:57.457621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:56.461189Z","time spent":"996.421757ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T12:21:57.457634Z","caller":"traceutil/trace.go:171","msg":"trace[635000317] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"116.719508ms","start":"2026-06-02T12:21:57.340904Z","end":"2026-06-02T12:21:57.457624Z","steps":["trace[635000317] 'agreement among raft nodes before linearized reading' (duration: 116.663246ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:21:57.457547Z","caller":"traceutil/trace.go:171","msg":"trace[2096059489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"789.78192ms","start":"2026-06-02T12:21:56.667749Z","end":"2026-06-02T12:21:57.457531Z","steps":["trace[2096059489] 'agreement among raft nodes before linearized reading' (duration: 789.507813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:56.667728Z","time spent":"789.981035ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T12:21:57.457601Z","caller":"traceutil/trace.go:171","msg":"trace[1599478824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"824.746568ms","start":"2026-06-02T12:21:56.632844Z","end":"2026-06-02T12:21:57.45759Z","steps":["trace[1599478824] 'agreement among raft nodes before linearized reading' (duration: 824.537033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.457751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:56.632823Z","time spent":"824.919562ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:21:57.458244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297244596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:21:57.458409Z","caller":"traceutil/trace.go:171","msg":"trace[1480019908] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:814; }","duration":"1.297448931s","start":"2026-06-02T12:21:56.160947Z","end":"2026-06-02T12:21:57.458396Z","steps":["trace[1480019908] 'agreement among raft nodes before linearized reading' (duration: 1.296902046s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.45847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:56.160927Z","time spent":"1.297529814s","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:21:57.458899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.23757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:21:57.458963Z","caller":"traceutil/trace.go:171","msg":"trace[267007340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"758.351512ms","start":"2026-06-02T12:21:56.700598Z","end":"2026-06-02T12:21:57.45895Z","steps":["trace[267007340] 'agreement among raft nodes before linearized reading' (duration: 756.975946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:21:57.459006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:21:56.700556Z","time spent":"758.440925ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:22:32.24094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839212201,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T12:22:32.626895Z","caller":"traceutil/trace.go:171","msg":"trace[1063430965] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"886.948206ms","start":"2026-06-02T12:22:31.739927Z","end":"2026-06-02T12:22:32.626875Z","steps":["trace[1063430965] 'process raft request' (duration: 886.837083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:32.627003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:31.739901Z","time spent":"887.048569ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:22:34.059034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.834489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:22:34.059126Z","caller":"traceutil/trace.go:171","msg":"trace[2094601995] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"2.318284577s","start":"2026-06-02T12:22:31.740828Z","end":"2026-06-02T12:22:34.059112Z","steps":["trace[2094601995] 'read index received' (duration: 885.807147ms)","trace[2094601995] 'applied index is now lower than readState.Index' (duration: 1.43247625s)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:34.059198Z","caller":"traceutil/trace.go:171","msg":"trace[167830842] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.933983394s","start":"2026-06-02T12:22:32.125179Z","end":"2026-06-02T12:22:34.059162Z","steps":["trace[167830842] 'process raft request' (duration: 1.22693445s)","trace[167830842] 'compare' (duration: 706.219453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:22:34.059296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318471132s","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-06-02T12:22:34.059325Z","caller":"traceutil/trace.go:171","msg":"trace[1599342493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"2.318513854s","start":"2026-06-02T12:22:31.740804Z","end":"2026-06-02T12:22:34.059318Z","steps":["trace[1599342493] 'agreement among raft nodes before linearized reading' (duration: 2.31838871s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.059351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:31.740797Z","time spent":"2.318548354s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:22:34.059355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:32.125149Z","time spent":"1.934117087s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:22:34.059529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.858714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:22:34.05956Z","caller":"traceutil/trace.go:171","msg":"trace[1101368157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1028; }","duration":"350.892505ms","start":"2026-06-02T12:22:33.708659Z","end":"2026-06-02T12:22:34.059552Z","steps":["trace[1101368157] 'agreement among raft nodes before linearized reading' (duration: 350.841603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.059515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.316761728s","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-06-02T12:22:34.059583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:33.708615Z","time spent":"350.960056ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-02T12:22:34.059603Z","caller":"traceutil/trace.go:171","msg":"trace[644268696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"2.31686378s","start":"2026-06-02T12:22:31.742724Z","end":"2026-06-02T12:22:34.059587Z","steps":["trace[644268696] 'agreement among raft nodes before linearized reading' (duration: 2.316603754s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.059738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:31.742718Z","time spent":"2.316971173s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T12:22:34.05978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.68534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:22:34.059806Z","caller":"traceutil/trace.go:171","msg":"trace[2084611538] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1028; }","duration":"206.744532ms","start":"2026-06-02T12:22:33.853052Z","end":"2026-06-02T12:22:34.059797Z","steps":["trace[2084611538] 'agreement among raft nodes before linearized reading' (duration: 206.66725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.059798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.589451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:22:34.059843Z","caller":"traceutil/trace.go:171","msg":"trace[46006137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"342.671894ms","start":"2026-06-02T12:22:33.71716Z","end":"2026-06-02T12:22:34.059832Z","steps":["trace[46006137] 'agreement among raft nodes before linearized reading' (duration: 342.602792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.059882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:33.71714Z","time spent":"342.732405ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:22:34.060013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.208409964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:22:34.060085Z","caller":"traceutil/trace.go:171","msg":"trace[918592306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"2.208452305s","start":"2026-06-02T12:22:31.85159Z","end":"2026-06-02T12:22:34.060042Z","steps":["trace[918592306] 'agreement among raft nodes before linearized reading' (duration: 2.20827378s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.060125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:31.851526Z","time spent":"2.208588729s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:22:34.06019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20864324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:22:34.060232Z","caller":"traceutil/trace.go:171","msg":"trace[1277345249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"2.208708831s","start":"2026-06-02T12:22:31.851509Z","end":"2026-06-02T12:22:34.060218Z","steps":["trace[1277345249] 'agreement among raft nodes before linearized reading' (duration: 2.208585248s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.060261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:31.851492Z","time spent":"2.208761803s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T12:22:34.693789Z","caller":"traceutil/trace.go:171","msg":"trace[231789847] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"628.913916ms","start":"2026-06-02T12:22:34.064852Z","end":"2026-06-02T12:22:34.693766Z","steps":["trace[231789847] 'process raft request' (duration: 628.68587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.693928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.064831Z","time spent":"629.022019ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:22:34.69733Z","caller":"traceutil/trace.go:171","msg":"trace[1144340495] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1069; }","duration":"327.771791ms","start":"2026-06-02T12:22:34.369537Z","end":"2026-06-02T12:22:34.697309Z","steps":["trace[1144340495] 'read index received' (duration: 324.25062ms)","trace[1144340495] 'applied index is now lower than readState.Index' (duration: 3.519891ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:34.697673Z","caller":"traceutil/trace.go:171","msg":"trace[811480794] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"631.130773ms","start":"2026-06-02T12:22:34.066467Z","end":"2026-06-02T12:22:34.697597Z","steps":["trace[811480794] 'process raft request' (duration: 630.699792ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:34.697839Z","caller":"traceutil/trace.go:171","msg":"trace[1488919275] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"632.343736ms","start":"2026-06-02T12:22:34.065477Z","end":"2026-06-02T12:22:34.697821Z","steps":["trace[1488919275] 'process raft request' (duration: 631.587217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.697888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.066457Z","time spent":"631.340329ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:22:34.697936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.06546Z","time spent":"632.418967ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:22:34.69795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.387236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:22:34.698017Z","caller":"traceutil/trace.go:171","msg":"trace[1442974626] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1033; }","duration":"328.484318ms","start":"2026-06-02T12:22:34.369513Z","end":"2026-06-02T12:22:34.697997Z","steps":["trace[1442974626] 'agreement among raft nodes before linearized reading' (duration: 328.384546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.698052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.369505Z","time spent":"328.53852ms","remote":"127.0.0.1:57948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-06-02T12:22:34.69818Z","caller":"traceutil/trace.go:171","msg":"trace[1498173027] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"630.763304ms","start":"2026-06-02T12:22:34.067402Z","end":"2026-06-02T12:22:34.698166Z","steps":["trace[1498173027] 'process raft request' (duration: 629.821391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.698255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.06739Z","time spent":"630.825986ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:22:34.697516Z","caller":"traceutil/trace.go:171","msg":"trace[696213695] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"342.91148ms","start":"2026-06-02T12:22:34.354582Z","end":"2026-06-02T12:22:34.697493Z","steps":["trace[696213695] 'process raft request' (duration: 342.683135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:34.698549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:34.35455Z","time spent":"343.921675ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:22:40.98162Z","caller":"traceutil/trace.go:171","msg":"trace[751723830] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"258.48868ms","start":"2026-06-02T12:22:40.723102Z","end":"2026-06-02T12:22:40.981591Z","steps":["trace[751723830] 'process raft request' (duration: 176.217727ms)","trace[751723830] 'compare' (duration: 82.053498ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:40.981877Z","caller":"traceutil/trace.go:171","msg":"trace[284534731] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"258.346657ms","start":"2026-06-02T12:22:40.723504Z","end":"2026-06-02T12:22:40.981851Z","steps":["trace[284534731] 'process raft request' (duration: 258.037459ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:40.981876Z","caller":"traceutil/trace.go:171","msg":"trace[2127249215] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1092; }","duration":"193.050829ms","start":"2026-06-02T12:22:40.788767Z","end":"2026-06-02T12:22:40.981817Z","steps":["trace[2127249215] 'read index received' (duration: 110.55638ms)","trace[2127249215] 'applied index is now lower than readState.Index' (duration: 82.492659ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:22:40.982146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.251628ms","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-06-02T12:22:40.982199Z","caller":"traceutil/trace.go:171","msg":"trace[1322540611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"255.364651ms","start":"2026-06-02T12:22:40.72682Z","end":"2026-06-02T12:22:40.982185Z","steps":["trace[1322540611] 'agreement among raft nodes before linearized reading' (duration: 255.146245ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:40.988879Z","caller":"traceutil/trace.go:171","msg":"trace[687181048] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"195.681418ms","start":"2026-06-02T12:22:40.793181Z","end":"2026-06-02T12:22:40.988862Z","steps":["trace[687181048] 'process raft request' (duration: 195.533584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:51.249829Z","caller":"traceutil/trace.go:171","msg":"trace[1568758968] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"120.449684ms","start":"2026-06-02T12:22:51.129357Z","end":"2026-06-02T12:22:51.249806Z","steps":["trace[1568758968] 'process raft request' (duration: 120.2986ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:51.250011Z","caller":"traceutil/trace.go:171","msg":"trace[1765894723] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"120.500976ms","start":"2026-06-02T12:22:51.129454Z","end":"2026-06-02T12:22:51.249955Z","steps":["trace[1765894723] 'process raft request' (duration: 120.314401ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:57.141123Z","caller":"traceutil/trace.go:171","msg":"trace[278126676] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"104.106904ms","start":"2026-06-02T12:22:57.036995Z","end":"2026-06-02T12:22:57.141102Z","steps":["trace[278126676] 'process raft request' (duration: 103.94462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.443262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.854077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:22:57.443369Z","caller":"traceutil/trace.go:171","msg":"trace[315139401] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"227.467654ms","start":"2026-06-02T12:22:57.215881Z","end":"2026-06-02T12:22:57.443349Z","steps":["trace[315139401] 'read index received' (duration: 9.281288ms)","trace[315139401] 'applied index is now lower than readState.Index' (duration: 218.185126ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:57.443472Z","caller":"traceutil/trace.go:171","msg":"trace[1400438457] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"289.378024ms","start":"2026-06-02T12:22:57.154066Z","end":"2026-06-02T12:22:57.443444Z","steps":["trace[1400438457] 'process raft request' (duration: 71.216649ms)","trace[1400438457] 'compare' (duration: 217.722914ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:22:57.443753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.865704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T12:22:57.443798Z","caller":"traceutil/trace.go:171","msg":"trace[1092156095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"227.945976ms","start":"2026-06-02T12:22:57.21584Z","end":"2026-06-02T12:22:57.443786Z","steps":["trace[1092156095] 'agreement among raft nodes before linearized reading' (duration: 227.761021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.443959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.027911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:22:57.443994Z","caller":"traceutil/trace.go:171","msg":"trace[1319768700] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1103; }","duration":"195.095913ms","start":"2026-06-02T12:22:57.24889Z","end":"2026-06-02T12:22:57.443986Z","steps":["trace[1319768700] 'agreement among raft nodes before linearized reading' (duration: 195.027551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.444234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.212198ms","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-06-02T12:22:57.444287Z","caller":"traceutil/trace.go:171","msg":"trace[1868567010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"169.28595ms","start":"2026-06-02T12:22:57.274985Z","end":"2026-06-02T12:22:57.444271Z","steps":["trace[1868567010] 'agreement among raft nodes before linearized reading' (duration: 169.169007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.444543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.265231ms","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-06-02T12:22:57.444702Z","caller":"traceutil/trace.go:171","msg":"trace[1894799680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"173.495707ms","start":"2026-06-02T12:22:57.271174Z","end":"2026-06-02T12:22:57.444669Z","steps":["trace[1894799680] 'agreement among raft nodes before linearized reading' (duration: 173.019055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.662712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.594491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:22:57.662827Z","caller":"traceutil/trace.go:171","msg":"trace[1716619877] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"214.201773ms","start":"2026-06-02T12:22:57.448606Z","end":"2026-06-02T12:22:57.662808Z","steps":["trace[1716619877] 'process raft request' (duration: 96.434528ms)","trace[1716619877] 'compare' (duration: 117.464557ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:57.713463Z","caller":"traceutil/trace.go:171","msg":"trace[320518771] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"264.042574ms","start":"2026-06-02T12:22:57.449378Z","end":"2026-06-02T12:22:57.71342Z","steps":["trace[320518771] 'process raft request' (duration: 263.726686ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:57.713783Z","caller":"traceutil/trace.go:171","msg":"trace[664812084] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1149; }","duration":"158.273136ms","start":"2026-06-02T12:22:57.555492Z","end":"2026-06-02T12:22:57.713765Z","steps":["trace[664812084] 'read index received' (duration: 157.659371ms)","trace[664812084] 'applied index is now lower than readState.Index' (duration: 611.845µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:57.713971Z","caller":"traceutil/trace.go:171","msg":"trace[1038123788] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"263.843638ms","start":"2026-06-02T12:22:57.450114Z","end":"2026-06-02T12:22:57.713958Z","steps":["trace[1038123788] 'process raft request' (duration: 263.226552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:57.714152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.616375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:22:57.714258Z","caller":"traceutil/trace.go:171","msg":"trace[1297534704] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1106; }","duration":"158.757838ms","start":"2026-06-02T12:22:57.555487Z","end":"2026-06-02T12:22:57.714245Z","steps":["trace[1297534704] 'agreement among raft nodes before linearized reading' (duration: 158.586524ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:22:59.594815Z","caller":"traceutil/trace.go:171","msg":"trace[244705176] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"146.792711ms","start":"2026-06-02T12:22:59.447999Z","end":"2026-06-02T12:22:59.594792Z","steps":["trace[244705176] 'read index received' (duration: 146.73636ms)","trace[244705176] 'applied index is now lower than readState.Index' (duration: 55.311µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:22:59.594916Z","caller":"traceutil/trace.go:171","msg":"trace[539288400] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"445.797924ms","start":"2026-06-02T12:22:59.149082Z","end":"2026-06-02T12:22:59.59488Z","steps":["trace[539288400] 'process raft request' (duration: 445.574879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:22:59.595086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.149065Z","time spent":"445.930827ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:22:59.595101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.067249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T12:22:59.595178Z","caller":"traceutil/trace.go:171","msg":"trace[1029760477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"147.198742ms","start":"2026-06-02T12:22:59.44796Z","end":"2026-06-02T12:22:59.595159Z","steps":["trace[1029760477] 'agreement among raft nodes before linearized reading' (duration: 146.944965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.290977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.6503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:23:00.291061Z","caller":"traceutil/trace.go:171","msg":"trace[1057613596] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"623.807626ms","start":"2026-06-02T12:22:59.66724Z","end":"2026-06-02T12:23:00.291047Z","steps":["trace[1057613596] 'read index received' (duration: 496.013552ms)","trace[1057613596] 'applied index is now lower than readState.Index' (duration: 127.793154ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:00.291112Z","caller":"traceutil/trace.go:171","msg":"trace[666552432] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"691.529826ms","start":"2026-06-02T12:22:59.599574Z","end":"2026-06-02T12:23:00.291104Z","steps":["trace[666552432] 'process raft request' (duration: 563.66875ms)","trace[666552432] 'compare' (duration: 127.360603ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:00.291168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.599547Z","time spent":"691.586397ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:00.291299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.059652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T12:23:00.291367Z","caller":"traceutil/trace.go:171","msg":"trace[544698688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1109; }","duration":"624.170265ms","start":"2026-06-02T12:22:59.667181Z","end":"2026-06-02T12:23:00.291351Z","steps":["trace[544698688] 'agreement among raft nodes before linearized reading' (duration: 623.993071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.291412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.667158Z","time spent":"624.244107ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T12:23:00.326891Z","caller":"traceutil/trace.go:171","msg":"trace[1966285186] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"135.387399ms","start":"2026-06-02T12:23:00.191474Z","end":"2026-06-02T12:23:00.326862Z","steps":["trace[1966285186] 'process raft request' (duration: 135.263646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.327206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.482671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-02T12:23:00.327353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.002261ms","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-06-02T12:23:00.327426Z","caller":"traceutil/trace.go:171","msg":"trace[186787800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1110; }","duration":"596.116754ms","start":"2026-06-02T12:22:59.731291Z","end":"2026-06-02T12:23:00.327408Z","steps":["trace[186787800] 'agreement among raft nodes before linearized reading' (duration: 595.981841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.327499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.731274Z","time spent":"596.214166ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T12:23:00.328209Z","caller":"traceutil/trace.go:171","msg":"trace[1246846145] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1110; }","duration":"577.528857ms","start":"2026-06-02T12:22:59.750662Z","end":"2026-06-02T12:23:00.328191Z","steps":["trace[1246846145] 'agreement among raft nodes before linearized reading' (duration: 576.427349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.328271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.750645Z","time spent":"577.60945ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-06-02T12:23:00.32719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.00572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:00.328588Z","caller":"traceutil/trace.go:171","msg":"trace[1175135425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"609.448737ms","start":"2026-06-02T12:22:59.719121Z","end":"2026-06-02T12:23:00.32857Z","steps":["trace[1175135425] 'agreement among raft nodes before linearized reading' (duration: 607.817185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:00.328647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:22:59.719102Z","time spent":"609.529518ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:02.755616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152481748s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:23:02.756096Z","caller":"traceutil/trace.go:171","msg":"trace[1255351169] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.153066703s","start":"2026-06-02T12:23:01.603015Z","end":"2026-06-02T12:23:02.756081Z","steps":["trace[1255351169] 'process raft request' (duration: 1.152852488s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.756216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:01.602996Z","time spent":"1.153157456s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:02.756463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.25215ms","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-06-02T12:23:02.756483Z","caller":"traceutil/trace.go:171","msg":"trace[1347282132] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"460.86865ms","start":"2026-06-02T12:23:02.295182Z","end":"2026-06-02T12:23:02.756051Z","steps":["trace[1347282132] 'read index received' (duration: 460.663585ms)","trace[1347282132] 'applied index is now lower than readState.Index' (duration: 204.335µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:02.75652Z","caller":"traceutil/trace.go:171","msg":"trace[1018016496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1116; }","duration":"461.360993ms","start":"2026-06-02T12:23:02.295141Z","end":"2026-06-02T12:23:02.756502Z","steps":["trace[1018016496] 'agreement among raft nodes before linearized reading' (duration: 461.157898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.756729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:02.295122Z","time spent":"461.518907ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T12:23:02.77074Z","caller":"traceutil/trace.go:171","msg":"trace[1819199977] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"438.574508ms","start":"2026-06-02T12:23:02.332142Z","end":"2026-06-02T12:23:02.770716Z","steps":["trace[1819199977] 'process raft request' (duration: 438.290621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.770768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.182467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:23:02.770842Z","caller":"traceutil/trace.go:171","msg":"trace[1658195236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1117; }","duration":"410.317751ms","start":"2026-06-02T12:23:02.360504Z","end":"2026-06-02T12:23:02.770821Z","steps":["trace[1658195236] 'agreement among raft nodes before linearized reading' (duration: 410.082165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.770866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.691169ms","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-06-02T12:23:02.770893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:02.360473Z","time spent":"410.411063ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T12:23:02.770919Z","caller":"traceutil/trace.go:171","msg":"trace[1068970806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"385.750261ms","start":"2026-06-02T12:23:02.385156Z","end":"2026-06-02T12:23:02.770906Z","steps":["trace[1068970806] 'agreement among raft nodes before linearized reading' (duration: 385.571856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.770958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:02.385086Z","time spent":"385.864084ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:02.771067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.919995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-02T12:23:02.771062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:02.332124Z","time spent":"438.67082ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:02.77114Z","caller":"traceutil/trace.go:171","msg":"trace[2034362241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"385.980576ms","start":"2026-06-02T12:23:02.385113Z","end":"2026-06-02T12:23:02.771094Z","steps":["trace[2034362241] 'agreement among raft nodes before linearized reading' (duration: 385.892024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:02.771176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:02.385097Z","time spent":"386.072309ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T12:23:02.927317Z","caller":"traceutil/trace.go:171","msg":"trace[1617763709] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"152.259171ms","start":"2026-06-02T12:23:02.775027Z","end":"2026-06-02T12:23:02.927286Z","steps":["trace[1617763709] 'process raft request' (duration: 66.044706ms)","trace[1617763709] 'compare' (duration: 86.00548ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:02.927542Z","caller":"traceutil/trace.go:171","msg":"trace[1616651341] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"152.267781ms","start":"2026-06-02T12:23:02.775244Z","end":"2026-06-02T12:23:02.927511Z","steps":["trace[1616651341] 'process raft request' (duration: 151.985184ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:02.927918Z","caller":"traceutil/trace.go:171","msg":"trace[201674162] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"152.585619ms","start":"2026-06-02T12:23:02.775284Z","end":"2026-06-02T12:23:02.927869Z","steps":["trace[201674162] 'process raft request' (duration: 152.148858ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:05.094974Z","caller":"traceutil/trace.go:171","msg":"trace[716169571] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"331.005843ms","start":"2026-06-02T12:23:04.763941Z","end":"2026-06-02T12:23:05.094947Z","steps":["trace[716169571] 'process raft request' (duration: 330.829819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:05.095126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:04.763929Z","time spent":"331.121886ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:05.108645Z","caller":"traceutil/trace.go:171","msg":"trace[1741310571] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1167; }","duration":"308.516826ms","start":"2026-06-02T12:23:04.800098Z","end":"2026-06-02T12:23:05.108614Z","steps":["trace[1741310571] 'read index received' (duration: 294.792883ms)","trace[1741310571] 'applied index is now lower than readState.Index' (duration: 13.722693ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:05.108757Z","caller":"traceutil/trace.go:171","msg":"trace[1330275182] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"333.006285ms","start":"2026-06-02T12:23:04.775723Z","end":"2026-06-02T12:23:05.108729Z","steps":["trace[1330275182] 'process raft request' (duration: 332.769119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:05.108891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.769643ms","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-06-02T12:23:05.10891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:04.775694Z","time spent":"333.135208ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:05.10902Z","caller":"traceutil/trace.go:171","msg":"trace[1703902731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1123; }","duration":"308.874156ms","start":"2026-06-02T12:23:04.800052Z","end":"2026-06-02T12:23:05.108926Z","steps":["trace[1703902731] 'agreement among raft nodes before linearized reading' (duration: 308.703881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:05.109082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:04.800033Z","time spent":"309.027519ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:23:05.109061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.53651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:05.109259Z","caller":"traceutil/trace.go:171","msg":"trace[1997781852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"177.771316ms","start":"2026-06-02T12:23:04.931468Z","end":"2026-06-02T12:23:05.10924Z","steps":["trace[1997781852] 'agreement among raft nodes before linearized reading' (duration: 177.453528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:05.109281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.177481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:23:05.10934Z","caller":"traceutil/trace.go:171","msg":"trace[1777085552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"177.269353ms","start":"2026-06-02T12:23:04.932057Z","end":"2026-06-02T12:23:05.109326Z","steps":["trace[1777085552] 'agreement among raft nodes before linearized reading' (duration: 177.050607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:05.109486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.722425ms","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-06-02T12:23:05.109557Z","caller":"traceutil/trace.go:171","msg":"trace[1472017105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1123; }","duration":"177.799218ms","start":"2026-06-02T12:23:04.931728Z","end":"2026-06-02T12:23:05.109527Z","steps":["trace[1472017105] 'agreement among raft nodes before linearized reading' (duration: 177.653764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.457189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204466109s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T12:23:06.457291Z","caller":"traceutil/trace.go:171","msg":"trace[1473448670] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1173; }","duration":"1.200334643s","start":"2026-06-02T12:23:05.256937Z","end":"2026-06-02T12:23:06.457271Z","steps":["trace[1473448670] 'read index received' (duration: 36.421µs)","trace[1473448670] 'applied index is now lower than readState.Index' (duration: 1.200296762s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:06.457601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200649062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:06.457652Z","caller":"traceutil/trace.go:171","msg":"trace[2050158355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1127; }","duration":"1.200749964s","start":"2026-06-02T12:23:05.256891Z","end":"2026-06-02T12:23:06.45764Z","steps":["trace[2050158355] 'agreement among raft nodes before linearized reading' (duration: 1.200643652s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.457705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:05.256871Z","time spent":"1.200823517s","remote":"127.0.0.1:46468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:06.827411Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.359339079s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:23:06.828016Z","caller":"traceutil/trace.go:171","msg":"trace[1737808014] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"370.659163ms","start":"2026-06-02T12:23:06.457317Z","end":"2026-06-02T12:23:06.827977Z","steps":["trace[1737808014] 'read index received' (duration: 370.215832ms)","trace[1737808014] 'applied index is now lower than readState.Index' (duration: 442.561µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:06.828043Z","caller":"traceutil/trace.go:171","msg":"trace[1038573604] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"1.360092389s","start":"2026-06-02T12:23:05.467914Z","end":"2026-06-02T12:23:06.828007Z","steps":["trace[1038573604] 'process raft request' (duration: 1.359904134s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.828426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336045051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T12:23:06.828486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:05.467892Z","time spent":"1.360442008s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:06.828525Z","caller":"traceutil/trace.go:171","msg":"trace[2019569164] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1128; }","duration":"1.336188435s","start":"2026-06-02T12:23:05.492304Z","end":"2026-06-02T12:23:06.828493Z","steps":["trace[2019569164] 'agreement among raft nodes before linearized reading' (duration: 1.33599657s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.828829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:05.492283Z","time spent":"1.336504283s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:06.828634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.343356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:06.829077Z","caller":"traceutil/trace.go:171","msg":"trace[217323959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1128; }","duration":"110.762627ms","start":"2026-06-02T12:23:06.718281Z","end":"2026-06-02T12:23:06.829043Z","steps":["trace[217323959] 'agreement among raft nodes before linearized reading' (duration: 110.318736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.829516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.775651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:06.829584Z","caller":"traceutil/trace.go:171","msg":"trace[1850955275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1128; }","duration":"816.874865ms","start":"2026-06-02T12:23:06.01268Z","end":"2026-06-02T12:23:06.829561Z","steps":["trace[1850955275] 'agreement among raft nodes before linearized reading' (duration: 815.403677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:06.829682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:06.012658Z","time spent":"817.006007ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T12:23:09.227067Z","caller":"traceutil/trace.go:171","msg":"trace[316808447] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"107.004879ms","start":"2026-06-02T12:23:09.120033Z","end":"2026-06-02T12:23:09.227038Z","steps":["trace[316808447] 'process raft request' (duration: 106.808934ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:09.342973Z","caller":"traceutil/trace.go:171","msg":"trace[163140873] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"110.813106ms","start":"2026-06-02T12:23:09.232115Z","end":"2026-06-02T12:23:09.342928Z","steps":["trace[163140873] 'process raft request' (duration: 110.637102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:09.386636Z","caller":"traceutil/trace.go:171","msg":"trace[564837783] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"154.082138ms","start":"2026-06-02T12:23:09.232535Z","end":"2026-06-02T12:23:09.386617Z","steps":["trace[564837783] 'process raft request' (duration: 153.952735ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:09.386877Z","caller":"traceutil/trace.go:171","msg":"trace[159026729] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"154.079329ms","start":"2026-06-02T12:23:09.232782Z","end":"2026-06-02T12:23:09.386861Z","steps":["trace[159026729] 'process raft request' (duration: 153.809752ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:09.386928Z","caller":"traceutil/trace.go:171","msg":"trace[763123816] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"153.823202ms","start":"2026-06-02T12:23:09.233082Z","end":"2026-06-02T12:23:09.386906Z","steps":["trace[763123816] 'process raft request' (duration: 153.715009ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:09.961665Z","caller":"traceutil/trace.go:171","msg":"trace[2124993129] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"144.653606ms","start":"2026-06-02T12:23:09.81699Z","end":"2026-06-02T12:23:09.961643Z","steps":["trace[2124993129] 'process raft request' (duration: 73.040926ms)","trace[2124993129] 'compare' (duration: 71.516128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:25.310057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.073942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:23:25.310078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751365503s","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-06-02T12:23:25.310159Z","caller":"traceutil/trace.go:171","msg":"trace[871710491] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1235; }","duration":"1.751501746s","start":"2026-06-02T12:23:23.558636Z","end":"2026-06-02T12:23:25.310138Z","steps":["trace[871710491] 'count revisions from in-memory index tree' (duration: 1.75128191s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.3102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.558615Z","time spent":"1.751573768s","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:25.310231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52666522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:25.31031Z","caller":"traceutil/trace.go:171","msg":"trace[692611582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"1.526751922s","start":"2026-06-02T12:23:23.783538Z","end":"2026-06-02T12:23:25.31029Z","steps":["trace[692611582] 'range keys from in-memory index tree' (duration: 1.526593439s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.310348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.783483Z","time spent":"1.526856905s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:23:25.310491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896878501s","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-06-02T12:23:25.310525Z","caller":"traceutil/trace.go:171","msg":"trace[2079921523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"1.896957363s","start":"2026-06-02T12:23:23.413558Z","end":"2026-06-02T12:23:25.310515Z","steps":["trace[2079921523] 'range keys from in-memory index tree' (duration: 1.896706046s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.31055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.413531Z","time spent":"1.897011824s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:23:25.310625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85168204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:25.310118Z","caller":"traceutil/trace.go:171","msg":"trace[57155941] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1235; }","duration":"717.196105ms","start":"2026-06-02T12:23:24.592908Z","end":"2026-06-02T12:23:25.310104Z","steps":["trace[57155941] 'count revisions from in-memory index tree' (duration: 716.958409ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:25.310663Z","caller":"traceutil/trace.go:171","msg":"trace[113356750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.851728691s","start":"2026-06-02T12:23:23.458925Z","end":"2026-06-02T12:23:25.310653Z","steps":["trace[113356750] 'range keys from in-memory index tree' (duration: 1.851588467s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.310681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85165773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-02T12:23:25.310693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.458921Z","time spent":"1.851765522s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T12:23:25.310703Z","caller":"traceutil/trace.go:171","msg":"trace[1378911747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.85169909s","start":"2026-06-02T12:23:23.458997Z","end":"2026-06-02T12:23:25.310696Z","steps":["trace[1378911747] 'range keys from in-memory index tree' (duration: 1.851572918s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.310699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:24.592888Z","time spent":"717.79825ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:25.310722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.458987Z","time spent":"1.851731421s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:25.310805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857499539s","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-06-02T12:23:25.310817Z","caller":"traceutil/trace.go:171","msg":"trace[1525261713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.85753711s","start":"2026-06-02T12:23:23.453275Z","end":"2026-06-02T12:23:25.310812Z","steps":["trace[1525261713] 'range keys from in-memory index tree' (duration: 1.857432047s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:25.310828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:23.453259Z","time spent":"1.857566351s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T12:23:33.603195Z","caller":"traceutil/trace.go:171","msg":"trace[291412852] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"149.48471ms","start":"2026-06-02T12:23:33.453679Z","end":"2026-06-02T12:23:33.603164Z","steps":["trace[291412852] 'process raft request' (duration: 55.431294ms)","trace[291412852] 'compare' (duration: 93.930033ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:39.557752Z","caller":"traceutil/trace.go:171","msg":"trace[978146395] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"109.093653ms","start":"2026-06-02T12:23:39.448636Z","end":"2026-06-02T12:23:39.55773Z","steps":["trace[978146395] 'read index received' (duration: 108.925969ms)","trace[978146395] 'applied index is now lower than readState.Index' (duration: 166.634µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:39.558008Z","caller":"traceutil/trace.go:171","msg":"trace[116813681] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"158.78398ms","start":"2026-06-02T12:23:39.399212Z","end":"2026-06-02T12:23:39.557996Z","steps":["trace[116813681] 'process raft request' (duration: 158.4059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:39.558287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.635647ms","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-06-02T12:23:39.55833Z","caller":"traceutil/trace.go:171","msg":"trace[1955800477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"109.709779ms","start":"2026-06-02T12:23:39.448611Z","end":"2026-06-02T12:23:39.55832Z","steps":["trace[1955800477] 'agreement among raft nodes before linearized reading' (duration: 109.565785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:39.801325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.917364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:23:39.801547Z","caller":"traceutil/trace.go:171","msg":"trace[1497083372] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1346; }","duration":"141.653569ms","start":"2026-06-02T12:23:39.659876Z","end":"2026-06-02T12:23:39.80153Z","steps":["trace[1497083372] 'read index received' (duration: 82.061099ms)","trace[1497083372] 'applied index is now lower than readState.Index' (duration: 59.59127ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:39.801788Z","caller":"traceutil/trace.go:171","msg":"trace[1123136749] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"239.249047ms","start":"2026-06-02T12:23:39.562525Z","end":"2026-06-02T12:23:39.801774Z","steps":["trace[1123136749] 'process raft request' (duration: 70.80253ms)","trace[1123136749] 'compare' (duration: 167.706769ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:39.801972Z","caller":"traceutil/trace.go:171","msg":"trace[414759403] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"237.661446ms","start":"2026-06-02T12:23:39.564301Z","end":"2026-06-02T12:23:39.801963Z","steps":["trace[414759403] 'process raft request' (duration: 237.140163ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:39.802112Z","caller":"traceutil/trace.go:171","msg":"trace[1679409187] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"234.29936ms","start":"2026-06-02T12:23:39.567804Z","end":"2026-06-02T12:23:39.802103Z","steps":["trace[1679409187] 'process raft request' (duration: 233.694254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:39.802336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.453589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:39.802375Z","caller":"traceutil/trace.go:171","msg":"trace[1694833693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"142.526631ms","start":"2026-06-02T12:23:39.65984Z","end":"2026-06-02T12:23:39.802366Z","steps":["trace[1694833693] 'agreement among raft nodes before linearized reading' (duration: 142.404388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:40.229566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.118258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:23:40.229809Z","caller":"traceutil/trace.go:171","msg":"trace[1307479195] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"352.008663ms","start":"2026-06-02T12:23:39.877782Z","end":"2026-06-02T12:23:40.229791Z","steps":["trace[1307479195] 'process raft request' (duration: 351.948992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:40.229886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:39.877761Z","time spent":"352.090956ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:40.229881Z","caller":"traceutil/trace.go:171","msg":"trace[1868562553] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"421.960431ms","start":"2026-06-02T12:23:39.807888Z","end":"2026-06-02T12:23:40.229848Z","steps":["trace[1868562553] 'process raft request' (duration: 153.484223ms)","trace[1868562553] 'compare' (duration: 268.001846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:40.230032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:39.80786Z","time spent":"422.092953ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:41.548802Z","caller":"traceutil/trace.go:171","msg":"trace[889022058] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"304.361089ms","start":"2026-06-02T12:23:41.244421Z","end":"2026-06-02T12:23:41.548782Z","steps":["trace[889022058] 'process raft request' (duration: 304.229985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:41.548981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.244407Z","time spent":"304.502653ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:41.733698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.055613ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:41.734081Z","caller":"traceutil/trace.go:171","msg":"trace[574200383] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1305; }","duration":"127.427124ms","start":"2026-06-02T12:23:41.606614Z","end":"2026-06-02T12:23:41.734041Z","steps":["trace[574200383] 'range keys from in-memory index tree' (duration: 127.039913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:42.070084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839212868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T12:23:42.084024Z","caller":"traceutil/trace.go:171","msg":"trace[1908380851] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"528.518197ms","start":"2026-06-02T12:23:41.555224Z","end":"2026-06-02T12:23:42.083743Z","steps":["trace[1908380851] 'process raft request' (duration: 528.26898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:42.084163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.555201Z","time spent":"528.896768ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:43.485499Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216684736s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:23:43.526898Z","caller":"traceutil/trace.go:171","msg":"trace[1264221732] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.967110206s","start":"2026-06-02T12:23:41.559764Z","end":"2026-06-02T12:23:43.526874Z","steps":["trace[1264221732] 'process raft request' (duration: 1.925887176s)","trace[1264221732] 'compare' (duration: 40.747077ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:43.527026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.559742Z","time spent":"1.967232729s","remote":"127.0.0.1:57918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:43.527353Z","caller":"traceutil/trace.go:171","msg":"trace[1759363002] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.960303331s","start":"2026-06-02T12:23:41.567038Z","end":"2026-06-02T12:23:43.527341Z","steps":["trace[1759363002] 'process raft request' (duration: 1.960023974s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.567011Z","time spent":"1.960377972s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:43.527418Z","caller":"traceutil/trace.go:171","msg":"trace[631367636] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1361; }","duration":"1.95835395s","start":"2026-06-02T12:23:41.569038Z","end":"2026-06-02T12:23:43.527392Z","steps":["trace[631367636] 'read index received' (duration: 514.22564ms)","trace[631367636] 'applied index is now lower than readState.Index' (duration: 1.44412702s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:43.527646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809472815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:43.527692Z","caller":"traceutil/trace.go:171","msg":"trace[1671656246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"1.809569048s","start":"2026-06-02T12:23:41.718118Z","end":"2026-06-02T12:23:43.527687Z","steps":["trace[1671656246] 'agreement among raft nodes before linearized reading' (duration: 1.809502577s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.718081Z","time spent":"1.80962435s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:23:43.527838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720623753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-02T12:23:43.527829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720041708s","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-06-02T12:23:43.527833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292690439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:43.527882Z","caller":"traceutil/trace.go:171","msg":"trace[2053127304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"1.720142621s","start":"2026-06-02T12:23:41.807725Z","end":"2026-06-02T12:23:43.527868Z","steps":["trace[2053127304] 'agreement among raft nodes before linearized reading' (duration: 1.719984407s)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.527928Z","caller":"traceutil/trace.go:171","msg":"trace[73820766] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.813762225s","start":"2026-06-02T12:23:41.714157Z","end":"2026-06-02T12:23:43.527919Z","steps":["trace[73820766] 'process raft request' (duration: 1.81314273s)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.52789Z","caller":"traceutil/trace.go:171","msg":"trace[2138434182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"1.292804462s","start":"2026-06-02T12:23:42.235071Z","end":"2026-06-02T12:23:43.527876Z","steps":["trace[2138434182] 'agreement among raft nodes before linearized reading' (duration: 1.292634858s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958925814s","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-06-02T12:23:43.527979Z","caller":"traceutil/trace.go:171","msg":"trace[1506833696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"1.958972686s","start":"2026-06-02T12:23:41.569002Z","end":"2026-06-02T12:23:43.527975Z","steps":["trace[1506833696] 'agreement among raft nodes before linearized reading' (duration: 1.958924414s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.527975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:42.235047Z","time spent":"1.292920545s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:43.527993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.568988Z","time spent":"1.959002036s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T12:23:43.52803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.714131Z","time spent":"1.813839668s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:43.527857Z","caller":"traceutil/trace.go:171","msg":"trace[204184821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"1.720680015s","start":"2026-06-02T12:23:41.807173Z","end":"2026-06-02T12:23:43.527853Z","steps":["trace[204184821] 'agreement among raft nodes before linearized reading' (duration: 1.720598633s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.528083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.807147Z","time spent":"1.720926471s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:43.528089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720170722s","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-06-02T12:23:43.528183Z","caller":"traceutil/trace.go:171","msg":"trace[1388833465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"1.720306625s","start":"2026-06-02T12:23:41.807854Z","end":"2026-06-02T12:23:43.52816Z","steps":["trace[1388833465] 'agreement among raft nodes before linearized reading' (duration: 1.720052569s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.528246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.807849Z","time spent":"1.720385047s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:43.527937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.80769Z","time spent":"1.720234873s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T12:23:43.528605Z","caller":"traceutil/trace.go:171","msg":"trace[1997755981] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"1.966428008s","start":"2026-06-02T12:23:41.560685Z","end":"2026-06-02T12:23:43.527113Z","steps":["trace[1997755981] 'process raft request' (duration: 1.966158271s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.528688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:41.560659Z","time spent":"1.967990567s","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:43.705966Z","caller":"traceutil/trace.go:171","msg":"trace[951201775] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"172.591874ms","start":"2026-06-02T12:23:43.533346Z","end":"2026-06-02T12:23:43.705938Z","steps":["trace[951201775] 'process raft request' (duration: 172.241104ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.706031Z","caller":"traceutil/trace.go:171","msg":"trace[1728284255] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"168.221321ms","start":"2026-06-02T12:23:43.537805Z","end":"2026-06-02T12:23:43.706026Z","steps":["trace[1728284255] 'process raft request' (duration: 167.940253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:43.706067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.773611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:43.705972Z","caller":"traceutil/trace.go:171","msg":"trace[1107581806] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1366; }","duration":"160.651998ms","start":"2026-06-02T12:23:43.545273Z","end":"2026-06-02T12:23:43.705925Z","steps":["trace[1107581806] 'read index received' (duration: 12.346428ms)","trace[1107581806] 'applied index is now lower than readState.Index' (duration: 148.30419ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:43.705985Z","caller":"traceutil/trace.go:171","msg":"trace[1721171760] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"167.764269ms","start":"2026-06-02T12:23:43.538188Z","end":"2026-06-02T12:23:43.705953Z","steps":["trace[1721171760] 'process raft request' (duration: 167.615625ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.706139Z","caller":"traceutil/trace.go:171","msg":"trace[1377966687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"160.871134ms","start":"2026-06-02T12:23:43.545252Z","end":"2026-06-02T12:23:43.706123Z","steps":["trace[1377966687] 'agreement among raft nodes before linearized reading' (duration: 160.764601ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:43.706179Z","caller":"traceutil/trace.go:171","msg":"trace[1382784375] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"161.13555ms","start":"2026-06-02T12:23:43.545033Z","end":"2026-06-02T12:23:43.706169Z","steps":["trace[1382784375] 'process raft request' (duration: 160.840171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:46.537971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010714994s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T12:23:46.53913Z","caller":"traceutil/trace.go:171","msg":"trace[914389145] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"977.03101ms","start":"2026-06-02T12:23:45.562064Z","end":"2026-06-02T12:23:46.539095Z","steps":["trace[914389145] 'read index received' (duration: 382.276551ms)","trace[914389145] 'applied index is now lower than readState.Index' (duration: 594.752299ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:46.539173Z","caller":"traceutil/trace.go:171","msg":"trace[1068948687] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"1.005571433s","start":"2026-06-02T12:23:45.533566Z","end":"2026-06-02T12:23:46.539138Z","steps":["trace[1068948687] 'process raft request' (duration: 1.004519977s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:46.539322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.533535Z","time spent":"1.005698937s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:47.148225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430259613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:23:47.148241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426735013s","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-06-02T12:23:47.148307Z","caller":"traceutil/trace.go:171","msg":"trace[1957357877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"1.430369285s","start":"2026-06-02T12:23:45.717917Z","end":"2026-06-02T12:23:47.148287Z","steps":["trace[1957357877] 'agreement among raft nodes before linearized reading' (duration: 821.403041ms)","trace[1957357877] 'range keys from in-memory index tree' (duration: 608.860802ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:47.148322Z","caller":"traceutil/trace.go:171","msg":"trace[1590169037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"1.426857935s","start":"2026-06-02T12:23:45.721441Z","end":"2026-06-02T12:23:47.148299Z","steps":["trace[1590169037] 'agreement among raft nodes before linearized reading' (duration: 817.86989ms)","trace[1590169037] 'range keys from in-memory index tree' (duration: 608.764549ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:47.148346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.717905Z","time spent":"1.430433457s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:23:47.148367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.72143Z","time spent":"1.426928657s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:47.148426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586350533s","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-06-02T12:23:47.148485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437983812s","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-06-02T12:23:47.148497Z","caller":"traceutil/trace.go:171","msg":"trace[1059158925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"1.586459536s","start":"2026-06-02T12:23:45.562017Z","end":"2026-06-02T12:23:47.148476Z","steps":["trace[1059158925] 'agreement among raft nodes before linearized reading' (duration: 977.243855ms)","trace[1059158925] 'range keys from in-memory index tree' (duration: 609.023875ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:47.14853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42704992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:23:47.148536Z","caller":"traceutil/trace.go:171","msg":"trace[668593876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1316; }","duration":"1.438078284s","start":"2026-06-02T12:23:45.710442Z","end":"2026-06-02T12:23:47.148521Z","steps":["trace[668593876] 'agreement among raft nodes before linearized reading' (duration: 828.844153ms)","trace[668593876] 'range keys from in-memory index tree' (duration: 609.099038ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:47.148538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.562001Z","time spent":"1.586528237s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:23:47.148562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.710403Z","time spent":"1.438154816s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T12:23:47.148559Z","caller":"traceutil/trace.go:171","msg":"trace[1313097784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"1.427094422s","start":"2026-06-02T12:23:45.721454Z","end":"2026-06-02T12:23:47.148548Z","steps":["trace[1313097784] 'agreement among raft nodes before linearized reading' (duration: 817.8486ms)","trace[1313097784] 'range keys from in-memory index tree' (duration: 609.17478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:47.148589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.721444Z","time spent":"1.427139493s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:47.148592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437945041s","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-06-02T12:23:47.148627Z","caller":"traceutil/trace.go:171","msg":"trace[188596236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"1.438010462s","start":"2026-06-02T12:23:45.710605Z","end":"2026-06-02T12:23:47.148615Z","steps":["trace[188596236] 'agreement among raft nodes before linearized reading' (duration: 828.72445ms)","trace[188596236] 'range keys from in-memory index tree' (duration: 609.17771ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:47.148655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:45.710573Z","time spent":"1.438075283s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T12:23:47.313595Z","caller":"traceutil/trace.go:171","msg":"trace[1291773893] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"161.304743ms","start":"2026-06-02T12:23:47.15227Z","end":"2026-06-02T12:23:47.313574Z","steps":["trace[1291773893] 'process raft request' (duration: 136.024634ms)","trace[1291773893] 'compare' (duration: 25.148086ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:47.313922Z","caller":"traceutil/trace.go:171","msg":"trace[1706841187] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"160.817141ms","start":"2026-06-02T12:23:47.153095Z","end":"2026-06-02T12:23:47.313912Z","steps":["trace[1706841187] 'process raft request' (duration: 160.449982ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:47.313976Z","caller":"traceutil/trace.go:171","msg":"trace[926873330] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1374; }","duration":"132.056483ms","start":"2026-06-02T12:23:47.181898Z","end":"2026-06-02T12:23:47.313954Z","steps":["trace[926873330] 'read index received' (duration: 106.376263ms)","trace[926873330] 'applied index is now lower than readState.Index' (duration: 25.67907ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:47.314026Z","caller":"traceutil/trace.go:171","msg":"trace[606509429] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"160.839311ms","start":"2026-06-02T12:23:47.153167Z","end":"2026-06-02T12:23:47.314006Z","steps":["trace[606509429] 'process raft request' (duration: 160.705988ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:47.314012Z","caller":"traceutil/trace.go:171","msg":"trace[1493451097] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"160.824281ms","start":"2026-06-02T12:23:47.153166Z","end":"2026-06-02T12:23:47.31399Z","steps":["trace[1493451097] 'process raft request' (duration: 160.605185ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:47.314061Z","caller":"traceutil/trace.go:171","msg":"trace[1960408216] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"160.019591ms","start":"2026-06-02T12:23:47.154026Z","end":"2026-06-02T12:23:47.314045Z","steps":["trace[1960408216] 'process raft request' (duration: 159.884408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:47.314124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.207188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:47.315035Z","caller":"traceutil/trace.go:171","msg":"trace[1530313670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"132.634738ms","start":"2026-06-02T12:23:47.181868Z","end":"2026-06-02T12:23:47.314503Z","steps":["trace[1530313670] 'agreement among raft nodes before linearized reading' (duration: 132.205147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.366708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839212902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T12:23:48.55303Z","caller":"traceutil/trace.go:171","msg":"trace[1560763637] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"688.55783ms","start":"2026-06-02T12:23:47.864395Z","end":"2026-06-02T12:23:48.552952Z","steps":["trace[1560763637] 'process raft request' (duration: 688.450717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.553149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:47.864372Z","time spent":"688.722033ms","remote":"127.0.0.1:57542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T12:23:48.605254Z","caller":"traceutil/trace.go:171","msg":"trace[1070511512] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"739.136968ms","start":"2026-06-02T12:23:47.866089Z","end":"2026-06-02T12:23:48.605226Z","steps":["trace[1070511512] 'read index received' (duration: 686.927567ms)","trace[1070511512] 'applied index is now lower than readState.Index' (duration: 52.208061ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:48.60547Z","caller":"traceutil/trace.go:171","msg":"trace[1048002097] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"737.332972ms","start":"2026-06-02T12:23:47.868108Z","end":"2026-06-02T12:23:48.605441Z","steps":["trace[1048002097] 'process raft request' (duration: 736.901281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.605527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.408825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-02T12:23:48.605578Z","caller":"traceutil/trace.go:171","msg":"trace[7532191] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"739.499127ms","start":"2026-06-02T12:23:47.866064Z","end":"2026-06-02T12:23:48.605563Z","steps":["trace[7532191] 'agreement among raft nodes before linearized reading' (duration: 739.312752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.605589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:47.868097Z","time spent":"737.438926ms","remote":"127.0.0.1:57542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T12:23:48.605628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:47.866055Z","time spent":"739.55961ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-02T12:23:48.776752Z","caller":"traceutil/trace.go:171","msg":"trace[1367407623] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"171.382504ms","start":"2026-06-02T12:23:48.605348Z","end":"2026-06-02T12:23:48.776731Z","steps":["trace[1367407623] 'read index received' (duration: 123.433722ms)","trace[1367407623] 'applied index is now lower than readState.Index' (duration: 47.947012ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:48.776771Z","caller":"traceutil/trace.go:171","msg":"trace[1737031025] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"220.190567ms","start":"2026-06-02T12:23:48.556556Z","end":"2026-06-02T12:23:48.776747Z","steps":["trace[1737031025] 'process raft request' (duration: 172.194013ms)","trace[1737031025] 'compare' (duration: 47.85546ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:48.777034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.015101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:87858"} {"level":"warn","ts":"2026-06-02T12:23:48.776893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.933938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-02T12:23:48.777097Z","caller":"traceutil/trace.go:171","msg":"trace[1482286470] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1329; }","duration":"717.114492ms","start":"2026-06-02T12:23:48.059967Z","end":"2026-06-02T12:23:48.777081Z","steps":["trace[1482286470] 'agreement among raft nodes before linearized reading' (duration: 716.768644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.77714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.059955Z","time spent":"717.177525ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":87881,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-02T12:23:48.777133Z","caller":"traceutil/trace.go:171","msg":"trace[470487504] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1329; }","duration":"717.206145ms","start":"2026-06-02T12:23:48.0599Z","end":"2026-06-02T12:23:48.777106Z","steps":["trace[470487504] 'agreement among raft nodes before linearized reading' (duration: 716.916258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:48.777215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.059879Z","time spent":"717.309357ms","remote":"127.0.0.1:57528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-02T12:23:49.170873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.372798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T12:23:49.170952Z","caller":"traceutil/trace.go:171","msg":"trace[764797950] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"394.056623ms","start":"2026-06-02T12:23:48.776882Z","end":"2026-06-02T12:23:49.170939Z","steps":["trace[764797950] 'read index received' (duration: 251.513451ms)","trace[764797950] 'applied index is now lower than readState.Index' (duration: 142.542292ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:49.171009Z","caller":"traceutil/trace.go:171","msg":"trace[1384370478] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"560.064838ms","start":"2026-06-02T12:23:48.610938Z","end":"2026-06-02T12:23:49.171002Z","steps":["trace[1384370478] 'process raft request' (duration: 417.506415ms)","trace[1384370478] 'compare' (duration: 142.05333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:49.171046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.61091Z","time spent":"560.119889ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:49.171135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.95983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:49.171184Z","caller":"traceutil/trace.go:171","msg":"trace[1083974768] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1330; }","duration":"206.050293ms","start":"2026-06-02T12:23:48.965119Z","end":"2026-06-02T12:23:49.17117Z","steps":["trace[1083974768] 'agreement among raft nodes before linearized reading' (duration: 205.962871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:49.171294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.116313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6181"} {"level":"warn","ts":"2026-06-02T12:23:49.171378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.751773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6181"} {"level":"info","ts":"2026-06-02T12:23:49.171395Z","caller":"traceutil/trace.go:171","msg":"trace[738859899] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1330; }","duration":"391.801085ms","start":"2026-06-02T12:23:48.779589Z","end":"2026-06-02T12:23:49.17139Z","steps":["trace[738859899] 'agreement among raft nodes before linearized reading' (duration: 391.700892ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:49.171376Z","caller":"traceutil/trace.go:171","msg":"trace[1915794990] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1330; }","duration":"392.220446ms","start":"2026-06-02T12:23:48.779134Z","end":"2026-06-02T12:23:49.171355Z","steps":["trace[1915794990] 'agreement among raft nodes before linearized reading' (duration: 391.932008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:49.171406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.685355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T12:23:49.171447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.779573Z","time spent":"391.833916ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6204,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-06-02T12:23:49.17143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.779124Z","time spent":"392.293459ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6204,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-06-02T12:23:49.17147Z","caller":"traceutil/trace.go:171","msg":"trace[333515585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"453.782257ms","start":"2026-06-02T12:23:48.717669Z","end":"2026-06-02T12:23:49.171451Z","steps":["trace[333515585] 'agreement among raft nodes before linearized reading' (duration: 453.696964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:49.171516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:48.717647Z","time spent":"453.856919ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T12:23:51.553535Z","caller":"traceutil/trace.go:171","msg":"trace[657166704] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"161.495299ms","start":"2026-06-02T12:23:51.392015Z","end":"2026-06-02T12:23:51.55351Z","steps":["trace[657166704] 'process raft request' (duration: 161.319855ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:51.558082Z","caller":"traceutil/trace.go:171","msg":"trace[665756465] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"165.238655ms","start":"2026-06-02T12:23:51.392828Z","end":"2026-06-02T12:23:51.558067Z","steps":["trace[665756465] 'process raft request' (duration: 165.128252ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:51.558404Z","caller":"traceutil/trace.go:171","msg":"trace[76321919] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"165.533253ms","start":"2026-06-02T12:23:51.392858Z","end":"2026-06-02T12:23:51.558391Z","steps":["trace[76321919] 'process raft request' (duration: 165.172673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:51.558805Z","caller":"traceutil/trace.go:171","msg":"trace[715354411] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"164.64166ms","start":"2026-06-02T12:23:51.394154Z","end":"2026-06-02T12:23:51.558796Z","steps":["trace[715354411] 'process raft request' (duration: 164.175488ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:51.558799Z","caller":"traceutil/trace.go:171","msg":"trace[2097752519] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"163.213723ms","start":"2026-06-02T12:23:51.395509Z","end":"2026-06-02T12:23:51.558722Z","steps":["trace[2097752519] 'process raft request' (duration: 163.121991ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:53.748418Z","caller":"traceutil/trace.go:171","msg":"trace[1933015659] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"185.763642ms","start":"2026-06-02T12:23:53.562616Z","end":"2026-06-02T12:23:53.748379Z","steps":["trace[1933015659] 'read index received' (duration: 185.511306ms)","trace[1933015659] 'applied index is now lower than readState.Index' (duration: 250.376µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:53.748505Z","caller":"traceutil/trace.go:171","msg":"trace[290570582] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"185.986488ms","start":"2026-06-02T12:23:53.562482Z","end":"2026-06-02T12:23:53.748469Z","steps":["trace[290570582] 'process raft request' (duration: 185.641029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:53.748775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.126431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:23:53.748864Z","caller":"traceutil/trace.go:171","msg":"trace[1175186749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"186.275146ms","start":"2026-06-02T12:23:53.562566Z","end":"2026-06-02T12:23:53.748841Z","steps":["trace[1175186749] 'agreement among raft nodes before linearized reading' (duration: 185.965277ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:53.752054Z","caller":"traceutil/trace.go:171","msg":"trace[1495065802] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"187.689322ms","start":"2026-06-02T12:23:53.564339Z","end":"2026-06-02T12:23:53.752028Z","steps":["trace[1495065802] 'process raft request' (duration: 187.437436ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:53.75216Z","caller":"traceutil/trace.go:171","msg":"trace[2045012617] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"175.840978ms","start":"2026-06-02T12:23:53.576293Z","end":"2026-06-02T12:23:53.752134Z","steps":["trace[2045012617] 'process raft request' (duration: 175.619952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:53.752275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.466468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:53.752415Z","caller":"traceutil/trace.go:171","msg":"trace[1384253476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"189.608962ms","start":"2026-06-02T12:23:53.562759Z","end":"2026-06-02T12:23:53.752368Z","steps":["trace[1384253476] 'agreement among raft nodes before linearized reading' (duration: 189.248072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:53.753222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.290963ms","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-06-02T12:23:53.753287Z","caller":"traceutil/trace.go:171","msg":"trace[1021028677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"189.414456ms","start":"2026-06-02T12:23:53.563859Z","end":"2026-06-02T12:23:53.753273Z","steps":["trace[1021028677] 'agreement among raft nodes before linearized reading' (duration: 189.194471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:55.587921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.446928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5tc82\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-06-02T12:23:55.588122Z","caller":"traceutil/trace.go:171","msg":"trace[87970842] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5tc82; range_end:; response_count:1; response_revision:1379; }","duration":"270.709205ms","start":"2026-06-02T12:23:55.317387Z","end":"2026-06-02T12:23:55.588096Z","steps":["trace[87970842] 'agreement among raft nodes before linearized reading' (duration: 270.323225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:55.587496Z","caller":"traceutil/trace.go:171","msg":"trace[422798697] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"270.012067ms","start":"2026-06-02T12:23:55.317448Z","end":"2026-06-02T12:23:55.58746Z","steps":["trace[422798697] 'read index received' (duration: 195.760619ms)","trace[422798697] 'applied index is now lower than readState.Index' (duration: 74.249648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:23:56.125133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839213006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:23:56.626338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839213006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:23:57.127162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393767839213006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T12:23:57.498617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.897404735s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T12:23:57.499269Z","caller":"traceutil/trace.go:171","msg":"trace[1571411956] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"1.898147083s","start":"2026-06-02T12:23:55.601092Z","end":"2026-06-02T12:23:57.499239Z","steps":["trace[1571411956] 'process raft request' (duration: 1.897972809s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.499503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.601063Z","time spent":"1.898282857s","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13705,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T12:23:57.518272Z","caller":"traceutil/trace.go:171","msg":"trace[86123139] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1438; }","duration":"1.894266154s","start":"2026-06-02T12:23:55.623988Z","end":"2026-06-02T12:23:57.518254Z","steps":["trace[86123139] 'read index received' (duration: 1.875222974s)","trace[86123139] 'applied index is now lower than readState.Index' (duration: 19.0422ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:23:57.518355Z","caller":"traceutil/trace.go:171","msg":"trace[1727905684] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"868.374709ms","start":"2026-06-02T12:23:56.649972Z","end":"2026-06-02T12:23:57.518347Z","steps":["trace[1727905684] 'process raft request' (duration: 868.236406ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:23:57.518403Z","caller":"traceutil/trace.go:171","msg":"trace[1959896764] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"1.424537766s","start":"2026-06-02T12:23:56.093847Z","end":"2026-06-02T12:23:57.518384Z","steps":["trace[1959896764] 'process raft request' (duration: 1.424228378s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.518443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:56.649948Z","time spent":"868.42394ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:57.518549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894555202s","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-06-02T12:23:57.518607Z","caller":"traceutil/trace.go:171","msg":"trace[1690908886] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1382; }","duration":"1.894653694s","start":"2026-06-02T12:23:55.623934Z","end":"2026-06-02T12:23:57.518587Z","steps":["trace[1690908886] 'agreement among raft nodes before linearized reading' (duration: 1.89447649s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.518602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:56.093826Z","time spent":"1.424630029s","remote":"127.0.0.1:57608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6319,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:23:57.518651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.623913Z","time spent":"1.894727906s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T12:23:57.524119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.569794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:23:57.524149Z","caller":"traceutil/trace.go:171","msg":"trace[1857724545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1385; }","duration":"926.639176ms","start":"2026-06-02T12:23:56.597502Z","end":"2026-06-02T12:23:57.524141Z","steps":["trace[1857724545] 'agreement among raft nodes before linearized reading' (duration: 926.575254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:56.597482Z","time spent":"926.678836ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:57.524143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.806200492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:57.52421Z","caller":"traceutil/trace.go:171","msg":"trace[1783634293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"1.806326335s","start":"2026-06-02T12:23:55.717867Z","end":"2026-06-02T12:23:57.524193Z","steps":["trace[1783634293] 'agreement among raft nodes before linearized reading' (duration: 1.806189812s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.717841Z","time spent":"1.806415768s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:23:57.524369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686505947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:23:57.524421Z","caller":"traceutil/trace.go:171","msg":"trace[1251816620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.68660562s","start":"2026-06-02T12:23:55.837803Z","end":"2026-06-02T12:23:57.524408Z","steps":["trace[1251816620] 'agreement among raft nodes before linearized reading' (duration: 1.686451286s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.837781Z","time spent":"1.686666291s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:57.524463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.346665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T12:23:57.524481Z","caller":"traceutil/trace.go:171","msg":"trace[1121983270] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1385; }","duration":"451.403396ms","start":"2026-06-02T12:23:57.073073Z","end":"2026-06-02T12:23:57.524476Z","steps":["trace[1121983270] 'agreement among raft nodes before linearized reading' (duration: 451.369496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:57.073052Z","time spent":"451.445228ms","remote":"127.0.0.1:57890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-06-02T12:23:57.524606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.935782ms","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-06-02T12:23:57.524625Z","caller":"traceutil/trace.go:171","msg":"trace[1389658280] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1385; }","duration":"852.974933ms","start":"2026-06-02T12:23:56.671646Z","end":"2026-06-02T12:23:57.524621Z","steps":["trace[1389658280] 'agreement among raft nodes before linearized reading' (duration: 852.920102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:56.671588Z","time spent":"853.046674ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-02T12:23:57.524686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511832879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:23:57.524774Z","caller":"traceutil/trace.go:171","msg":"trace[589266176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"1.511962032s","start":"2026-06-02T12:23:56.012805Z","end":"2026-06-02T12:23:57.524767Z","steps":["trace[589266176] 'agreement among raft nodes before linearized reading' (duration: 1.511858159s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.524793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:56.012784Z","time spent":"1.512004733s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:23:57.52494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686610309s","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-06-02T12:23:57.524963Z","caller":"traceutil/trace.go:171","msg":"trace[1719600305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.686650981s","start":"2026-06-02T12:23:55.838306Z","end":"2026-06-02T12:23:57.524957Z","steps":["trace[1719600305] 'agreement among raft nodes before linearized reading' (duration: 1.686583769s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.525314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.838297Z","time spent":"1.6870066s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:57.525049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699386498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T12:23:57.525426Z","caller":"traceutil/trace.go:171","msg":"trace[812773581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.699814739s","start":"2026-06-02T12:23:55.825597Z","end":"2026-06-02T12:23:57.525412Z","steps":["trace[812773581] 'agreement among raft nodes before linearized reading' (duration: 1.698883805s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.525491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.825575Z","time spent":"1.69988011s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T12:23:57.525144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.767752815s","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-06-02T12:23:57.525612Z","caller":"traceutil/trace.go:171","msg":"trace[48259580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1385; }","duration":"1.768246618s","start":"2026-06-02T12:23:55.757356Z","end":"2026-06-02T12:23:57.525602Z","steps":["trace[48259580] 'agreement among raft nodes before linearized reading' (duration: 1.767720794s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.525245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772907637s","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-06-02T12:23:57.525703Z","caller":"traceutil/trace.go:171","msg":"trace[1004876210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"1.773409709s","start":"2026-06-02T12:23:55.752282Z","end":"2026-06-02T12:23:57.525692Z","steps":["trace[1004876210] 'agreement among raft nodes before linearized reading' (duration: 1.772865135s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:23:57.525765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.752259Z","time spent":"1.773494922s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T12:23:57.525673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:23:55.757337Z","time spent":"1.768324059s","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T12:25:25.992251Z","caller":"traceutil/trace.go:171","msg":"trace[53307954] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"140.122869ms","start":"2026-06-02T12:25:25.852101Z","end":"2026-06-02T12:25:25.992224Z","steps":["trace[53307954] 'process raft request' (duration: 139.943365ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:25:26.155971Z","caller":"traceutil/trace.go:171","msg":"trace[1807647553] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"104.739883ms","start":"2026-06-02T12:25:26.051205Z","end":"2026-06-02T12:25:26.155945Z","steps":["trace[1807647553] 'read index received' (duration: 89.820141ms)","trace[1807647553] 'applied index is now lower than readState.Index' (duration: 14.918662ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:25:26.156069Z","caller":"traceutil/trace.go:171","msg":"trace[987426915] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"108.086209ms","start":"2026-06-02T12:25:26.047942Z","end":"2026-06-02T12:25:26.156028Z","steps":["trace[987426915] 'process raft request' (duration: 93.089265ms)","trace[987426915] 'compare' (duration: 14.772949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T12:25:26.156283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.066132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T12:25:26.156407Z","caller":"traceutil/trace.go:171","msg":"trace[86652200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1795; }","duration":"105.242757ms","start":"2026-06-02T12:25:26.051152Z","end":"2026-06-02T12:25:26.156395Z","steps":["trace[86652200] 'agreement among raft nodes before linearized reading' (duration: 105.003611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:25:26.156582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.927737ms","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-06-02T12:25:26.156708Z","caller":"traceutil/trace.go:171","msg":"trace[1000500614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"103.05933ms","start":"2026-06-02T12:25:26.053562Z","end":"2026-06-02T12:25:26.156621Z","steps":["trace[1000500614] 'agreement among raft nodes before linearized reading' (duration: 102.65446ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:27:35.031642Z","caller":"traceutil/trace.go:171","msg":"trace[2132490229] linearizableReadLoop","detail":"{readStateIndex:2508; appliedIndex:2507; }","duration":"314.928524ms","start":"2026-06-02T12:27:34.716689Z","end":"2026-06-02T12:27:35.031617Z","steps":["trace[2132490229] 'read index received' (duration: 314.727299ms)","trace[2132490229] 'applied index is now lower than readState.Index' (duration: 200.065µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T12:27:35.031724Z","caller":"traceutil/trace.go:171","msg":"trace[1696154486] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"405.729789ms","start":"2026-06-02T12:27:34.625963Z","end":"2026-06-02T12:27:35.031693Z","steps":["trace[1696154486] 'process raft request' (duration: 405.502214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.031875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.160231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T12:27:35.031968Z","caller":"traceutil/trace.go:171","msg":"trace[323410085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2405; }","duration":"315.297304ms","start":"2026-06-02T12:27:34.716649Z","end":"2026-06-02T12:27:35.031946Z","steps":["trace[323410085] 'agreement among raft nodes before linearized reading' (duration: 315.115649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.032018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:27:34.716633Z","time spent":"315.376826ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T12:27:35.032116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T12:27:34.625947Z","time spent":"405.937214ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T12:27:35.042334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.649365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T12:27:35.04241Z","caller":"traceutil/trace.go:171","msg":"trace[591647785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2405; }","duration":"142.762278ms","start":"2026-06-02T12:27:34.899637Z","end":"2026-06-02T12:27:35.042399Z","steps":["trace[591647785] 'agreement among raft nodes before linearized reading' (duration: 142.589994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.042404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.84483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-02T12:27:35.042506Z","caller":"traceutil/trace.go:171","msg":"trace[1136423713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2405; }","duration":"153.992394ms","start":"2026-06-02T12:27:34.8885Z","end":"2026-06-02T12:27:35.042492Z","steps":["trace[1136423713] 'agreement among raft nodes before linearized reading' (duration: 153.763268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.042484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.403374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T12:27:35.042638Z","caller":"traceutil/trace.go:171","msg":"trace[197103573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2405; }","duration":"141.618499ms","start":"2026-06-02T12:27:34.900996Z","end":"2026-06-02T12:27:35.042614Z","steps":["trace[197103573] 'agreement among raft nodes before linearized reading' (duration: 141.215199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.042697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.450152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T12:27:35.042763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.647486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T12:27:35.042839Z","caller":"traceutil/trace.go:171","msg":"trace[1075740126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2405; }","duration":"146.645057ms","start":"2026-06-02T12:27:34.896175Z","end":"2026-06-02T12:27:35.04282Z","steps":["trace[1075740126] 'agreement among raft nodes before linearized reading' (duration: 146.093303ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:27:35.04284Z","caller":"traceutil/trace.go:171","msg":"trace[773867411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2405; }","duration":"146.738139ms","start":"2026-06-02T12:27:34.89609Z","end":"2026-06-02T12:27:35.042828Z","steps":["trace[773867411] 'agreement among raft nodes before linearized reading' (duration: 146.599165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T12:27:35.043057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.962506ms","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-06-02T12:27:35.043147Z","caller":"traceutil/trace.go:171","msg":"trace[1909129595] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2405; }","duration":"142.091781ms","start":"2026-06-02T12:27:34.90104Z","end":"2026-06-02T12:27:35.043131Z","steps":["trace[1909129595] 'agreement among raft nodes before linearized reading' (duration: 141.851415ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T12:27:42.185769Z","caller":"traceutil/trace.go:171","msg":"trace[257341750] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"128.024142ms","start":"2026-06-02T12:27:42.057718Z","end":"2026-06-02T12:27:42.185742Z","steps":["trace[257341750] 'process raft request' (duration: 127.835668ms)"],"step_count":1}