{"level":"warn","ts":"2026-03-03T21:12:02.892469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T21:12:02.892551Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-03T21:12:02.892626Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T21:12:02.892634Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-03T21:12:02.89265Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T21:12:02.893286Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-03-03T21:12:02.893354Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-03T21:12:02.898986Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.424195ms"} {"level":"info","ts":"2026-03-03T21:12:02.907938Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-03-03T21:12:02.908018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T21:12:02.908045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-03-03T21:12:02.908063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T21:12:02.908077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-03-03T21:12:02.908115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-03-03T21:12:02.914658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T21:12:02.915892Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T21:12:02.917117Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-03T21:12:02.920661Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T21:12:02.922Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T21:12:02.922111Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T21:12:02.922124Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T21:12:02.921835Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T21:12:02.924214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-03-03T21:12:02.925444Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-03T21:12:02.926714Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T21:12:02.926834Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-03T21:12:02.926961Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-03T21:12:02.927318Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T21:12:02.92737Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T21:12:03.508966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T21:12:03.509024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T21:12:03.509048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-03-03T21:12:03.509074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-03-03T21:12:03.509085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-03T21:12:03.509101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-03-03T21:12:03.509114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-03T21:12:03.510058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T21:12:03.510111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T21:12:03.510093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T21:12:03.510413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T21:12:03.510486Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T21:12:03.510318Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:12:03.511677Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:12:03.511809Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:12:03.511877Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T21:12:03.513072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-03-03T21:12:03.514852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T21:12:05.240891Z","caller":"traceutil/trace.go:171","msg":"trace[227015632] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"469.294191ms","start":"2026-03-03T21:12:04.771572Z","end":"2026-03-03T21:12:05.240866Z","steps":["trace[227015632] 'process raft request' (duration: 469.043514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.241884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.771556Z","time spent":"469.420734ms","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:12:05.850074Z","caller":"traceutil/trace.go:171","msg":"trace[1040324574] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.078377981s","start":"2026-03-03T21:12:04.771643Z","end":"2026-03-03T21:12:05.85002Z","steps":["trace[1040324574] 'process raft request' (duration: 1.078108444s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.850236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.771632Z","time spent":"1.078529435s","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:12:05.850335Z","caller":"traceutil/trace.go:171","msg":"trace[623716775] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.00150611s","start":"2026-03-03T21:12:04.848763Z","end":"2026-03-03T21:12:05.850269Z","steps":["trace[623716775] 'process raft request' (duration: 1.00113978s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:05.850403Z","caller":"traceutil/trace.go:171","msg":"trace[2144407428] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"998.304075ms","start":"2026-03-03T21:12:04.85208Z","end":"2026-03-03T21:12:05.850384Z","steps":["trace[2144407428] 'process raft request' (duration: 997.924285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.850564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.852062Z","time spent":"998.409897ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:05.851109Z","caller":"traceutil/trace.go:171","msg":"trace[955071131] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"999.558469ms","start":"2026-03-03T21:12:04.851524Z","end":"2026-03-03T21:12:05.851082Z","steps":["trace[955071131] 'process raft request' (duration: 998.420648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.851256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.85151Z","time spent":"999.668501ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:05.851637Z","caller":"traceutil/trace.go:171","msg":"trace[333891637] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"999.604199ms","start":"2026-03-03T21:12:04.852022Z","end":"2026-03-03T21:12:05.851626Z","steps":["trace[333891637] 'process raft request' (duration: 997.954275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.85169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.851997Z","time spent":"999.671861ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:12:05.851684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.848735Z","time spent":"1.001701165s","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:05.852013Z","caller":"traceutil/trace.go:171","msg":"trace[396652236] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"999.64341ms","start":"2026-03-03T21:12:04.852257Z","end":"2026-03-03T21:12:05.8519Z","steps":["trace[396652236] 'process raft request' (duration: 997.789281ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:05.852025Z","caller":"traceutil/trace.go:171","msg":"trace[1581494727] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"999.919598ms","start":"2026-03-03T21:12:04.852093Z","end":"2026-03-03T21:12:05.852012Z","steps":["trace[1581494727] 'process raft request' (duration: 997.933845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.852082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.852064Z","time spent":"999.9922ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:12:05.852085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.852232Z","time spent":"999.821126ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:05.852192Z","caller":"traceutil/trace.go:171","msg":"trace[613484077] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"986.715566ms","start":"2026-03-03T21:12:04.86545Z","end":"2026-03-03T21:12:05.852165Z","steps":["trace[613484077] 'process raft request' (duration: 984.929798ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:05.852143Z","caller":"traceutil/trace.go:171","msg":"trace[372260413] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"994.322238ms","start":"2026-03-03T21:12:04.857788Z","end":"2026-03-03T21:12:05.85211Z","steps":["trace[372260413] 'process raft request' (duration: 992.299814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.852324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.865441Z","time spent":"986.827428ms","remote":"127.0.0.1:58488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:05.852345Z","caller":"traceutil/trace.go:171","msg":"trace[380527754] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.071309532s","start":"2026-03-03T21:12:04.780924Z","end":"2026-03-03T21:12:05.852234Z","steps":["trace[380527754] 'read index received' (duration: 459.829278ms)","trace[380527754] 'applied index is now lower than readState.Index' (duration: 609.664946ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:12:05.852402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071479067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.852774Z","caller":"traceutil/trace.go:171","msg":"trace[2103016843] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.071884068s","start":"2026-03-03T21:12:04.780879Z","end":"2026-03-03T21:12:05.852763Z","steps":["trace[2103016843] 'agreement among raft nodes before linearized reading' (duration: 1.071385284s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.852856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.780862Z","time spent":"1.07196756s","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-03T21:12:05.85316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.525929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.853235Z","caller":"traceutil/trace.go:171","msg":"trace[101950010] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"107.593091ms","start":"2026-03-03T21:12:05.745595Z","end":"2026-03-03T21:12:05.853189Z","steps":["trace[101950010] 'agreement among raft nodes before linearized reading' (duration: 107.510849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.852901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006659348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:05.853767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.043411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.853924Z","caller":"traceutil/trace.go:171","msg":"trace[214404282] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"931.224755ms","start":"2026-03-03T21:12:04.922689Z","end":"2026-03-03T21:12:05.853914Z","steps":["trace[214404282] 'agreement among raft nodes before linearized reading' (duration: 930.930217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.853874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.468689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.854028Z","caller":"traceutil/trace.go:171","msg":"trace[957901158] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"995.645974ms","start":"2026-03-03T21:12:04.85837Z","end":"2026-03-03T21:12:05.854016Z","steps":["trace[957901158] 'agreement among raft nodes before linearized reading' (duration: 995.475419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.8542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.858365Z","time spent":"995.823159ms","remote":"127.0.0.1:58336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-03T21:12:05.853699Z","caller":"traceutil/trace.go:171","msg":"trace[929389954] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:0; response_revision:11; }","duration":"1.00751708s","start":"2026-03-03T21:12:04.846168Z","end":"2026-03-03T21:12:05.853685Z","steps":["trace[929389954] 'agreement among raft nodes before linearized reading' (duration: 1.006657967s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.855541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.846143Z","time spent":"1.00937907s","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-03-03T21:12:05.854136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.76395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.85578Z","caller":"traceutil/trace.go:171","msg":"trace[1561574214] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"933.410974ms","start":"2026-03-03T21:12:04.92235Z","end":"2026-03-03T21:12:05.855761Z","steps":["trace[1561574214] 'agreement among raft nodes before linearized reading' (duration: 931.76402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.854086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.922673Z","time spent":"931.283287ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T21:12:05.853016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.141116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:05.85309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.341584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:05.85263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.857779Z","time spent":"994.692699ms","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:12:05.854356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.624563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:05.854316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.435385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.856057Z","caller":"traceutil/trace.go:171","msg":"trace[135530176] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"936.228969ms","start":"2026-03-03T21:12:04.919815Z","end":"2026-03-03T21:12:05.856044Z","steps":["trace[135530176] 'agreement among raft nodes before linearized reading' (duration: 933.176528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.856217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.919795Z","time spent":"936.403513ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-03T21:12:05.8564Z","caller":"traceutil/trace.go:171","msg":"trace[774795245] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"949.705498ms","start":"2026-03-03T21:12:04.906684Z","end":"2026-03-03T21:12:05.856389Z","steps":["trace[774795245] 'agreement among raft nodes before linearized reading' (duration: 947.646473ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:05.856419Z","caller":"traceutil/trace.go:171","msg":"trace[1436088547] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"947.603672ms","start":"2026-03-03T21:12:04.9088Z","end":"2026-03-03T21:12:05.856404Z","steps":["trace[1436088547] 'agreement among raft nodes before linearized reading' (duration: 945.474495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.85656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.906664Z","time spent":"949.883493ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-03-03T21:12:05.856571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.908778Z","time spent":"947.733516ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T21:12:05.856079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.922342Z","time spent":"933.722072ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T21:12:05.854396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002911058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:05.857277Z","caller":"traceutil/trace.go:171","msg":"trace[406659918] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.005804276s","start":"2026-03-03T21:12:04.851457Z","end":"2026-03-03T21:12:05.857261Z","steps":["trace[406659918] 'agreement among raft nodes before linearized reading' (duration: 1.002909238s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:05.857361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:04.851445Z","time spent":"1.005900278s","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-03T21:12:05.856124Z","caller":"traceutil/trace.go:171","msg":"trace[1513730803] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"110.392735ms","start":"2026-03-03T21:12:05.745722Z","end":"2026-03-03T21:12:05.856115Z","steps":["trace[1513730803] 'agreement among raft nodes before linearized reading' (duration: 107.347364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.359123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598567557,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:12:06.401694Z","caller":"traceutil/trace.go:171","msg":"trace[1145498247] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"543.460259ms","start":"2026-03-03T21:12:05.858209Z","end":"2026-03-03T21:12:06.401669Z","steps":["trace[1145498247] 'process raft request' (duration: 539.206175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.401829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.858173Z","time spent":"543.592842ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.405689Z","caller":"traceutil/trace.go:171","msg":"trace[1424290128] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"546.87249ms","start":"2026-03-03T21:12:05.85879Z","end":"2026-03-03T21:12:06.405662Z","steps":["trace[1424290128] 'process raft request' (duration: 546.757237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.405835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.85878Z","time spent":"546.984973ms","remote":"127.0.0.1:58662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.405928Z","caller":"traceutil/trace.go:171","msg":"trace[888317425] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"547.61732ms","start":"2026-03-03T21:12:05.858273Z","end":"2026-03-03T21:12:06.405913Z","steps":["trace[888317425] 'read index received' (duration: 539.246696ms)","trace[888317425] 'applied index is now lower than readState.Index' (duration: 8.369684ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:12:06.406029Z","caller":"traceutil/trace.go:171","msg":"trace[1654304281] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"546.311735ms","start":"2026-03-03T21:12:05.859709Z","end":"2026-03-03T21:12:06.406021Z","steps":["trace[1654304281] 'process raft request' (duration: 546.036547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.859701Z","time spent":"546.356026ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406147Z","caller":"traceutil/trace.go:171","msg":"trace[190723575] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"547.061405ms","start":"2026-03-03T21:12:05.859074Z","end":"2026-03-03T21:12:06.406135Z","steps":["trace[190723575] 'process raft request' (duration: 546.611263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.859064Z","time spent":"547.111506ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406199Z","caller":"traceutil/trace.go:171","msg":"trace[1860761714] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"547.016293ms","start":"2026-03-03T21:12:05.859131Z","end":"2026-03-03T21:12:06.406148Z","steps":["trace[1860761714] 'process raft request' (duration: 546.591942ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:06.406354Z","caller":"traceutil/trace.go:171","msg":"trace[187433798] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"545.864083ms","start":"2026-03-03T21:12:05.860477Z","end":"2026-03-03T21:12:06.406341Z","steps":["trace[187433798] 'process raft request' (duration: 545.300568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.860464Z","time spent":"545.922065ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406454Z","caller":"traceutil/trace.go:171","msg":"trace[1999121692] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"547.442855ms","start":"2026-03-03T21:12:05.859004Z","end":"2026-03-03T21:12:06.406447Z","steps":["trace[1999121692] 'process raft request' (duration: 546.649874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.858994Z","time spent":"547.481606ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406559Z","caller":"traceutil/trace.go:171","msg":"trace[1750619327] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"543.431368ms","start":"2026-03-03T21:12:05.863117Z","end":"2026-03-03T21:12:06.406549Z","steps":["trace[1750619327] 'process raft request' (duration: 542.695748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.863109Z","time spent":"543.48358ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406423Z","caller":"traceutil/trace.go:171","msg":"trace[491932164] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"547.482566ms","start":"2026-03-03T21:12:05.85892Z","end":"2026-03-03T21:12:06.406403Z","steps":["trace[491932164] 'process raft request' (duration: 546.705105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.8591Z","time spent":"547.27116ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:12:06.406897Z","caller":"traceutil/trace.go:171","msg":"trace[1581972597] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"542.849813ms","start":"2026-03-03T21:12:05.86403Z","end":"2026-03-03T21:12:06.40688Z","steps":["trace[1581972597] 'process raft request' (duration: 541.815835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.406977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.864012Z","time spent":"542.924185ms","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:12:06.406994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.858902Z","time spent":"547.98032ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:12:06.407209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.926905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-03-03T21:12:06.407324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.390784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:06.407342Z","caller":"traceutil/trace.go:171","msg":"trace[586800746] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"549.026187ms","start":"2026-03-03T21:12:05.858237Z","end":"2026-03-03T21:12:06.407263Z","steps":["trace[586800746] 'agreement among raft nodes before linearized reading' (duration: 548.869353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.407339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.025436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:06.407451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.858218Z","time spent":"549.220173ms","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-03T21:12:06.407476Z","caller":"traceutil/trace.go:171","msg":"trace[1584399079] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"492.207871ms","start":"2026-03-03T21:12:05.915252Z","end":"2026-03-03T21:12:06.40746Z","steps":["trace[1584399079] 'agreement among raft nodes before linearized reading' (duration: 492.033547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.407495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.865232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-03T21:12:06.407521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.915218Z","time spent":"492.294004ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-03-03T21:12:06.407587Z","caller":"traceutil/trace.go:171","msg":"trace[231357705] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"485.951165ms","start":"2026-03-03T21:12:05.921572Z","end":"2026-03-03T21:12:06.407523Z","steps":["trace[231357705] 'agreement among raft nodes before linearized reading' (duration: 485.836252ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:06.407357Z","caller":"traceutil/trace.go:171","msg":"trace[1242315275] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"342.476206ms","start":"2026-03-03T21:12:06.064873Z","end":"2026-03-03T21:12:06.407349Z","steps":["trace[1242315275] 'agreement among raft nodes before linearized reading' (duration: 342.414135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.40768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:06.064851Z","time spent":"342.818856ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-03-03T21:12:06.407641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.921557Z","time spent":"486.077108ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T21:12:06.407239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.533704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-nsktg\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-03-03T21:12:06.407383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.106423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T21:12:06.407864Z","caller":"traceutil/trace.go:171","msg":"trace[303771871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"548.602236ms","start":"2026-03-03T21:12:05.859253Z","end":"2026-03-03T21:12:06.407855Z","steps":["trace[303771871] 'agreement among raft nodes before linearized reading' (duration: 548.118533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.407379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.968083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T21:12:06.407896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.859243Z","time spent":"548.645357ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:12:06.407906Z","caller":"traceutil/trace.go:171","msg":"trace[2017754764] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"547.500117ms","start":"2026-03-03T21:12:05.860389Z","end":"2026-03-03T21:12:06.407889Z","steps":["trace[2017754764] 'agreement among raft nodes before linearized reading' (duration: 546.957182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.407967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.860382Z","time spent":"547.571118ms","remote":"127.0.0.1:58582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-03-03T21:12:06.407863Z","caller":"traceutil/trace.go:171","msg":"trace[823259075] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-nsktg; range_end:; response_count:1; response_revision:20; }","duration":"549.192942ms","start":"2026-03-03T21:12:05.858657Z","end":"2026-03-03T21:12:06.40785Z","steps":["trace[823259075] 'agreement among raft nodes before linearized reading' (duration: 548.530954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:06.408069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:05.858644Z","time spent":"549.416387ms","remote":"127.0.0.1:58488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-nsktg\" "} {"level":"info","ts":"2026-03-03T21:12:33.961109Z","caller":"traceutil/trace.go:171","msg":"trace[1852206793] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"325.471261ms","start":"2026-03-03T21:12:33.635614Z","end":"2026-03-03T21:12:33.961085Z","steps":["trace[1852206793] 'read index received' (duration: 325.286016ms)","trace[1852206793] 'applied index is now lower than readState.Index' (duration: 184.135µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:12:33.961235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.606266ms","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-03-03T21:12:33.96127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.288873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-03T21:12:33.961213Z","caller":"traceutil/trace.go:171","msg":"trace[2102392419] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"620.117589ms","start":"2026-03-03T21:12:33.341037Z","end":"2026-03-03T21:12:33.961155Z","steps":["trace[2102392419] 'process raft request' (duration: 619.897413ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:33.96131Z","caller":"traceutil/trace.go:171","msg":"trace[335800329] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:507; }","duration":"259.357855ms","start":"2026-03-03T21:12:33.701942Z","end":"2026-03-03T21:12:33.9613Z","steps":["trace[335800329] 'agreement among raft nodes before linearized reading' (duration: 259.287283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:33.96133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.51331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:12:33.961347Z","caller":"traceutil/trace.go:171","msg":"trace[672507902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"211.551281ms","start":"2026-03-03T21:12:33.749791Z","end":"2026-03-03T21:12:33.961342Z","steps":["trace[672507902] 'agreement among raft nodes before linearized reading' (duration: 211.52295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:33.961468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:33.341022Z","time spent":"620.314764ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:12:33.961282Z","caller":"traceutil/trace.go:171","msg":"trace[1107641779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"325.691437ms","start":"2026-03-03T21:12:33.63558Z","end":"2026-03-03T21:12:33.961272Z","steps":["trace[1107641779] 'agreement among raft nodes before linearized reading' (duration: 325.602425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:33.961626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:33.635564Z","time spent":"326.032497ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T21:12:34.127507Z","caller":"traceutil/trace.go:171","msg":"trace[2044012543] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"160.695817ms","start":"2026-03-03T21:12:33.966763Z","end":"2026-03-03T21:12:34.127459Z","steps":["trace[2044012543] 'process raft request' (duration: 156.548088ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:34.134333Z","caller":"traceutil/trace.go:171","msg":"trace[1918335829] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"164.777305ms","start":"2026-03-03T21:12:33.969529Z","end":"2026-03-03T21:12:34.134307Z","steps":["trace[1918335829] 'process raft request' (duration: 164.59113ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:40.522663Z","caller":"traceutil/trace.go:171","msg":"trace[2005303378] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"371.891349ms","start":"2026-03-03T21:12:40.150742Z","end":"2026-03-03T21:12:40.522633Z","steps":["trace[2005303378] 'read index received' (duration: 371.685643ms)","trace[2005303378] 'applied index is now lower than readState.Index' (duration: 204.446µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:12:40.522747Z","caller":"traceutil/trace.go:171","msg":"trace[1450806319] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"527.202644ms","start":"2026-03-03T21:12:39.995515Z","end":"2026-03-03T21:12:40.522717Z","steps":["trace[1450806319] 'process raft request' (duration: 526.966048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:40.522898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:39.995501Z","time spent":"527.313247ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:12:40.522911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.129505ms","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-03-03T21:12:40.522961Z","caller":"traceutil/trace.go:171","msg":"trace[1864009787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"372.259618ms","start":"2026-03-03T21:12:40.150688Z","end":"2026-03-03T21:12:40.522947Z","steps":["trace[1864009787] 'agreement among raft nodes before linearized reading' (duration: 372.050693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:40.523021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:40.150667Z","time spent":"372.340441ms","remote":"127.0.0.1:58496","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-03-03T21:12:41.067643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598568566,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:12:41.224419Z","caller":"traceutil/trace.go:171","msg":"trace[1339860338] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"657.292941ms","start":"2026-03-03T21:12:40.567103Z","end":"2026-03-03T21:12:41.224396Z","steps":["trace[1339860338] 'read index received' (duration: 657.082786ms)","trace[1339860338] 'applied index is now lower than readState.Index' (duration: 209.235µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:12:41.224498Z","caller":"traceutil/trace.go:171","msg":"trace[350828143] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"697.512844ms","start":"2026-03-03T21:12:40.52695Z","end":"2026-03-03T21:12:41.224463Z","steps":["trace[350828143] 'process raft request' (duration: 697.307189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:41.224633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:40.526928Z","time spent":"697.633468ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:12:41.224646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.054179ms","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-03-03T21:12:41.224648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.526377ms","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-03-03T21:12:41.224685Z","caller":"traceutil/trace.go:171","msg":"trace[1837288999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"513.144962ms","start":"2026-03-03T21:12:40.71153Z","end":"2026-03-03T21:12:41.224675Z","steps":["trace[1837288999] 'agreement among raft nodes before linearized reading' (duration: 512.995188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:41.224725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:40.711511Z","time spent":"513.201903ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T21:12:41.224728Z","caller":"traceutil/trace.go:171","msg":"trace[127181609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"657.63383ms","start":"2026-03-03T21:12:40.567066Z","end":"2026-03-03T21:12:41.2247Z","steps":["trace[127181609] 'agreement among raft nodes before linearized reading' (duration: 657.423055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:41.22477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:40.567048Z","time spent":"657.714533ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T21:12:42.806217Z","caller":"traceutil/trace.go:171","msg":"trace[1024803572] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"278.705896ms","start":"2026-03-03T21:12:42.527484Z","end":"2026-03-03T21:12:42.80619Z","steps":["trace[1024803572] 'read index received' (duration: 208.367567ms)","trace[1024803572] 'applied index is now lower than readState.Index' (duration: 70.336719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:12:42.806463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.971903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:12:42.806712Z","caller":"traceutil/trace.go:171","msg":"trace[2044679192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"279.2385ms","start":"2026-03-03T21:12:42.52746Z","end":"2026-03-03T21:12:42.806698Z","steps":["trace[2044679192] 'agreement among raft nodes before linearized reading' (duration: 278.837199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:43.739345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598568586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:12:44.240399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598568586,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:12:44.285999Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052294001s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:12:44.286604Z","caller":"traceutil/trace.go:171","msg":"trace[957993582] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"1.047833394s","start":"2026-03-03T21:12:43.238747Z","end":"2026-03-03T21:12:44.286581Z","steps":["trace[957993582] 'read index received' (duration: 1.047396922s)","trace[957993582] 'applied index is now lower than readState.Index' (duration: 435.552µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:12:44.286708Z","caller":"traceutil/trace.go:171","msg":"trace[1701750335] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.053113843s","start":"2026-03-03T21:12:43.233573Z","end":"2026-03-03T21:12:44.286687Z","steps":["trace[1701750335] 'process raft request' (duration: 1.052665471s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:44.286787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048034049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-03T21:12:44.286831Z","caller":"traceutil/trace.go:171","msg":"trace[1855075931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.048133581s","start":"2026-03-03T21:12:43.238688Z","end":"2026-03-03T21:12:44.286821Z","steps":["trace[1855075931] 'agreement among raft nodes before linearized reading' (duration: 1.047987887s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:44.286862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:43.238667Z","time spent":"1.048187143s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:12:44.286865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:43.233546Z","time spent":"1.053208965s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:12:44.286996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047581167s","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-03-03T21:12:44.287116Z","caller":"traceutil/trace.go:171","msg":"trace[1796953992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.04772933s","start":"2026-03-03T21:12:43.239359Z","end":"2026-03-03T21:12:44.287088Z","steps":["trace[1796953992] 'agreement among raft nodes before linearized reading' (duration: 1.047376321s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:44.287182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:43.239351Z","time spent":"1.047816653s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T21:12:45.291759Z","caller":"traceutil/trace.go:171","msg":"trace[1417136288] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.000623035s","start":"2026-03-03T21:12:44.291076Z","end":"2026-03-03T21:12:45.291699Z","steps":["trace[1417136288] 'process raft request' (duration: 911.093109ms)","trace[1417136288] 'compare' (duration: 89.178537ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:12:45.291865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.920716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:12:45.291902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:44.291055Z","time spent":"1.00077616s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:12:45.291913Z","caller":"traceutil/trace.go:171","msg":"trace[92427922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"541.022078ms","start":"2026-03-03T21:12:44.750879Z","end":"2026-03-03T21:12:45.291901Z","steps":["trace[92427922] 'agreement among raft nodes before linearized reading' (duration: 540.936226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:45.291965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:44.750835Z","time spent":"541.119181ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:12:45.291749Z","caller":"traceutil/trace.go:171","msg":"trace[1248444167] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"540.755721ms","start":"2026-03-03T21:12:44.75094Z","end":"2026-03-03T21:12:45.291696Z","steps":["trace[1248444167] 'read index received' (duration: 451.244515ms)","trace[1248444167] 'applied index is now lower than readState.Index' (duration: 89.510236ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:12:45.292358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.42855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:12:45.292395Z","caller":"traceutil/trace.go:171","msg":"trace[1640540869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"465.507093ms","start":"2026-03-03T21:12:44.826877Z","end":"2026-03-03T21:12:45.292384Z","steps":["trace[1640540869] 'agreement among raft nodes before linearized reading' (duration: 465.36967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:45.292426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:44.826856Z","time spent":"465.563484ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T21:12:45.292412Z","caller":"traceutil/trace.go:171","msg":"trace[1141977883] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.000320697s","start":"2026-03-03T21:12:44.291574Z","end":"2026-03-03T21:12:45.291895Z","steps":["trace[1141977883] 'process raft request' (duration: 1.00004503s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:45.292539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:12:44.291555Z","time spent":"1.000910843s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:12:47.344796Z","caller":"traceutil/trace.go:171","msg":"trace[2105709645] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"172.599832ms","start":"2026-03-03T21:12:47.172176Z","end":"2026-03-03T21:12:47.344776Z","steps":["trace[2105709645] 'process raft request' (duration: 172.501479ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:47.348935Z","caller":"traceutil/trace.go:171","msg":"trace[843214529] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"132.848581ms","start":"2026-03-03T21:12:47.21604Z","end":"2026-03-03T21:12:47.348888Z","steps":["trace[843214529] 'read index received' (duration: 128.709302ms)","trace[843214529] 'applied index is now lower than readState.Index' (duration: 4.138519ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:12:47.349Z","caller":"traceutil/trace.go:171","msg":"trace[494741461] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"173.525766ms","start":"2026-03-03T21:12:47.175444Z","end":"2026-03-03T21:12:47.34897Z","steps":["trace[494741461] 'process raft request' (duration: 173.28186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:12:47.349475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.430136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T21:12:47.349644Z","caller":"traceutil/trace.go:171","msg":"trace[1593747586] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:569; }","duration":"133.640632ms","start":"2026-03-03T21:12:47.215986Z","end":"2026-03-03T21:12:47.349627Z","steps":["trace[1593747586] 'agreement among raft nodes before linearized reading' (duration: 133.072077ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:12:56.364998Z","caller":"traceutil/trace.go:171","msg":"trace[541229893] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"244.08138ms","start":"2026-03-03T21:12:56.120897Z","end":"2026-03-03T21:12:56.364978Z","steps":["trace[541229893] 'process raft request' (duration: 243.920246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:01.930495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598568916,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:13:02.36964Z","caller":"traceutil/trace.go:171","msg":"trace[1441168723] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"939.816649ms","start":"2026-03-03T21:13:01.429791Z","end":"2026-03-03T21:13:02.369608Z","steps":["trace[1441168723] 'read index received' (duration: 939.702506ms)","trace[1441168723] 'applied index is now lower than readState.Index' (duration: 112.653µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:02.369782Z","caller":"traceutil/trace.go:171","msg":"trace[1133561511] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"941.928144ms","start":"2026-03-03T21:13:01.427806Z","end":"2026-03-03T21:13:02.369734Z","steps":["trace[1133561511] 'process raft request' (duration: 941.652887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:02.369915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.104586ms","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-03-03T21:13:02.369982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:01.427764Z","time spent":"942.114709ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:02.369987Z","caller":"traceutil/trace.go:171","msg":"trace[1707977808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"940.240769ms","start":"2026-03-03T21:13:01.429725Z","end":"2026-03-03T21:13:02.369965Z","steps":["trace[1707977808] 'agreement among raft nodes before linearized reading' (duration: 940.029083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:02.370044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:01.429696Z","time spent":"940.338682ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:13:03.05461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.997653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:13:03.054686Z","caller":"traceutil/trace.go:171","msg":"trace[1712526645] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"684.966283ms","start":"2026-03-03T21:13:02.369707Z","end":"2026-03-03T21:13:03.054673Z","steps":["trace[1712526645] 'read index received' (duration: 133.809737ms)","trace[1712526645] 'applied index is now lower than readState.Index' (duration: 551.155356ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:03.05476Z","caller":"traceutil/trace.go:171","msg":"trace[338440973] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"939.166591ms","start":"2026-03-03T21:13:02.115574Z","end":"2026-03-03T21:13:03.054741Z","steps":["trace[338440973] 'process raft request' (duration: 387.85655ms)","trace[338440973] 'compare' (duration: 550.705125ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:03.054771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304555638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:13:03.054843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622255075s","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-03-03T21:13:03.054853Z","caller":"traceutil/trace.go:171","msg":"trace[500845400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"1.304691932s","start":"2026-03-03T21:13:01.750146Z","end":"2026-03-03T21:13:03.054838Z","steps":["trace[500845400] 'agreement among raft nodes before linearized reading' (duration: 1.304577519s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:03.054869Z","caller":"traceutil/trace.go:171","msg":"trace[1641987566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"1.622304806s","start":"2026-03-03T21:13:01.432557Z","end":"2026-03-03T21:13:03.054862Z","steps":["trace[1641987566] 'agreement among raft nodes before linearized reading' (duration: 1.622167123s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.054882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:02.115543Z","time spent":"939.283484ms","remote":"127.0.0.1:58510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1453,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:03.054896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:01.432544Z","time spent":"1.622346267s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:13:03.0549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:01.75012Z","time spent":"1.304769354s","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:13:03.543457Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039975624s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:13:03.636642Z","caller":"traceutil/trace.go:171","msg":"trace[1277003861] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"1.262572777s","start":"2026-03-03T21:13:02.374013Z","end":"2026-03-03T21:13:03.636586Z","steps":["trace[1277003861] 'process raft request' (duration: 1.169619241s)","trace[1277003861] 'compare' (duration: 92.616448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:03.636892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:02.373989Z","time spent":"1.262782253s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:03.639751Z","caller":"traceutil/trace.go:171","msg":"trace[53491298] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:737; }","duration":"585.00188ms","start":"2026-03-03T21:13:03.054702Z","end":"2026-03-03T21:13:03.639704Z","steps":["trace[53491298] 'read index received' (duration: 488.941151ms)","trace[53491298] 'applied index is now lower than readState.Index' (duration: 96.059439ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:03.639904Z","caller":"traceutil/trace.go:171","msg":"trace[324829261] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"593.30743ms","start":"2026-03-03T21:13:03.046586Z","end":"2026-03-03T21:13:03.639893Z","steps":["trace[324829261] 'process raft request' (duration: 592.971571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.640017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:03.046565Z","time spent":"593.376581ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T21:13:03.640548Z","caller":"traceutil/trace.go:171","msg":"trace[980418607] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"582.178425ms","start":"2026-03-03T21:13:03.058359Z","end":"2026-03-03T21:13:03.640538Z","steps":["trace[980418607] 'process raft request' (duration: 581.288992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.640705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.852596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:03.640794Z","caller":"traceutil/trace.go:171","msg":"trace[1672734251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"584.951198ms","start":"2026-03-03T21:13:03.055821Z","end":"2026-03-03T21:13:03.640773Z","steps":["trace[1672734251] 'agreement among raft nodes before linearized reading' (duration: 584.815485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.640844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:03.055797Z","time spent":"585.039261ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:13:03.640878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.819004ms","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-03-03T21:13:03.640951Z","caller":"traceutil/trace.go:171","msg":"trace[60267821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"797.948209ms","start":"2026-03-03T21:13:02.842986Z","end":"2026-03-03T21:13:03.640934Z","steps":["trace[60267821] 'agreement among raft nodes before linearized reading' (duration: 797.751953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.640992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:02.842919Z","time spent":"798.063251ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T21:13:03.641086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.516538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5s88m\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-03-03T21:13:03.64113Z","caller":"traceutil/trace.go:171","msg":"trace[1819747887] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5s88m; range_end:; response_count:1; response_revision:712; }","duration":"595.60569ms","start":"2026-03-03T21:13:03.045513Z","end":"2026-03-03T21:13:03.641119Z","steps":["trace[1819747887] 'agreement among raft nodes before linearized reading' (duration: 595.476087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.641165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:03.045482Z","time spent":"595.673802ms","remote":"127.0.0.1:58428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4908,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5s88m\" "} {"level":"warn","ts":"2026-03-03T21:13:03.641158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236505279s","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-03-03T21:13:03.641215Z","caller":"traceutil/trace.go:171","msg":"trace[83766087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"1.236613282s","start":"2026-03-03T21:13:02.404588Z","end":"2026-03-03T21:13:03.641201Z","steps":["trace[83766087] 'agreement among raft nodes before linearized reading' (duration: 1.236426207s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:03.64121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:03.058344Z","time spent":"582.238607ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:03.641254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:02.404562Z","time spent":"1.236684134s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T21:13:09.714668Z","caller":"traceutil/trace.go:171","msg":"trace[919463800] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"100.040474ms","start":"2026-03-03T21:13:09.614598Z","end":"2026-03-03T21:13:09.714638Z","steps":["trace[919463800] 'process raft request' (duration: 99.551661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:10.379336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.958812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:13:10.379443Z","caller":"traceutil/trace.go:171","msg":"trace[750691749] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"659.579941ms","start":"2026-03-03T21:13:09.719839Z","end":"2026-03-03T21:13:10.379419Z","steps":["trace[750691749] 'process raft request' (duration: 548.462295ms)","trace[750691749] 'compare' (duration: 110.727356ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:10.379499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.719812Z","time spent":"659.663394ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:10.557188Z","caller":"traceutil/trace.go:171","msg":"trace[55264836] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"835.869671ms","start":"2026-03-03T21:13:09.721289Z","end":"2026-03-03T21:13:10.557159Z","steps":["trace[55264836] 'process raft request' (duration: 807.45455ms)","trace[55264836] 'compare' (duration: 28.150124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:10.557404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.721263Z","time spent":"836.025655ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:10.557583Z","caller":"traceutil/trace.go:171","msg":"trace[1197093601] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"835.865641ms","start":"2026-03-03T21:13:09.721681Z","end":"2026-03-03T21:13:10.557547Z","steps":["trace[1197093601] 'process raft request' (duration: 835.394539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:10.557819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.721503Z","time spent":"836.19581ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:10.557889Z","caller":"traceutil/trace.go:171","msg":"trace[13694950] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"834.040783ms","start":"2026-03-03T21:13:09.723774Z","end":"2026-03-03T21:13:10.557815Z","steps":["trace[13694950] 'process raft request' (duration: 833.602212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:10.558006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.987516Z","time spent":"570.482407ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-03T21:13:10.558013Z","caller":"traceutil/trace.go:171","msg":"trace[419518952] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:758; }","duration":"578.285394ms","start":"2026-03-03T21:13:09.979661Z","end":"2026-03-03T21:13:10.557947Z","steps":["trace[419518952] 'read index received' (duration: 288.611508ms)","trace[419518952] 'applied index is now lower than readState.Index' (duration: 289.672136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:10.558099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.723755Z","time spent":"834.237838ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:10.558218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.5342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:10.558277Z","caller":"traceutil/trace.go:171","msg":"trace[1297226074] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:732; }","duration":"578.653723ms","start":"2026-03-03T21:13:09.979609Z","end":"2026-03-03T21:13:10.558263Z","steps":["trace[1297226074] 'agreement among raft nodes before linearized reading' (duration: 578.53041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:10.558322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:09.97959Z","time spent":"578.722134ms","remote":"127.0.0.1:58534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:13:10.616311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.772535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:13:10.616383Z","caller":"traceutil/trace.go:171","msg":"trace[1709794146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"183.892319ms","start":"2026-03-03T21:13:10.432473Z","end":"2026-03-03T21:13:10.616365Z","steps":["trace[1709794146] 'agreement among raft nodes before linearized reading' (duration: 183.679363ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:10.616319Z","caller":"traceutil/trace.go:171","msg":"trace[2107432312] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"216.268344ms","start":"2026-03-03T21:13:10.399977Z","end":"2026-03-03T21:13:10.616245Z","steps":["trace[2107432312] 'process raft request' (duration: 216.021578ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:10.732593Z","caller":"traceutil/trace.go:171","msg":"trace[1769300250] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"111.867977ms","start":"2026-03-03T21:13:10.620705Z","end":"2026-03-03T21:13:10.732573Z","steps":["trace[1769300250] 'process raft request' (duration: 111.703333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.140915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569139,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T21:13:29.300516Z","caller":"traceutil/trace.go:171","msg":"trace[452970986] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"659.85859ms","start":"2026-03-03T21:13:28.640629Z","end":"2026-03-03T21:13:29.300488Z","steps":["trace[452970986] 'read index received' (duration: 659.625394ms)","trace[452970986] 'applied index is now lower than readState.Index' (duration: 231.806µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:29.300613Z","caller":"traceutil/trace.go:171","msg":"trace[823389029] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"822.781204ms","start":"2026-03-03T21:13:28.477796Z","end":"2026-03-03T21:13:29.300577Z","steps":["trace[823389029] 'process raft request' (duration: 822.454826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.300833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.187939ms","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-03-03T21:13:29.300931Z","caller":"traceutil/trace.go:171","msg":"trace[1651679728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"660.347353ms","start":"2026-03-03T21:13:28.640559Z","end":"2026-03-03T21:13:29.300907Z","steps":["trace[1651679728] 'agreement among raft nodes before linearized reading' (duration: 660.040365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.300905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.960858ms","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-03-03T21:13:29.30096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.321134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:13:29.300988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.640531Z","time spent":"660.446516ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T21:13:29.300964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.227957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:13:29.301002Z","caller":"traceutil/trace.go:171","msg":"trace[2119686579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"656.099151ms","start":"2026-03-03T21:13:28.644882Z","end":"2026-03-03T21:13:29.300981Z","steps":["trace[2119686579] 'agreement among raft nodes before linearized reading' (duration: 655.799963ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:29.301012Z","caller":"traceutil/trace.go:171","msg":"trace[1048560974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"550.424417ms","start":"2026-03-03T21:13:28.750575Z","end":"2026-03-03T21:13:29.300999Z","steps":["trace[1048560974] 'agreement among raft nodes before linearized reading' (duration: 550.307853ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:29.301049Z","caller":"traceutil/trace.go:171","msg":"trace[631084162] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:786; }","duration":"126.382361ms","start":"2026-03-03T21:13:29.174644Z","end":"2026-03-03T21:13:29.301027Z","steps":["trace[631084162] 'agreement among raft nodes before linearized reading' (duration: 126.210207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.301057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.644841Z","time spent":"656.206774ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:13:29.301059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.750558Z","time spent":"550.491758ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:13:29.301131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.563021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-03T21:13:29.301185Z","caller":"traceutil/trace.go:171","msg":"trace[1463802475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"654.630053ms","start":"2026-03-03T21:13:28.646539Z","end":"2026-03-03T21:13:29.301169Z","steps":["trace[1463802475] 'agreement among raft nodes before linearized reading' (duration: 654.374136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.301153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.806604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-03T21:13:29.301219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.646532Z","time spent":"654.679714ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T21:13:29.301288Z","caller":"traceutil/trace.go:171","msg":"trace[1805125819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"485.992438ms","start":"2026-03-03T21:13:28.815263Z","end":"2026-03-03T21:13:29.301256Z","steps":["trace[1805125819] 'agreement among raft nodes before linearized reading' (duration: 485.581477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.301345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.81525Z","time spent":"486.086131ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T21:13:29.300863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:28.477765Z","time spent":"822.951609ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:29.90472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.256169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:13:29.905032Z","caller":"traceutil/trace.go:171","msg":"trace[2054491386] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:821; }","duration":"153.885006ms","start":"2026-03-03T21:13:29.751115Z","end":"2026-03-03T21:13:29.905Z","steps":["trace[2054491386] 'read index received' (duration: 146.525332ms)","trace[2054491386] 'applied index is now lower than readState.Index' (duration: 7.358494ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:29.905126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.016819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:29.905154Z","caller":"traceutil/trace.go:171","msg":"trace[172676568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"154.093051ms","start":"2026-03-03T21:13:29.751051Z","end":"2026-03-03T21:13:29.905144Z","steps":["trace[172676568] 'agreement among raft nodes before linearized reading' (duration: 154.026069ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:29.905173Z","caller":"traceutil/trace.go:171","msg":"trace[104909700] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"598.261587ms","start":"2026-03-03T21:13:29.306889Z","end":"2026-03-03T21:13:29.905151Z","steps":["trace[104909700] 'process raft request' (duration: 598.044431ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:13:29.905173Z","caller":"traceutil/trace.go:171","msg":"trace[1145295416] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"599.269534ms","start":"2026-03-03T21:13:29.305872Z","end":"2026-03-03T21:13:29.905141Z","steps":["trace[1145295416] 'process raft request' (duration: 598.968556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.905261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:29.306876Z","time spent":"598.33984ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:29.905371Z","caller":"traceutil/trace.go:171","msg":"trace[2044801379] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"598.466822ms","start":"2026-03-03T21:13:29.306893Z","end":"2026-03-03T21:13:29.90536Z","steps":["trace[2044801379] 'process raft request' (duration: 598.071122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:29.90537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:29.30584Z","time spent":"599.412538ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:29.905431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:29.30686Z","time spent":"598.538434ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:29.905402Z","caller":"traceutil/trace.go:171","msg":"trace[1902427927] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"599.598943ms","start":"2026-03-03T21:13:29.305771Z","end":"2026-03-03T21:13:29.90537Z","steps":["trace[1902427927] 'process raft request' (duration: 212.576213ms)","trace[1902427927] 'compare' (duration: 385.671684ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:13:29.905557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:29.305751Z","time spent":"599.728365ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:31.945584Z","caller":"traceutil/trace.go:171","msg":"trace[1419895628] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"190.010308ms","start":"2026-03-03T21:13:31.755548Z","end":"2026-03-03T21:13:31.945558Z","steps":["trace[1419895628] 'read index received' (duration: 189.805593ms)","trace[1419895628] 'applied index is now lower than readState.Index' (duration: 203.765µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:31.945729Z","caller":"traceutil/trace.go:171","msg":"trace[1352720607] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"635.477878ms","start":"2026-03-03T21:13:31.31024Z","end":"2026-03-03T21:13:31.945718Z","steps":["trace[1352720607] 'process raft request' (duration: 635.109129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:31.945846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:31.310221Z","time spent":"635.535219ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:13:31.946046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.48853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:31.946202Z","caller":"traceutil/trace.go:171","msg":"trace[1776668041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"190.629154ms","start":"2026-03-03T21:13:31.755473Z","end":"2026-03-03T21:13:31.946102Z","steps":["trace[1776668041] 'agreement among raft nodes before linearized reading' (duration: 190.530202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:46.524969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:13:47.025359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:13:47.101589Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078953276s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:13:47.102065Z","caller":"traceutil/trace.go:171","msg":"trace[1424473443] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"1.077635311s","start":"2026-03-03T21:13:46.024405Z","end":"2026-03-03T21:13:47.102041Z","steps":["trace[1424473443] 'read index received' (duration: 1.077375784s)","trace[1424473443] 'applied index is now lower than readState.Index' (duration: 258.367µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:13:47.102166Z","caller":"traceutil/trace.go:171","msg":"trace[1154099312] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.079636593s","start":"2026-03-03T21:13:46.022519Z","end":"2026-03-03T21:13:47.102156Z","steps":["trace[1154099312] 'process raft request' (duration: 1.079332655s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.448481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:47.1024Z","caller":"traceutil/trace.go:171","msg":"trace[813862969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"357.553574ms","start":"2026-03-03T21:13:46.744834Z","end":"2026-03-03T21:13:47.102387Z","steps":["trace[813862969] 'agreement among raft nodes before linearized reading' (duration: 357.474822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.744809Z","time spent":"357.619555ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:13:47.102491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052997651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T21:13:47.10259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.718186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:13:47.102496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017809664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-03T21:13:47.102593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.979765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:13:47.102647Z","caller":"traceutil/trace.go:171","msg":"trace[887143776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"1.017992948s","start":"2026-03-03T21:13:46.084642Z","end":"2026-03-03T21:13:47.102635Z","steps":["trace[887143776] 'agreement among raft nodes before linearized reading' (duration: 1.01765726s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078206216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-03T21:13:47.102693Z","caller":"traceutil/trace.go:171","msg":"trace[182151050] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:882; }","duration":"846.152609ms","start":"2026-03-03T21:13:46.256519Z","end":"2026-03-03T21:13:47.102671Z","steps":["trace[182151050] 'agreement among raft nodes before linearized reading' (duration: 845.985215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045848663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-03T21:13:47.102749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.256494Z","time spent":"846.243962ms","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-03-03T21:13:47.102751Z","caller":"traceutil/trace.go:171","msg":"trace[2068131093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"1.078332229s","start":"2026-03-03T21:13:46.024394Z","end":"2026-03-03T21:13:47.102726Z","steps":["trace[2068131093] 'agreement among raft nodes before linearized reading' (duration: 1.07800962s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.024389Z","time spent":"1.078412731s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T21:13:47.102593Z","caller":"traceutil/trace.go:171","msg":"trace[2013763521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"1.053114863s","start":"2026-03-03T21:13:46.049445Z","end":"2026-03-03T21:13:47.10256Z","steps":["trace[2013763521] 'agreement among raft nodes before linearized reading' (duration: 1.052821596s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.04944Z","time spent":"1.053414832s","remote":"127.0.0.1:58496","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-03-03T21:13:47.102772Z","caller":"traceutil/trace.go:171","msg":"trace[466511521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"1.045966766s","start":"2026-03-03T21:13:46.056789Z","end":"2026-03-03T21:13:47.102756Z","steps":["trace[466511521] 'agreement among raft nodes before linearized reading' (duration: 1.045667408s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.056784Z","time spent":"1.04614709s","remote":"127.0.0.1:58496","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-03-03T21:13:47.102649Z","caller":"traceutil/trace.go:171","msg":"trace[858934730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"352.796898ms","start":"2026-03-03T21:13:46.749835Z","end":"2026-03-03T21:13:47.102631Z","steps":["trace[858934730] 'agreement among raft nodes before linearized reading' (duration: 352.717986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:13:47.102709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.084575Z","time spent":"1.018112732s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:13:47.103065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.749821Z","time spent":"353.235149ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:13:47.103096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:13:46.022489Z","time spent":"1.079714725s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:13:59.073981Z","caller":"traceutil/trace.go:171","msg":"trace[1542921176] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"162.201805ms","start":"2026-03-03T21:13:58.911754Z","end":"2026-03-03T21:13:59.073956Z","steps":["trace[1542921176] 'process raft request' (duration: 162.048391ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:03.659749Z","caller":"traceutil/trace.go:171","msg":"trace[464573019] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"421.699623ms","start":"2026-03-03T21:14:03.23801Z","end":"2026-03-03T21:14:03.659709Z","steps":["trace[464573019] 'process raft request' (duration: 421.385165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:03.659906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.236312Z","time spent":"423.518072ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:04.707905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.048383939s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:14:04.746637Z","caller":"traceutil/trace.go:171","msg":"trace[1613599895] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:990; }","duration":"1.507839379s","start":"2026-03-03T21:14:03.238767Z","end":"2026-03-03T21:14:04.746607Z","steps":["trace[1613599895] 'read index received' (duration: 420.79531ms)","trace[1613599895] 'applied index is now lower than readState.Index' (duration: 1.087042169s)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:04.746746Z","caller":"traceutil/trace.go:171","msg":"trace[905867150] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.508313211s","start":"2026-03-03T21:14:03.238408Z","end":"2026-03-03T21:14:04.746721Z","steps":["trace[905867150] 'process raft request' (duration: 1.469642211s)","trace[905867150] 'compare' (duration: 38.052783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:04.746866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.238326Z","time spent":"1.508468935s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:04.746891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508110775s","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-03-03T21:14:04.746927Z","caller":"traceutil/trace.go:171","msg":"trace[1107991826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"1.508164887s","start":"2026-03-03T21:14:03.238751Z","end":"2026-03-03T21:14:04.746916Z","steps":["trace[1107991826] 'agreement among raft nodes before linearized reading' (duration: 1.508000402s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:04.74696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.238674Z","time spent":"1.50827776s","remote":"127.0.0.1:58496","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-03-03T21:14:04.747198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173893637s","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":"warn","ts":"2026-03-03T21:14:04.747204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.52278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:14:04.74724Z","caller":"traceutil/trace.go:171","msg":"trace[984208545] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:949; }","duration":"596.599402ms","start":"2026-03-03T21:14:04.150632Z","end":"2026-03-03T21:14:04.747231Z","steps":["trace[984208545] 'agreement among raft nodes before linearized reading' (duration: 596.53173ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:04.747238Z","caller":"traceutil/trace.go:171","msg":"trace[1708826163] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:949; }","duration":"1.173936628s","start":"2026-03-03T21:14:03.573292Z","end":"2026-03-03T21:14:04.747228Z","steps":["trace[1708826163] 'agreement among raft nodes before linearized reading' (duration: 1.173868736s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:04.747263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:04.150611Z","time spent":"596.646244ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:14:04.747269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.573214Z","time spent":"1.174047441s","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:14:04.747245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.088127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:04.747322Z","caller":"traceutil/trace.go:171","msg":"trace[825988576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"997.18514ms","start":"2026-03-03T21:14:03.750117Z","end":"2026-03-03T21:14:04.747302Z","steps":["trace[825988576] 'agreement among raft nodes before linearized reading' (duration: 997.033086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:04.747364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.750055Z","time spent":"997.301063ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:14:04.747345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505787244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T21:14:04.747459Z","caller":"traceutil/trace.go:171","msg":"trace[1255019161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"1.505944428s","start":"2026-03-03T21:14:03.241486Z","end":"2026-03-03T21:14:04.74743Z","steps":["trace[1255019161] 'agreement among raft nodes before linearized reading' (duration: 1.505572929s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:04.747521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.241478Z","time spent":"1.50603208s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:04.747608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506044921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T21:14:04.747662Z","caller":"traceutil/trace.go:171","msg":"trace[1210027982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"1.506125443s","start":"2026-03-03T21:14:03.241519Z","end":"2026-03-03T21:14:04.747645Z","steps":["trace[1210027982] 'agreement among raft nodes before linearized reading' (duration: 1.505936078s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:04.747713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:03.24149Z","time spent":"1.506212286s","remote":"127.0.0.1:58496","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-03-03T21:14:06.250232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569522,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:14:06.388362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.53027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:14:06.388615Z","caller":"traceutil/trace.go:171","msg":"trace[791980492] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"639.116064ms","start":"2026-03-03T21:14:05.749423Z","end":"2026-03-03T21:14:06.388539Z","steps":["trace[791980492] 'read index received' (duration: 521.065612ms)","trace[791980492] 'applied index is now lower than readState.Index' (duration: 118.049072ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:06.388652Z","caller":"traceutil/trace.go:171","msg":"trace[378252606] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"674.809355ms","start":"2026-03-03T21:14:05.713776Z","end":"2026-03-03T21:14:06.388585Z","steps":["trace[378252606] 'process raft request' (duration: 30.982807ms)","trace[378252606] 'compare' (duration: 643.12689ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:06.388778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.34434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:06.388845Z","caller":"traceutil/trace.go:171","msg":"trace[789272367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"639.416742ms","start":"2026-03-03T21:14:05.749391Z","end":"2026-03-03T21:14:06.388808Z","steps":["trace[789272367] 'agreement among raft nodes before linearized reading' (duration: 639.294119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:06.388869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:05.71376Z","time spent":"674.99838ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:06.38889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:05.749384Z","time spent":"639.495514ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:14:06.389306Z","caller":"traceutil/trace.go:171","msg":"trace[848442801] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"646.93493ms","start":"2026-03-03T21:14:05.742314Z","end":"2026-03-03T21:14:06.389249Z","steps":["trace[848442801] 'process raft request' (duration: 646.148629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:06.389408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:05.742284Z","time spent":"647.083264ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:07.492476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.96039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:14:07.49268Z","caller":"traceutil/trace.go:171","msg":"trace[362934277] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"742.233112ms","start":"2026-03-03T21:14:06.750427Z","end":"2026-03-03T21:14:07.49266Z","steps":["trace[362934277] 'read index received' (duration: 456.948303ms)","trace[362934277] 'applied index is now lower than readState.Index' (duration: 285.283369ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:07.492921Z","caller":"traceutil/trace.go:171","msg":"trace[777939352] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.089072272s","start":"2026-03-03T21:14:06.403836Z","end":"2026-03-03T21:14:07.492909Z","steps":["trace[777939352] 'process raft request' (duration: 1.088747364s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:07.493006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.403829Z","time spent":"1.089126254s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:07.493096Z","caller":"traceutil/trace.go:171","msg":"trace[1984701383] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.091958448s","start":"2026-03-03T21:14:06.401092Z","end":"2026-03-03T21:14:07.49305Z","steps":["trace[1984701383] 'process raft request' (duration: 806.353871ms)","trace[1984701383] 'compare' (duration: 284.781645ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:07.493119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.681953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:14:07.493233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.67485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-03T21:14:07.493285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.40107Z","time spent":"1.092118263s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T21:14:07.493303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.216542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T21:14:07.49331Z","caller":"traceutil/trace.go:171","msg":"trace[646589373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"742.824547ms","start":"2026-03-03T21:14:06.750362Z","end":"2026-03-03T21:14:07.493186Z","steps":["trace[646589373] 'agreement among raft nodes before linearized reading' (duration: 742.672193ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.493314Z","caller":"traceutil/trace.go:171","msg":"trace[1492617705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"740.813314ms","start":"2026-03-03T21:14:06.752481Z","end":"2026-03-03T21:14:07.493295Z","steps":["trace[1492617705] 'agreement among raft nodes before linearized reading' (duration: 740.604619ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.493343Z","caller":"traceutil/trace.go:171","msg":"trace[834833397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"651.311425ms","start":"2026-03-03T21:14:06.842018Z","end":"2026-03-03T21:14:07.493329Z","steps":["trace[834833397] 'agreement among raft nodes before linearized reading' (duration: 651.167112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:07.493363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.752457Z","time spent":"740.896026ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T21:14:07.493362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.750337Z","time spent":"743.016622ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T21:14:07.493381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.841985Z","time spent":"651.389258ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:07.493492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.194843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-03T21:14:07.493537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.541732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T21:14:07.49354Z","caller":"traceutil/trace.go:171","msg":"trace[682510551] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:960; }","duration":"272.279395ms","start":"2026-03-03T21:14:07.221244Z","end":"2026-03-03T21:14:07.493523Z","steps":["trace[682510551] 'agreement among raft nodes before linearized reading' (duration: 272.155402ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.493563Z","caller":"traceutil/trace.go:171","msg":"trace[486469258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"651.612093ms","start":"2026-03-03T21:14:06.841942Z","end":"2026-03-03T21:14:07.493554Z","steps":["trace[486469258] 'agreement among raft nodes before linearized reading' (duration: 651.537751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:07.493587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.841907Z","time spent":"651.674805ms","remote":"127.0.0.1:58496","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-03-03T21:14:07.493831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.422774ms","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-03-03T21:14:07.493886Z","caller":"traceutil/trace.go:171","msg":"trace[978585968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"685.507677ms","start":"2026-03-03T21:14:06.808366Z","end":"2026-03-03T21:14:07.493873Z","steps":["trace[978585968] 'agreement among raft nodes before linearized reading' (duration: 685.281551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:07.493918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:06.808352Z","time spent":"685.560078ms","remote":"127.0.0.1:58496","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-03-03T21:14:07.778454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.004628ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-03T21:14:07.795831Z","caller":"traceutil/trace.go:171","msg":"trace[1295696447] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"297.278595ms","start":"2026-03-03T21:14:07.498519Z","end":"2026-03-03T21:14:07.795797Z","steps":["trace[1295696447] 'process raft request' (duration: 296.969707ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.795881Z","caller":"traceutil/trace.go:171","msg":"trace[1962190975] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"297.264045ms","start":"2026-03-03T21:14:07.498592Z","end":"2026-03-03T21:14:07.795856Z","steps":["trace[1962190975] 'process raft request' (duration: 297.059959ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.796884Z","caller":"traceutil/trace.go:171","msg":"trace[777502535] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"297.542142ms","start":"2026-03-03T21:14:07.499322Z","end":"2026-03-03T21:14:07.796864Z","steps":["trace[777502535] 'process raft request' (duration: 296.372911ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:07.797518Z","caller":"traceutil/trace.go:171","msg":"trace[843143129] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"297.442639ms","start":"2026-03-03T21:14:07.500058Z","end":"2026-03-03T21:14:07.797501Z","steps":["trace[843143129] 'process raft request' (duration: 296.527835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:10.390969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.531323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:14:10.391247Z","caller":"traceutil/trace.go:171","msg":"trace[1430634680] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"531.962639ms","start":"2026-03-03T21:14:09.859266Z","end":"2026-03-03T21:14:10.391228Z","steps":["trace[1430634680] 'process raft request' (duration: 531.918838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:10.391324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:09.859258Z","time spent":"532.034172ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:10.391542Z","caller":"traceutil/trace.go:171","msg":"trace[1494119369] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"532.33416ms","start":"2026-03-03T21:14:09.85918Z","end":"2026-03-03T21:14:10.391514Z","steps":["trace[1494119369] 'process raft request' (duration: 531.96924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:10.391701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:09.859146Z","time spent":"532.471543ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:10.391671Z","caller":"traceutil/trace.go:171","msg":"trace[659679401] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"533.472419ms","start":"2026-03-03T21:14:09.858158Z","end":"2026-03-03T21:14:10.391631Z","steps":["trace[659679401] 'process raft request' (duration: 123.174146ms)","trace[659679401] 'compare' (duration: 409.360758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:14:10.391882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:09.85813Z","time spent":"533.659824ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:10.392008Z","caller":"traceutil/trace.go:171","msg":"trace[1848762718] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"532.839123ms","start":"2026-03-03T21:14:09.859122Z","end":"2026-03-03T21:14:10.391961Z","steps":["trace[1848762718] 'process raft request' (duration: 531.944559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:10.39226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:09.859107Z","time spent":"533.026297ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:16.845833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.855843ms","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":"warn","ts":"2026-03-03T21:14:16.845987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.653409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-03T21:14:16.84604Z","caller":"traceutil/trace.go:171","msg":"trace[271389925] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"308.075549ms","start":"2026-03-03T21:14:16.537927Z","end":"2026-03-03T21:14:16.846002Z","steps":["trace[271389925] 'range keys from in-memory index tree' (duration: 307.661458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:16.846104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.537837Z","time spent":"308.251963ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-03T21:14:16.846109Z","caller":"traceutil/trace.go:171","msg":"trace[50715230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"425.830103ms","start":"2026-03-03T21:14:16.420242Z","end":"2026-03-03T21:14:16.846072Z","steps":["trace[50715230] 'range keys from in-memory index tree' (duration: 425.440452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:16.846107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.877205ms","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-03-03T21:14:16.846163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.420227Z","time spent":"425.925466ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T21:14:16.846195Z","caller":"traceutil/trace.go:171","msg":"trace[1252145971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"426.033968ms","start":"2026-03-03T21:14:16.420137Z","end":"2026-03-03T21:14:16.846171Z","steps":["trace[1252145971] 'range keys from in-memory index tree' (duration: 425.576686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:16.846197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.998321ms","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-03-03T21:14:16.846254Z","caller":"traceutil/trace.go:171","msg":"trace[1678721260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"425.104374ms","start":"2026-03-03T21:14:16.421133Z","end":"2026-03-03T21:14:16.846238Z","steps":["trace[1678721260] 'range keys from in-memory index tree' (duration: 424.851787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:16.846261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.420111Z","time spent":"426.138892ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:16.846287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.421106Z","time spent":"425.173595ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T21:14:16.846358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.058454ms","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-03-03T21:14:16.84646Z","caller":"traceutil/trace.go:171","msg":"trace[1937539736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"430.231219ms","start":"2026-03-03T21:14:16.416204Z","end":"2026-03-03T21:14:16.846435Z","steps":["trace[1937539736] 'range keys from in-memory index tree' (duration: 429.806667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:16.846517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.416169Z","time spent":"430.338012ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T21:14:17.411255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:14:17.912417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724677598569651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T21:14:18.237724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.386330727s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T21:14:18.238165Z","caller":"traceutil/trace.go:171","msg":"trace[1955035134] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.386898172s","start":"2026-03-03T21:14:16.851242Z","end":"2026-03-03T21:14:18.23814Z","steps":["trace[1955035134] 'process raft request' (duration: 1.386717968s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.238334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.851211Z","time spent":"1.387041006s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:18.562531Z","caller":"traceutil/trace.go:171","msg":"trace[88066446] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1067; }","duration":"1.65161752s","start":"2026-03-03T21:14:16.910889Z","end":"2026-03-03T21:14:18.562507Z","steps":["trace[88066446] 'read index received' (duration: 1.32723214s)","trace[88066446] 'applied index is now lower than readState.Index' (duration: 324.3838ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:14:18.562583Z","caller":"traceutil/trace.go:171","msg":"trace[995116742] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.710959993s","start":"2026-03-03T21:14:16.851565Z","end":"2026-03-03T21:14:18.562525Z","steps":["trace[995116742] 'process raft request' (duration: 1.710781519s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:18.562683Z","caller":"traceutil/trace.go:171","msg":"trace[948238066] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.711086827s","start":"2026-03-03T21:14:16.851582Z","end":"2026-03-03T21:14:18.562669Z","steps":["trace[948238066] 'process raft request' (duration: 1.71081942s)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:14:18.562587Z","caller":"traceutil/trace.go:171","msg":"trace[612634441] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.710994335s","start":"2026-03-03T21:14:16.851448Z","end":"2026-03-03T21:14:18.562442Z","steps":["trace[612634441] 'process raft request' (duration: 1.710632496s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.563434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.851416Z","time spent":"1.711938349s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:18.563498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.019676ms","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-03-03T21:14:18.563798Z","caller":"traceutil/trace.go:171","msg":"trace[947972559] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1025; }","duration":"180.288043ms","start":"2026-03-03T21:14:18.383392Z","end":"2026-03-03T21:14:18.56368Z","steps":["trace[947972559] 'agreement among raft nodes before linearized reading' (duration: 179.79786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.562856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.851564Z","time spent":"1.71116157s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:18.562865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.851555Z","time spent":"1.711159419s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:14:18.562913Z","caller":"traceutil/trace.go:171","msg":"trace[1218439775] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.709013972s","start":"2026-03-03T21:14:16.853887Z","end":"2026-03-03T21:14:18.562901Z","steps":["trace[1218439775] 'process raft request' (duration: 1.708562631s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.564403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.853875Z","time spent":"1.71044563s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:14:18.563005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454945006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:18.564573Z","caller":"traceutil/trace.go:171","msg":"trace[2045328003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"1.4565695s","start":"2026-03-03T21:14:17.107974Z","end":"2026-03-03T21:14:18.564543Z","steps":["trace[2045328003] 'agreement among raft nodes before linearized reading' (duration: 1.454964777s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.563126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056149916s","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":"warn","ts":"2026-03-03T21:14:18.564633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.10795Z","time spent":"1.456668142s","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:14:18.56466Z","caller":"traceutil/trace.go:171","msg":"trace[1485471073] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"1.057728247s","start":"2026-03-03T21:14:17.506915Z","end":"2026-03-03T21:14:18.564643Z","steps":["trace[1485471073] 'agreement among raft nodes before linearized reading' (duration: 1.056042713s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.564758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.506886Z","time spent":"1.057853741s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-03T21:14:18.563148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.373889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-03T21:14:18.564888Z","caller":"traceutil/trace.go:171","msg":"trace[1423528638] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"705.209106ms","start":"2026-03-03T21:14:17.859663Z","end":"2026-03-03T21:14:18.564872Z","steps":["trace[1423528638] 'agreement among raft nodes before linearized reading' (duration: 703.290626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.563183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.741751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T21:14:18.565016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.859631Z","time spent":"705.371191ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-03T21:14:18.565064Z","caller":"traceutil/trace.go:171","msg":"trace[1488928081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"814.657921ms","start":"2026-03-03T21:14:17.750391Z","end":"2026-03-03T21:14:18.565049Z","steps":["trace[1488928081] 'agreement among raft nodes before linearized reading' (duration: 812.767491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.56319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341159228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-03T21:14:18.565118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.75037Z","time spent":"814.733572ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:14:18.565134Z","caller":"traceutil/trace.go:171","msg":"trace[568877992] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1025; }","duration":"1.34314896s","start":"2026-03-03T21:14:17.221971Z","end":"2026-03-03T21:14:18.56512Z","steps":["trace[568877992] 'agreement among raft nodes before linearized reading' (duration: 1.341101566s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.565366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.221944Z","time spent":"1.343405957s","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-03-03T21:14:18.563254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652370419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:14:18.565594Z","caller":"traceutil/trace.go:171","msg":"trace[758527586] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1025; }","duration":"1.654747512s","start":"2026-03-03T21:14:16.910829Z","end":"2026-03-03T21:14:18.565577Z","steps":["trace[758527586] 'agreement among raft nodes before linearized reading' (duration: 1.65238973s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.565635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:16.910802Z","time spent":"1.654818884s","remote":"127.0.0.1:35062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-03T21:14:18.563255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.888198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:14:18.566027Z","caller":"traceutil/trace.go:171","msg":"trace[2044220996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"644.692261ms","start":"2026-03-03T21:14:17.921321Z","end":"2026-03-03T21:14:18.566014Z","steps":["trace[2044220996] 'agreement among raft nodes before linearized reading' (duration: 641.864027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:14:18.566062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:14:17.921302Z","time spent":"644.750102ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T21:17:09.130183Z","caller":"traceutil/trace.go:171","msg":"trace[1811060901] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"180.801639ms","start":"2026-03-03T21:17:08.949053Z","end":"2026-03-03T21:17:09.129855Z","steps":["trace[1811060901] 'process raft request' (duration: 85.444664ms)","trace[1811060901] 'compare' (duration: 95.108489ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:09.130083Z","caller":"traceutil/trace.go:171","msg":"trace[1254688964] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"179.480124ms","start":"2026-03-03T21:17:08.95057Z","end":"2026-03-03T21:17:09.13005Z","steps":["trace[1254688964] 'process raft request' (duration: 179.228158ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:09.653451Z","caller":"traceutil/trace.go:171","msg":"trace[330544557] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"103.261004ms","start":"2026-03-03T21:17:09.550147Z","end":"2026-03-03T21:17:09.653408Z","steps":["trace[330544557] 'process raft request' (duration: 49.615489ms)","trace[330544557] 'compare' (duration: 53.349657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:11.932484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.583056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:11.932594Z","caller":"traceutil/trace.go:171","msg":"trace[1865461465] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1815; }","duration":"189.869519ms","start":"2026-03-03T21:17:11.742707Z","end":"2026-03-03T21:17:11.932577Z","steps":["trace[1865461465] 'read index received' (duration: 18.063117ms)","trace[1865461465] 'applied index is now lower than readState.Index' (duration: 171.804702ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:11.932691Z","caller":"traceutil/trace.go:171","msg":"trace[101953687] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"239.870567ms","start":"2026-03-03T21:17:11.69273Z","end":"2026-03-03T21:17:11.9326Z","steps":["trace[101953687] 'process raft request' (duration: 68.071096ms)","trace[101953687] 'compare' (duration: 171.139484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:11.93286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.000242ms","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-03-03T21:17:11.932892Z","caller":"traceutil/trace.go:171","msg":"trace[731631375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1735; }","duration":"190.070034ms","start":"2026-03-03T21:17:11.742812Z","end":"2026-03-03T21:17:11.932882Z","steps":["trace[731631375] 'agreement among raft nodes before linearized reading' (duration: 189.999172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:11.932786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.079194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:11.932975Z","caller":"traceutil/trace.go:171","msg":"trace[915581380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1735; }","duration":"190.269029ms","start":"2026-03-03T21:17:11.74267Z","end":"2026-03-03T21:17:11.93294Z","steps":["trace[915581380] 'agreement among raft nodes before linearized reading' (duration: 189.992822ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:12.099087Z","caller":"traceutil/trace.go:171","msg":"trace[665651881] linearizableReadLoop","detail":"{readStateIndex:1818; appliedIndex:1816; }","duration":"152.568734ms","start":"2026-03-03T21:17:11.94649Z","end":"2026-03-03T21:17:12.099059Z","steps":["trace[665651881] 'read index received' (duration: 52.524605ms)","trace[665651881] 'applied index is now lower than readState.Index' (duration: 100.042659ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:12.099247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.737968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:17:12.099316Z","caller":"traceutil/trace.go:171","msg":"trace[1846361133] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1737; }","duration":"152.884102ms","start":"2026-03-03T21:17:11.946419Z","end":"2026-03-03T21:17:12.099303Z","steps":["trace[1846361133] 'agreement among raft nodes before linearized reading' (duration: 152.758789ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:12.099581Z","caller":"traceutil/trace.go:171","msg":"trace[1267388749] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"161.078508ms","start":"2026-03-03T21:17:11.93804Z","end":"2026-03-03T21:17:12.099118Z","steps":["trace[1267388749] 'process raft request' (duration: 109.432726ms)","trace[1267388749] 'compare' (duration: 51.446657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:12.702959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.880946ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-03T21:17:13.824914Z","caller":"traceutil/trace.go:171","msg":"trace[850417421] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"183.160411ms","start":"2026-03-03T21:17:13.641717Z","end":"2026-03-03T21:17:13.824877Z","steps":["trace[850417421] 'process raft request' (duration: 182.876773ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:13.825087Z","caller":"traceutil/trace.go:171","msg":"trace[776668643] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1821; }","duration":"133.463051ms","start":"2026-03-03T21:17:13.691592Z","end":"2026-03-03T21:17:13.825055Z","steps":["trace[776668643] 'read index received' (duration: 132.908506ms)","trace[776668643] 'applied index is now lower than readState.Index' (duration: 552.794µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:13.825426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.81244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T21:17:13.825516Z","caller":"traceutil/trace.go:171","msg":"trace[147497770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1740; }","duration":"133.975953ms","start":"2026-03-03T21:17:13.691529Z","end":"2026-03-03T21:17:13.825505Z","steps":["trace[147497770] 'agreement among raft nodes before linearized reading' (duration: 133.736137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:14.295357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.011258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:14.295511Z","caller":"traceutil/trace.go:171","msg":"trace[540178240] linearizableReadLoop","detail":"{readStateIndex:1825; appliedIndex:1824; }","duration":"291.235731ms","start":"2026-03-03T21:17:14.004248Z","end":"2026-03-03T21:17:14.295484Z","steps":["trace[540178240] 'read index received' (duration: 28.159303ms)","trace[540178240] 'applied index is now lower than readState.Index' (duration: 263.074528ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:14.295651Z","caller":"traceutil/trace.go:171","msg":"trace[1083524595] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"295.224087ms","start":"2026-03-03T21:17:14.000412Z","end":"2026-03-03T21:17:14.295636Z","steps":["trace[1083524595] 'process raft request' (duration: 161.727556ms)","trace[1083524595] 'compare' (duration: 132.854765ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:14.296045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.963343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:14.296148Z","caller":"traceutil/trace.go:171","msg":"trace[709120275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1743; }","duration":"192.147948ms","start":"2026-03-03T21:17:14.103976Z","end":"2026-03-03T21:17:14.296124Z","steps":["trace[709120275] 'agreement among raft nodes before linearized reading' (duration: 191.851441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:14.296294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.069768ms","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-03-03T21:17:14.296421Z","caller":"traceutil/trace.go:171","msg":"trace[842781213] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1743; }","duration":"161.293124ms","start":"2026-03-03T21:17:14.135096Z","end":"2026-03-03T21:17:14.296389Z","steps":["trace[842781213] 'agreement among raft nodes before linearized reading' (duration: 160.929675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:14.296514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.242009ms","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-03-03T21:17:14.296615Z","caller":"traceutil/trace.go:171","msg":"trace[2142132553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1743; }","duration":"292.388622ms","start":"2026-03-03T21:17:14.004204Z","end":"2026-03-03T21:17:14.296592Z","steps":["trace[2142132553] 'agreement among raft nodes before linearized reading' (duration: 292.088744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:14.296725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.469879ms","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-03-03T21:17:14.296954Z","caller":"traceutil/trace.go:171","msg":"trace[1941984115] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1743; }","duration":"161.696915ms","start":"2026-03-03T21:17:14.135123Z","end":"2026-03-03T21:17:14.29682Z","steps":["trace[1941984115] 'agreement among raft nodes before linearized reading' (duration: 160.994806ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:14.460744Z","caller":"traceutil/trace.go:171","msg":"trace[1459016462] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"157.247848ms","start":"2026-03-03T21:17:14.303463Z","end":"2026-03-03T21:17:14.460711Z","steps":["trace[1459016462] 'process raft request' (duration: 127.904704ms)","trace[1459016462] 'compare' (duration: 29.095117ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:14.461097Z","caller":"traceutil/trace.go:171","msg":"trace[1196697765] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"156.383485ms","start":"2026-03-03T21:17:14.304683Z","end":"2026-03-03T21:17:14.461067Z","steps":["trace[1196697765] 'process raft request' (duration: 156.242451ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:14.46124Z","caller":"traceutil/trace.go:171","msg":"trace[280365307] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"157.663969ms","start":"2026-03-03T21:17:14.303543Z","end":"2026-03-03T21:17:14.461207Z","steps":["trace[280365307] 'process raft request' (duration: 157.113474ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:15.792204Z","caller":"traceutil/trace.go:171","msg":"trace[1516184080] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"147.169501ms","start":"2026-03-03T21:17:15.644965Z","end":"2026-03-03T21:17:15.792134Z","steps":["trace[1516184080] 'process raft request' (duration: 146.900504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:16.013668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.232483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T21:17:16.013846Z","caller":"traceutil/trace.go:171","msg":"trace[1115588625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1750; }","duration":"147.49345ms","start":"2026-03-03T21:17:15.866325Z","end":"2026-03-03T21:17:16.013818Z","steps":["trace[1115588625] 'range keys from in-memory index tree' (duration: 146.200996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:17.408291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.914279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:17.408524Z","caller":"traceutil/trace.go:171","msg":"trace[1589041600] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"132.783412ms","start":"2026-03-03T21:17:17.275665Z","end":"2026-03-03T21:17:17.408448Z","steps":["trace[1589041600] 'process raft request' (duration: 22.629097ms)","trace[1589041600] 'compare' (duration: 109.736534ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:17.91386Z","caller":"traceutil/trace.go:171","msg":"trace[790812340] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"113.214266ms","start":"2026-03-03T21:17:17.800606Z","end":"2026-03-03T21:17:17.91382Z","steps":["trace[790812340] 'process raft request' (duration: 112.938319ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:18.19515Z","caller":"traceutil/trace.go:171","msg":"trace[540488316] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"131.440467ms","start":"2026-03-03T21:17:18.063683Z","end":"2026-03-03T21:17:18.195124Z","steps":["trace[540488316] 'process raft request' (duration: 130.899333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:19.884114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.536192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:19.88422Z","caller":"traceutil/trace.go:171","msg":"trace[383620846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1765; }","duration":"133.707456ms","start":"2026-03-03T21:17:19.750488Z","end":"2026-03-03T21:17:19.884196Z","steps":["trace[383620846] 'range keys from in-memory index tree' (duration: 133.411879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:20.091455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.942497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:20.091614Z","caller":"traceutil/trace.go:171","msg":"trace[651663636] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"168.392472ms","start":"2026-03-03T21:17:19.92319Z","end":"2026-03-03T21:17:20.091583Z","steps":["trace[651663636] 'process raft request' (duration: 60.203938ms)","trace[651663636] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 107.70648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:20.314904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.519291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:17:20.31502Z","caller":"traceutil/trace.go:171","msg":"trace[490096632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1767; }","duration":"114.720056ms","start":"2026-03-03T21:17:20.200268Z","end":"2026-03-03T21:17:20.314988Z","steps":["trace[490096632] 'range keys from in-memory index tree' (duration: 114.281064ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:20.455059Z","caller":"traceutil/trace.go:171","msg":"trace[394561855] linearizableReadLoop","detail":"{readStateIndex:1851; appliedIndex:1850; }","duration":"103.582862ms","start":"2026-03-03T21:17:20.351451Z","end":"2026-03-03T21:17:20.455034Z","steps":["trace[394561855] 'read index received' (duration: 103.369306ms)","trace[394561855] 'applied index is now lower than readState.Index' (duration: 211.836µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:20.455268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.787318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:20.455383Z","caller":"traceutil/trace.go:171","msg":"trace[560686618] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1768; }","duration":"103.986003ms","start":"2026-03-03T21:17:20.351372Z","end":"2026-03-03T21:17:20.455358Z","steps":["trace[560686618] 'agreement among raft nodes before linearized reading' (duration: 103.788487ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:20.455103Z","caller":"traceutil/trace.go:171","msg":"trace[1139324705] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"134.96575ms","start":"2026-03-03T21:17:20.320106Z","end":"2026-03-03T21:17:20.455071Z","steps":["trace[1139324705] 'process raft request' (duration: 134.736154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:22.648888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.554298ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-03-03T21:17:22.649228Z","caller":"traceutil/trace.go:171","msg":"trace[1669761568] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1859; }","duration":"188.241435ms","start":"2026-03-03T21:17:22.460966Z","end":"2026-03-03T21:17:22.649207Z","steps":["trace[1669761568] 'read index received' (duration: 52.731µs)","trace[1669761568] 'applied index is now lower than readState.Index' (duration: 188.186424ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:22.649331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:22.259463Z","time spent":"389.864793ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-03T21:17:22.649684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.726198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T21:17:22.649725Z","caller":"traceutil/trace.go:171","msg":"trace[1122930524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1776; }","duration":"188.842031ms","start":"2026-03-03T21:17:22.460872Z","end":"2026-03-03T21:17:22.649714Z","steps":["trace[1122930524] 'agreement among raft nodes before linearized reading' (duration: 188.668506ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:22.852395Z","caller":"traceutil/trace.go:171","msg":"trace[1283807096] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"203.103497ms","start":"2026-03-03T21:17:22.649261Z","end":"2026-03-03T21:17:22.852365Z","steps":["trace[1283807096] 'read index received' (duration: 117.261613ms)","trace[1283807096] 'applied index is now lower than readState.Index' (duration: 85.840124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:22.852556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.996662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:22.852631Z","caller":"traceutil/trace.go:171","msg":"trace[682897831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1776; }","duration":"220.119155ms","start":"2026-03-03T21:17:22.632493Z","end":"2026-03-03T21:17:22.852613Z","steps":["trace[682897831] 'agreement among raft nodes before linearized reading' (duration: 219.90385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:22.852641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.722641ms","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-03-03T21:17:22.852676Z","caller":"traceutil/trace.go:171","msg":"trace[1415970104] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1776; }","duration":"303.826714ms","start":"2026-03-03T21:17:22.548839Z","end":"2026-03-03T21:17:22.852666Z","steps":["trace[1415970104] 'agreement among raft nodes before linearized reading' (duration: 303.651369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:22.852705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:22.548782Z","time spent":"303.916417ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-03T21:17:22.873113Z","caller":"traceutil/trace.go:171","msg":"trace[80958399] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"219.429098ms","start":"2026-03-03T21:17:22.653669Z","end":"2026-03-03T21:17:22.873098Z","steps":["trace[80958399] 'process raft request' (duration: 219.380867ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:22.873305Z","caller":"traceutil/trace.go:171","msg":"trace[2082212652] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"222.579331ms","start":"2026-03-03T21:17:22.650716Z","end":"2026-03-03T21:17:22.873295Z","steps":["trace[2082212652] 'process raft request' (duration: 222.185471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:22.873317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.885377ms","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-03-03T21:17:22.873379Z","caller":"traceutil/trace.go:171","msg":"trace[486382084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1779; }","duration":"123.97185ms","start":"2026-03-03T21:17:22.74938Z","end":"2026-03-03T21:17:22.873352Z","steps":["trace[486382084] 'agreement among raft nodes before linearized reading' (duration: 123.805225ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:22.873455Z","caller":"traceutil/trace.go:171","msg":"trace[1204227660] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"222.646563ms","start":"2026-03-03T21:17:22.650799Z","end":"2026-03-03T21:17:22.873445Z","steps":["trace[1204227660] 'process raft request' (duration: 222.210722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:22.873583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.514117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:22.873613Z","caller":"traceutil/trace.go:171","msg":"trace[1075571134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1779; }","duration":"123.59716ms","start":"2026-03-03T21:17:22.750006Z","end":"2026-03-03T21:17:22.873604Z","steps":["trace[1075571134] 'agreement among raft nodes before linearized reading' (duration: 123.549699ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:23.803741Z","caller":"traceutil/trace.go:171","msg":"trace[1490165372] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"137.61774ms","start":"2026-03-03T21:17:23.666058Z","end":"2026-03-03T21:17:23.803676Z","steps":["trace[1490165372] 'process raft request' (duration: 137.23628ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:26.173002Z","caller":"traceutil/trace.go:171","msg":"trace[885706619] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"358.682771ms","start":"2026-03-03T21:17:25.814265Z","end":"2026-03-03T21:17:26.172948Z","steps":["trace[885706619] 'process raft request' (duration: 357.739856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:26.173268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:25.814233Z","time spent":"358.884266ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:26.442742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.206046ms","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-03-03T21:17:26.442861Z","caller":"traceutil/trace.go:171","msg":"trace[1598410054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1798; }","duration":"229.397321ms","start":"2026-03-03T21:17:26.213431Z","end":"2026-03-03T21:17:26.442828Z","steps":["trace[1598410054] 'range keys from in-memory index tree' (duration: 228.917728ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:26.653312Z","caller":"traceutil/trace.go:171","msg":"trace[936557400] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"142.836747ms","start":"2026-03-03T21:17:26.510444Z","end":"2026-03-03T21:17:26.653281Z","steps":["trace[936557400] 'process raft request' (duration: 62.240081ms)","trace[936557400] 'compare' (duration: 80.479423ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:27.051865Z","caller":"traceutil/trace.go:171","msg":"trace[792072397] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"109.624832ms","start":"2026-03-03T21:17:26.942217Z","end":"2026-03-03T21:17:27.051842Z","steps":["trace[792072397] 'process raft request' (duration: 109.432807ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:27.261525Z","caller":"traceutil/trace.go:171","msg":"trace[1275471150] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"205.131951ms","start":"2026-03-03T21:17:27.056327Z","end":"2026-03-03T21:17:27.261459Z","steps":["trace[1275471150] 'process raft request' (duration: 130.79992ms)","trace[1275471150] 'compare' (duration: 74.098995ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:27.515442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.926758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:27.515889Z","caller":"traceutil/trace.go:171","msg":"trace[1647470299] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1887; }","duration":"285.327986ms","start":"2026-03-03T21:17:27.23053Z","end":"2026-03-03T21:17:27.515858Z","steps":["trace[1647470299] 'read index received' (duration: 179.865684ms)","trace[1647470299] 'applied index is now lower than readState.Index' (duration: 105.460442ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:27.515935Z","caller":"traceutil/trace.go:171","msg":"trace[2089284354] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"458.790522ms","start":"2026-03-03T21:17:27.057097Z","end":"2026-03-03T21:17:27.515888Z","steps":["trace[2089284354] 'process raft request' (duration: 458.481603ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:27.515909Z","caller":"traceutil/trace.go:171","msg":"trace[23805580] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"459.442779ms","start":"2026-03-03T21:17:27.056421Z","end":"2026-03-03T21:17:27.515864Z","steps":["trace[23805580] 'process raft request' (duration: 353.958557ms)","trace[23805580] 'compare' (duration: 104.743512ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:27.516193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:27.057081Z","time spent":"458.972426ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:27.516506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.981843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T21:17:27.516564Z","caller":"traceutil/trace.go:171","msg":"trace[129857775] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1804; }","duration":"286.100346ms","start":"2026-03-03T21:17:27.230448Z","end":"2026-03-03T21:17:27.516548Z","steps":["trace[129857775] 'agreement among raft nodes before linearized reading' (duration: 285.49106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:27.516456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:27.056394Z","time spent":"459.811038ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:29.218946Z","caller":"traceutil/trace.go:171","msg":"trace[50676589] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"101.146638ms","start":"2026-03-03T21:17:29.117754Z","end":"2026-03-03T21:17:29.2189Z","steps":["trace[50676589] 'process raft request' (duration: 58.680888ms)","trace[50676589] 'compare' (duration: 42.316576ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:29.46566Z","caller":"traceutil/trace.go:171","msg":"trace[275509319] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"195.463486ms","start":"2026-03-03T21:17:29.270163Z","end":"2026-03-03T21:17:29.465627Z","steps":["trace[275509319] 'process raft request' (duration: 194.87294ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:29.651089Z","caller":"traceutil/trace.go:171","msg":"trace[1577014942] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"125.604513ms","start":"2026-03-03T21:17:29.525438Z","end":"2026-03-03T21:17:29.651043Z","steps":["trace[1577014942] 'process raft request' (duration: 65.272282ms)","trace[1577014942] 'compare' (duration: 60.069014ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:29.651151Z","caller":"traceutil/trace.go:171","msg":"trace[1844022174] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"122.963833ms","start":"2026-03-03T21:17:29.528151Z","end":"2026-03-03T21:17:29.651115Z","steps":["trace[1844022174] 'process raft request' (duration: 122.785278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:29.972455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.091912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:29.972595Z","caller":"traceutil/trace.go:171","msg":"trace[2132355003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"221.266316ms","start":"2026-03-03T21:17:29.751264Z","end":"2026-03-03T21:17:29.972531Z","steps":["trace[2132355003] 'range keys from in-memory index tree' (duration: 220.936587ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:30.170322Z","caller":"traceutil/trace.go:171","msg":"trace[1134320916] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"120.488478ms","start":"2026-03-03T21:17:30.049802Z","end":"2026-03-03T21:17:30.170291Z","steps":["trace[1134320916] 'process raft request' (duration: 120.242542ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:31.229807Z","caller":"traceutil/trace.go:171","msg":"trace[914101911] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"106.035517ms","start":"2026-03-03T21:17:31.123741Z","end":"2026-03-03T21:17:31.229776Z","steps":["trace[914101911] 'process raft request' (duration: 105.861652ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:31.646004Z","caller":"traceutil/trace.go:171","msg":"trace[124396029] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"128.735826ms","start":"2026-03-03T21:17:31.517245Z","end":"2026-03-03T21:17:31.645981Z","steps":["trace[124396029] 'process raft request' (duration: 128.682774ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:31.646317Z","caller":"traceutil/trace.go:171","msg":"trace[764546178] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"129.088955ms","start":"2026-03-03T21:17:31.517185Z","end":"2026-03-03T21:17:31.646274Z","steps":["trace[764546178] 'process raft request' (duration: 128.665144ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:31.803241Z","caller":"traceutil/trace.go:171","msg":"trace[237482626] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"129.057784ms","start":"2026-03-03T21:17:31.674156Z","end":"2026-03-03T21:17:31.803214Z","steps":["trace[237482626] 'process raft request' (duration: 44.131534ms)","trace[237482626] 'compare' (duration: 83.995325ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:31.803633Z","caller":"traceutil/trace.go:171","msg":"trace[275522029] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"129.218178ms","start":"2026-03-03T21:17:31.674404Z","end":"2026-03-03T21:17:31.803622Z","steps":["trace[275522029] 'process raft request' (duration: 128.731865ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:31.8041Z","caller":"traceutil/trace.go:171","msg":"trace[1004276558] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"128.982922ms","start":"2026-03-03T21:17:31.675104Z","end":"2026-03-03T21:17:31.804087Z","steps":["trace[1004276558] 'process raft request' (duration: 128.329804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:32.01106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737815ms","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-03-03T21:17:32.011232Z","caller":"traceutil/trace.go:171","msg":"trace[293095104] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1827; }","duration":"106.94325ms","start":"2026-03-03T21:17:31.904259Z","end":"2026-03-03T21:17:32.011202Z","steps":["trace[293095104] 'range keys from in-memory index tree' (duration: 106.699264ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:33.390078Z","caller":"traceutil/trace.go:171","msg":"trace[90791071] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"128.585322ms","start":"2026-03-03T21:17:33.261459Z","end":"2026-03-03T21:17:33.390044Z","steps":["trace[90791071] 'read index received' (duration: 128.354416ms)","trace[90791071] 'applied index is now lower than readState.Index' (duration: 229.436µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:33.390145Z","caller":"traceutil/trace.go:171","msg":"trace[61678276] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"150.518471ms","start":"2026-03-03T21:17:33.239576Z","end":"2026-03-03T21:17:33.390094Z","steps":["trace[61678276] 'process raft request' (duration: 150.268224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:33.390459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.764997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:33.390523Z","caller":"traceutil/trace.go:171","msg":"trace[233535585] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1831; }","duration":"129.086106ms","start":"2026-03-03T21:17:33.261418Z","end":"2026-03-03T21:17:33.390504Z","steps":["trace[233535585] 'agreement among raft nodes before linearized reading' (duration: 128.756967ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:34.080092Z","caller":"traceutil/trace.go:171","msg":"trace[2095254920] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1921; }","duration":"273.038202ms","start":"2026-03-03T21:17:33.807026Z","end":"2026-03-03T21:17:34.080064Z","steps":["trace[2095254920] 'read index received' (duration: 207.492552ms)","trace[2095254920] 'applied index is now lower than readState.Index' (duration: 65.54472ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:34.080122Z","caller":"traceutil/trace.go:171","msg":"trace[1627725440] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"278.557438ms","start":"2026-03-03T21:17:33.801514Z","end":"2026-03-03T21:17:34.080072Z","steps":["trace[1627725440] 'process raft request' (duration: 212.987648ms)","trace[1627725440] 'compare' (duration: 65.306413ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:34.080472Z","caller":"traceutil/trace.go:171","msg":"trace[1001347527] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"273.569366ms","start":"2026-03-03T21:17:33.806885Z","end":"2026-03-03T21:17:34.080455Z","steps":["trace[1001347527] 'process raft request' (duration: 273.076053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:34.080694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.210107ms","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-03-03T21:17:34.080929Z","caller":"traceutil/trace.go:171","msg":"trace[1077571450] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1836; }","duration":"273.504354ms","start":"2026-03-03T21:17:33.807407Z","end":"2026-03-03T21:17:34.080911Z","steps":["trace[1077571450] 'agreement among raft nodes before linearized reading' (duration: 272.98798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:34.080688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.626447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:34.08124Z","caller":"traceutil/trace.go:171","msg":"trace[1469356063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"274.217213ms","start":"2026-03-03T21:17:33.807007Z","end":"2026-03-03T21:17:34.081225Z","steps":["trace[1469356063] 'agreement among raft nodes before linearized reading' (duration: 273.433383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:34.080837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.371655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-03-03T21:17:34.081584Z","caller":"traceutil/trace.go:171","msg":"trace[927144152] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1836; }","duration":"146.172165ms","start":"2026-03-03T21:17:33.935395Z","end":"2026-03-03T21:17:34.081567Z","steps":["trace[927144152] 'agreement among raft nodes before linearized reading' (duration: 145.362754ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:34.503526Z","caller":"traceutil/trace.go:171","msg":"trace[1289927375] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"109.014746ms","start":"2026-03-03T21:17:34.394469Z","end":"2026-03-03T21:17:34.503483Z","steps":["trace[1289927375] 'process raft request' (duration: 49.298381ms)","trace[1289927375] 'compare' (duration: 59.55006ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:34.661988Z","caller":"traceutil/trace.go:171","msg":"trace[762721977] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"149.575815ms","start":"2026-03-03T21:17:34.512361Z","end":"2026-03-03T21:17:34.661937Z","steps":["trace[762721977] 'process raft request' (duration: 124.003081ms)","trace[762721977] 'compare' (duration: 25.433051ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:34.8134Z","caller":"traceutil/trace.go:171","msg":"trace[154045708] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"148.690111ms","start":"2026-03-03T21:17:34.664682Z","end":"2026-03-03T21:17:34.813372Z","steps":["trace[154045708] 'read index received' (duration: 117.279103ms)","trace[154045708] 'applied index is now lower than readState.Index' (duration: 31.409888ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:34.81345Z","caller":"traceutil/trace.go:171","msg":"trace[2008247752] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"212.529455ms","start":"2026-03-03T21:17:34.600872Z","end":"2026-03-03T21:17:34.813401Z","steps":["trace[2008247752] 'process raft request' (duration: 181.067085ms)","trace[2008247752] 'compare' (duration: 31.315856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:34.813891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.171215ms","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-03-03T21:17:34.8141Z","caller":"traceutil/trace.go:171","msg":"trace[1700162388] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1844; }","duration":"149.439281ms","start":"2026-03-03T21:17:34.664606Z","end":"2026-03-03T21:17:34.814045Z","steps":["trace[1700162388] 'agreement among raft nodes before linearized reading' (duration: 148.884837ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:34.91245Z","caller":"traceutil/trace.go:171","msg":"trace[1998274047] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"215.084753ms","start":"2026-03-03T21:17:34.697339Z","end":"2026-03-03T21:17:34.912424Z","steps":["trace[1998274047] 'process raft request' (duration: 214.767485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:34.912519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.069695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:34.912608Z","caller":"traceutil/trace.go:171","msg":"trace[271313685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"162.199248ms","start":"2026-03-03T21:17:34.750385Z","end":"2026-03-03T21:17:34.912585Z","steps":["trace[271313685] 'agreement among raft nodes before linearized reading' (duration: 161.980293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:34.912591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.381064ms","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-03-03T21:17:34.912721Z","caller":"traceutil/trace.go:171","msg":"trace[431269642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1845; }","duration":"159.57478ms","start":"2026-03-03T21:17:34.753131Z","end":"2026-03-03T21:17:34.912705Z","steps":["trace[431269642] 'agreement among raft nodes before linearized reading' (duration: 159.294842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:35.070366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.690444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:35.070741Z","caller":"traceutil/trace.go:171","msg":"trace[593405428] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"153.272942ms","start":"2026-03-03T21:17:34.917444Z","end":"2026-03-03T21:17:35.070717Z","steps":["trace[593405428] 'process raft request' (duration: 153.18479ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:35.070701Z","caller":"traceutil/trace.go:171","msg":"trace[394950017] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"251.73102ms","start":"2026-03-03T21:17:34.818901Z","end":"2026-03-03T21:17:35.070632Z","steps":["trace[394950017] 'process raft request' (duration: 144.625515ms)","trace[394950017] 'compare' (duration: 106.52818ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:35.070942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.892379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:35.070746Z","caller":"traceutil/trace.go:171","msg":"trace[1918655411] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"156.762885ms","start":"2026-03-03T21:17:34.913957Z","end":"2026-03-03T21:17:35.07072Z","steps":["trace[1918655411] 'read index received' (duration: 49.602559ms)","trace[1918655411] 'applied index is now lower than readState.Index' (duration: 107.157456ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:35.071005Z","caller":"traceutil/trace.go:171","msg":"trace[2041545066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1847; }","duration":"157.048802ms","start":"2026-03-03T21:17:34.913938Z","end":"2026-03-03T21:17:35.070987Z","steps":["trace[2041545066] 'agreement among raft nodes before linearized reading' (duration: 156.858177ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:39.211597Z","caller":"traceutil/trace.go:171","msg":"trace[1536532208] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"100.06289ms","start":"2026-03-03T21:17:39.111509Z","end":"2026-03-03T21:17:39.211572Z","steps":["trace[1536532208] 'process raft request' (duration: 99.946796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:39.93403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.499804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:39.934248Z","caller":"traceutil/trace.go:171","msg":"trace[2002060707] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"160.303458ms","start":"2026-03-03T21:17:39.773863Z","end":"2026-03-03T21:17:39.934167Z","steps":["trace[2002060707] 'process raft request' (duration: 57.528517ms)","trace[2002060707] 'compare' (duration: 102.333669ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:40.196133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.795039ms","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-03-03T21:17:40.196257Z","caller":"traceutil/trace.go:171","msg":"trace[194631650] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1866; }","duration":"120.002075ms","start":"2026-03-03T21:17:40.076228Z","end":"2026-03-03T21:17:40.19623Z","steps":["trace[194631650] 'range keys from in-memory index tree' (duration: 119.565573ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:40.434489Z","caller":"traceutil/trace.go:171","msg":"trace[172275624] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"132.870265ms","start":"2026-03-03T21:17:40.301589Z","end":"2026-03-03T21:17:40.43446Z","steps":["trace[172275624] 'read index received' (duration: 132.598948ms)","trace[172275624] 'applied index is now lower than readState.Index' (duration: 270.257µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:40.434549Z","caller":"traceutil/trace.go:171","msg":"trace[1814981247] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"232.890863ms","start":"2026-03-03T21:17:40.201621Z","end":"2026-03-03T21:17:40.434512Z","steps":["trace[1814981247] 'process raft request' (duration: 232.676938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:40.434813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.715234ms","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-03-03T21:17:40.434811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.196443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-03T21:17:40.434858Z","caller":"traceutil/trace.go:171","msg":"trace[1014457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"120.801576ms","start":"2026-03-03T21:17:40.314046Z","end":"2026-03-03T21:17:40.434847Z","steps":["trace[1014457] 'agreement among raft nodes before linearized reading' (duration: 120.631792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:40.434872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.996603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-03-03T21:17:40.43487Z","caller":"traceutil/trace.go:171","msg":"trace[1170576303] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1867; }","duration":"133.335956ms","start":"2026-03-03T21:17:40.301521Z","end":"2026-03-03T21:17:40.434857Z","steps":["trace[1170576303] 'agreement among raft nodes before linearized reading' (duration: 133.0917ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:40.434911Z","caller":"traceutil/trace.go:171","msg":"trace[1922626339] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1867; }","duration":"115.107067ms","start":"2026-03-03T21:17:40.319792Z","end":"2026-03-03T21:17:40.434899Z","steps":["trace[1922626339] 'agreement among raft nodes before linearized reading' (duration: 114.931622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:40.761193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.853901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:40.761372Z","caller":"traceutil/trace.go:171","msg":"trace[642192394] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"321.300105ms","start":"2026-03-03T21:17:40.440043Z","end":"2026-03-03T21:17:40.761343Z","steps":["trace[642192394] 'process raft request' (duration: 88.184856ms)","trace[642192394] 'compare' (duration: 232.755609ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:40.761534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:40.440021Z","time spent":"321.423267ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:40.761562Z","caller":"traceutil/trace.go:171","msg":"trace[343002098] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"321.383487ms","start":"2026-03-03T21:17:40.440112Z","end":"2026-03-03T21:17:40.761495Z","steps":["trace[343002098] 'process raft request' (duration: 321.178521ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:40.761592Z","caller":"traceutil/trace.go:171","msg":"trace[1329607877] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1955; }","duration":"156.627811ms","start":"2026-03-03T21:17:40.604915Z","end":"2026-03-03T21:17:40.761543Z","steps":["trace[1329607877] 'read index received' (duration: 80.623526ms)","trace[1329607877] 'applied index is now lower than readState.Index' (duration: 76.003215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:40.761846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.928139ms","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-03-03T21:17:40.761875Z","caller":"traceutil/trace.go:171","msg":"trace[1346136985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"156.99463ms","start":"2026-03-03T21:17:40.604873Z","end":"2026-03-03T21:17:40.761868Z","steps":["trace[1346136985] 'agreement among raft nodes before linearized reading' (duration: 156.819306ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:40.761774Z","caller":"traceutil/trace.go:171","msg":"trace[1967517338] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"321.50913ms","start":"2026-03-03T21:17:40.440229Z","end":"2026-03-03T21:17:40.761738Z","steps":["trace[1967517338] 'process raft request' (duration: 321.273044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:40.762146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:40.440199Z","time spent":"321.776867ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:40.762279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:40.440084Z","time spent":"321.528221ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:41.038151Z","caller":"traceutil/trace.go:171","msg":"trace[957730326] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"271.058826ms","start":"2026-03-03T21:17:40.767065Z","end":"2026-03-03T21:17:41.038124Z","steps":["trace[957730326] 'process raft request' (duration: 267.691128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:43.033081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.251743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-03T21:17:43.033234Z","caller":"traceutil/trace.go:171","msg":"trace[157307541] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"282.127159ms","start":"2026-03-03T21:17:42.751082Z","end":"2026-03-03T21:17:43.03321Z","steps":["trace[157307541] 'read index received' (duration: 145.798794ms)","trace[157307541] 'applied index is now lower than readState.Index' (duration: 136.326705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:43.033534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.334255ms","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-03-03T21:17:43.033576Z","caller":"traceutil/trace.go:171","msg":"trace[425018046] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1875; }","duration":"266.445467ms","start":"2026-03-03T21:17:42.767119Z","end":"2026-03-03T21:17:43.033564Z","steps":["trace[425018046] 'agreement among raft nodes before linearized reading' (duration: 266.234102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:43.033595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.365126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:43.033699Z","caller":"traceutil/trace.go:171","msg":"trace[843059304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1875; }","duration":"266.515319ms","start":"2026-03-03T21:17:42.76716Z","end":"2026-03-03T21:17:43.033675Z","steps":["trace[843059304] 'agreement among raft nodes before linearized reading' (duration: 266.223942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:43.034068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.94819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:43.034245Z","caller":"traceutil/trace.go:171","msg":"trace[1959753700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"283.144396ms","start":"2026-03-03T21:17:42.751014Z","end":"2026-03-03T21:17:43.034158Z","steps":["trace[1959753700] 'agreement among raft nodes before linearized reading' (duration: 282.311764ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:43.205388Z","caller":"traceutil/trace.go:171","msg":"trace[399104429] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"166.070885ms","start":"2026-03-03T21:17:43.039278Z","end":"2026-03-03T21:17:43.205349Z","steps":["trace[399104429] 'process raft request' (duration: 165.85938ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:43.321701Z","caller":"traceutil/trace.go:171","msg":"trace[454538575] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1964; }","duration":"279.384027ms","start":"2026-03-03T21:17:43.042277Z","end":"2026-03-03T21:17:43.321661Z","steps":["trace[454538575] 'read index received' (duration: 162.980104ms)","trace[454538575] 'applied index is now lower than readState.Index' (duration: 116.402213ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:43.321814Z","caller":"traceutil/trace.go:171","msg":"trace[452869816] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"281.714918ms","start":"2026-03-03T21:17:43.040052Z","end":"2026-03-03T21:17:43.321767Z","steps":["trace[452869816] 'process raft request' (duration: 201.211547ms)","trace[452869816] 'compare' (duration: 80.231854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:43.321999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.689375ms","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-03-03T21:17:43.322063Z","caller":"traceutil/trace.go:171","msg":"trace[2059680981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1877; }","duration":"279.820278ms","start":"2026-03-03T21:17:43.042229Z","end":"2026-03-03T21:17:43.32205Z","steps":["trace[2059680981] 'agreement among raft nodes before linearized reading' (duration: 279.547281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:43.322486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.788886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-03-03T21:17:43.322583Z","caller":"traceutil/trace.go:171","msg":"trace[1620403275] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1877; }","duration":"176.94872ms","start":"2026-03-03T21:17:43.145619Z","end":"2026-03-03T21:17:43.322567Z","steps":["trace[1620403275] 'agreement among raft nodes before linearized reading' (duration: 176.697394ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:44.227409Z","caller":"traceutil/trace.go:171","msg":"trace[1216464049] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"101.791529ms","start":"2026-03-03T21:17:44.125551Z","end":"2026-03-03T21:17:44.227343Z","steps":["trace[1216464049] 'process raft request' (duration: 101.595294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:44.500203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.57933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:44.500731Z","caller":"traceutil/trace.go:171","msg":"trace[1909834727] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"262.419022ms","start":"2026-03-03T21:17:44.238057Z","end":"2026-03-03T21:17:44.500476Z","steps":["trace[1909834727] 'process raft request' (duration: 130.435951ms)","trace[1909834727] 'compare' (duration: 131.390596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:44.822033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.785073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:44.822433Z","caller":"traceutil/trace.go:171","msg":"trace[1697934873] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"316.385547ms","start":"2026-03-03T21:17:44.506023Z","end":"2026-03-03T21:17:44.822409Z","steps":["trace[1697934873] 'process raft request' (duration: 101.087681ms)","trace[1697934873] 'compare' (duration: 214.582267ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:44.822604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:44.505995Z","time spent":"316.536501ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:44.880507Z","caller":"traceutil/trace.go:171","msg":"trace[1198813303] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"142.272101ms","start":"2026-03-03T21:17:44.738204Z","end":"2026-03-03T21:17:44.880476Z","steps":["trace[1198813303] 'read index received' (duration: 141.934812ms)","trace[1198813303] 'applied index is now lower than readState.Index' (duration: 336.199µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:44.880568Z","caller":"traceutil/trace.go:171","msg":"trace[491182531] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"167.721918ms","start":"2026-03-03T21:17:44.712811Z","end":"2026-03-03T21:17:44.880533Z","steps":["trace[491182531] 'process raft request' (duration: 167.513343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:44.880749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.252056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:44.880816Z","caller":"traceutil/trace.go:171","msg":"trace[1777005014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"130.357559ms","start":"2026-03-03T21:17:44.750438Z","end":"2026-03-03T21:17:44.880795Z","steps":["trace[1777005014] 'agreement among raft nodes before linearized reading' (duration: 130.201675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:44.880841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.569229ms","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-03-03T21:17:44.881014Z","caller":"traceutil/trace.go:171","msg":"trace[373355962] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1885; }","duration":"142.848376ms","start":"2026-03-03T21:17:44.738141Z","end":"2026-03-03T21:17:44.88099Z","steps":["trace[373355962] 'agreement among raft nodes before linearized reading' (duration: 142.433965ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:45.565322Z","caller":"traceutil/trace.go:171","msg":"trace[84444082] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1978; }","duration":"122.072201ms","start":"2026-03-03T21:17:45.443209Z","end":"2026-03-03T21:17:45.565281Z","steps":["trace[84444082] 'read index received' (duration: 35.398658ms)","trace[84444082] 'applied index is now lower than readState.Index' (duration: 86.672143ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:45.565354Z","caller":"traceutil/trace.go:171","msg":"trace[419336555] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"134.60806ms","start":"2026-03-03T21:17:45.4307Z","end":"2026-03-03T21:17:45.565308Z","steps":["trace[419336555] 'process raft request' (duration: 131.022146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:45.565862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.608416ms","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:508"} {"level":"info","ts":"2026-03-03T21:17:45.566078Z","caller":"traceutil/trace.go:171","msg":"trace[112748536] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1891; }","duration":"122.877322ms","start":"2026-03-03T21:17:45.443172Z","end":"2026-03-03T21:17:45.566049Z","steps":["trace[112748536] 'agreement among raft nodes before linearized reading' (duration: 122.260896ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:47.372074Z","caller":"traceutil/trace.go:171","msg":"trace[775432182] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"107.445358ms","start":"2026-03-03T21:17:47.264585Z","end":"2026-03-03T21:17:47.37203Z","steps":["trace[775432182] 'process raft request' (duration: 99.264703ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:47.399467Z","caller":"traceutil/trace.go:171","msg":"trace[1943233809] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"119.484443ms","start":"2026-03-03T21:17:47.279952Z","end":"2026-03-03T21:17:47.399437Z","steps":["trace[1943233809] 'process raft request' (duration: 119.269318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:47.598413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.986462ms","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-03-03T21:17:47.598543Z","caller":"traceutil/trace.go:171","msg":"trace[2036147795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"116.167166ms","start":"2026-03-03T21:17:47.482347Z","end":"2026-03-03T21:17:47.598514Z","steps":["trace[2036147795] 'range keys from in-memory index tree' (duration: 115.739775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:47.807445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.135016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T21:17:47.80757Z","caller":"traceutil/trace.go:171","msg":"trace[307057011] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1900; }","duration":"100.325411ms","start":"2026-03-03T21:17:47.707214Z","end":"2026-03-03T21:17:47.80754Z","steps":["trace[307057011] 'count revisions from in-memory index tree' (duration: 100.042013ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:47.969247Z","caller":"traceutil/trace.go:171","msg":"trace[1888456306] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"156.967937ms","start":"2026-03-03T21:17:47.812247Z","end":"2026-03-03T21:17:47.969215Z","steps":["trace[1888456306] 'process raft request' (duration: 156.69843ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:48.310673Z","caller":"traceutil/trace.go:171","msg":"trace[2023810996] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"160.290764ms","start":"2026-03-03T21:17:48.150341Z","end":"2026-03-03T21:17:48.310632Z","steps":["trace[2023810996] 'process raft request' (duration: 160.048917ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:49.586701Z","caller":"traceutil/trace.go:171","msg":"trace[1260839580] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"130.097092ms","start":"2026-03-03T21:17:49.456544Z","end":"2026-03-03T21:17:49.586641Z","steps":["trace[1260839580] 'process raft request' (duration: 50.894484ms)","trace[1260839580] 'compare' (duration: 78.972912ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:49.586818Z","caller":"traceutil/trace.go:171","msg":"trace[485015232] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"130.03548ms","start":"2026-03-03T21:17:49.456765Z","end":"2026-03-03T21:17:49.5868Z","steps":["trace[485015232] 'process raft request' (duration: 129.824454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.231123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.418236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T21:17:50.231277Z","caller":"traceutil/trace.go:171","msg":"trace[1917699891] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"482.884272ms","start":"2026-03-03T21:17:49.748357Z","end":"2026-03-03T21:17:50.231241Z","steps":["trace[1917699891] 'read index received' (duration: 33.42µs)","trace[1917699891] 'applied index is now lower than readState.Index' (duration: 482.848772ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:50.231552Z","caller":"traceutil/trace.go:171","msg":"trace[1983681983] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"545.815553ms","start":"2026-03-03T21:17:49.685722Z","end":"2026-03-03T21:17:50.231537Z","steps":["trace[1983681983] 'process raft request' (duration: 29.871083ms)","trace[1983681983] 'compare' (duration: 515.279183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:50.231625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:49.685692Z","time spent":"545.893265ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:50.231915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.977561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-03T21:17:50.231977Z","caller":"traceutil/trace.go:171","msg":"trace[596428456] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1906; }","duration":"405.066183ms","start":"2026-03-03T21:17:49.826892Z","end":"2026-03-03T21:17:50.231958Z","steps":["trace[596428456] 'agreement among raft nodes before linearized reading' (duration: 404.868308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.232018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:49.82688Z","time spent":"405.129805ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-03T21:17:50.232046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.611952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T21:17:50.232082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.745354ms","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-03-03T21:17:50.232124Z","caller":"traceutil/trace.go:171","msg":"trace[246962966] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1906; }","duration":"314.810766ms","start":"2026-03-03T21:17:49.917301Z","end":"2026-03-03T21:17:50.232112Z","steps":["trace[246962966] 'agreement among raft nodes before linearized reading' (duration: 314.696493ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:50.232122Z","caller":"traceutil/trace.go:171","msg":"trace[312033832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1906; }","duration":"258.712545ms","start":"2026-03-03T21:17:49.973389Z","end":"2026-03-03T21:17:50.232101Z","steps":["trace[312033832] 'agreement among raft nodes before linearized reading' (duration: 258.51422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.232157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:49.917286Z","time spent":"314.863577ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-03T21:17:50.232132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.747906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:50.232225Z","caller":"traceutil/trace.go:171","msg":"trace[1247717597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1906; }","duration":"483.88974ms","start":"2026-03-03T21:17:49.748311Z","end":"2026-03-03T21:17:50.2322Z","steps":["trace[1247717597] 'agreement among raft nodes before linearized reading' (duration: 483.721445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.232286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:49.748298Z","time spent":"483.978792ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T21:17:50.617381Z","caller":"traceutil/trace.go:171","msg":"trace[681532746] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"380.595641ms","start":"2026-03-03T21:17:50.23675Z","end":"2026-03-03T21:17:50.617346Z","steps":["trace[681532746] 'process raft request' (duration: 358.869781ms)","trace[681532746] 'compare' (duration: 21.483924ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:50.617559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:50.236721Z","time spent":"380.744125ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:50.700012Z","caller":"traceutil/trace.go:171","msg":"trace[1038835627] linearizableReadLoop","detail":"{readStateIndex:1999; appliedIndex:1996; }","duration":"384.017161ms","start":"2026-03-03T21:17:50.315966Z","end":"2026-03-03T21:17:50.699983Z","steps":["trace[1038835627] 'read index received' (duration: 279.640434ms)","trace[1038835627] 'applied index is now lower than readState.Index' (duration: 104.372407ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:50.700169Z","caller":"traceutil/trace.go:171","msg":"trace[1505074272] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"463.024133ms","start":"2026-03-03T21:17:50.237022Z","end":"2026-03-03T21:17:50.700046Z","steps":["trace[1505074272] 'process raft request' (duration: 462.765776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.700448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:50.237003Z","time spent":"463.303ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:50.700455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.471523ms","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-03-03T21:17:50.700829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.615747ms","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-03-03T21:17:50.700887Z","caller":"traceutil/trace.go:171","msg":"trace[227266549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1909; }","duration":"384.968006ms","start":"2026-03-03T21:17:50.315902Z","end":"2026-03-03T21:17:50.70087Z","steps":["trace[227266549] 'agreement among raft nodes before linearized reading' (duration: 384.238647ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:50.700943Z","caller":"traceutil/trace.go:171","msg":"trace[894522930] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1909; }","duration":"103.806423ms","start":"2026-03-03T21:17:50.597104Z","end":"2026-03-03T21:17:50.700911Z","steps":["trace[894522930] 'agreement among raft nodes before linearized reading' (duration: 103.177246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.701088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:50.315869Z","time spent":"385.060998ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T21:17:50.700526Z","caller":"traceutil/trace.go:171","msg":"trace[1435694989] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"463.073884ms","start":"2026-03-03T21:17:50.237425Z","end":"2026-03-03T21:17:50.700499Z","steps":["trace[1435694989] 'process raft request' (duration: 462.506499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:50.701372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:50.237414Z","time spent":"463.857925ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:51.493267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.430859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:51.493373Z","caller":"traceutil/trace.go:171","msg":"trace[1367615982] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1912; }","duration":"255.625114ms","start":"2026-03-03T21:17:51.237726Z","end":"2026-03-03T21:17:51.493352Z","steps":["trace[1367615982] 'count revisions from in-memory index tree' (duration: 255.334186ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:51.729858Z","caller":"traceutil/trace.go:171","msg":"trace[1222653342] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"133.46674ms","start":"2026-03-03T21:17:51.596008Z","end":"2026-03-03T21:17:51.729475Z","steps":["trace[1222653342] 'process raft request' (duration: 133.290206ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:51.764016Z","caller":"traceutil/trace.go:171","msg":"trace[708187221] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"167.220435ms","start":"2026-03-03T21:17:51.596761Z","end":"2026-03-03T21:17:51.763982Z","steps":["trace[708187221] 'process raft request' (duration: 166.993759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:52.978182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.548183ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-03T21:17:52.978406Z","caller":"traceutil/trace.go:171","msg":"trace[827620539] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"271.051928ms","start":"2026-03-03T21:17:52.707254Z","end":"2026-03-03T21:17:52.978306Z","steps":["trace[827620539] 'read index received' (duration: 47.422713ms)","trace[827620539] 'applied index is now lower than readState.Index' (duration: 223.627025ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:52.978609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.342196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T21:17:52.978684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.479634ms","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-03-03T21:17:52.978687Z","caller":"traceutil/trace.go:171","msg":"trace[861234114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1917; }","duration":"271.47649ms","start":"2026-03-03T21:17:52.707189Z","end":"2026-03-03T21:17:52.978666Z","steps":["trace[861234114] 'agreement among raft nodes before linearized reading' (duration: 271.207273ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:52.978729Z","caller":"traceutil/trace.go:171","msg":"trace[496645804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1917; }","duration":"262.550506ms","start":"2026-03-03T21:17:52.716166Z","end":"2026-03-03T21:17:52.978717Z","steps":["trace[496645804] 'agreement among raft nodes before linearized reading' (duration: 262.415012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:52.978862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.0171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:17:52.979037Z","caller":"traceutil/trace.go:171","msg":"trace[204877740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"228.243476ms","start":"2026-03-03T21:17:52.750741Z","end":"2026-03-03T21:17:52.978984Z","steps":["trace[204877740] 'agreement among raft nodes before linearized reading' (duration: 227.855236ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:53.112187Z","caller":"traceutil/trace.go:171","msg":"trace[1252042893] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"128.616333ms","start":"2026-03-03T21:17:52.983245Z","end":"2026-03-03T21:17:53.111861Z","steps":["trace[1252042893] 'process raft request' (duration: 128.394717ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:53.542365Z","caller":"traceutil/trace.go:171","msg":"trace[1716592685] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"201.754351ms","start":"2026-03-03T21:17:53.340584Z","end":"2026-03-03T21:17:53.542338Z","steps":["trace[1716592685] 'read index received' (duration: 179.771225ms)","trace[1716592685] 'applied index is now lower than readState.Index' (duration: 21.981696ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:53.542473Z","caller":"traceutil/trace.go:171","msg":"trace[1537087441] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"558.722692ms","start":"2026-03-03T21:17:52.983708Z","end":"2026-03-03T21:17:53.542431Z","steps":["trace[1537087441] 'process raft request' (duration: 536.638352ms)","trace[1537087441] 'compare' (duration: 21.70658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:53.54262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.018858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-03-03T21:17:53.542657Z","caller":"traceutil/trace.go:171","msg":"trace[1140317117] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1919; }","duration":"202.11769ms","start":"2026-03-03T21:17:53.34053Z","end":"2026-03-03T21:17:53.542648Z","steps":["trace[1140317117] 'agreement among raft nodes before linearized reading' (duration: 201.901125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:53.54268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:52.983689Z","time spent":"558.873126ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:54.011502Z","caller":"traceutil/trace.go:171","msg":"trace[260423443] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"212.01993ms","start":"2026-03-03T21:17:53.799447Z","end":"2026-03-03T21:17:54.011467Z","steps":["trace[260423443] 'process raft request' (duration: 205.826738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:55.769983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.300084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-03T21:17:55.770084Z","caller":"traceutil/trace.go:171","msg":"trace[39656682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1926; }","duration":"152.51315ms","start":"2026-03-03T21:17:55.617552Z","end":"2026-03-03T21:17:55.770065Z","steps":["trace[39656682] 'range keys from in-memory index tree' (duration: 152.108019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:55.770301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.568626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-03T21:17:55.77033Z","caller":"traceutil/trace.go:171","msg":"trace[1894437779] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1926; }","duration":"148.615928ms","start":"2026-03-03T21:17:55.621705Z","end":"2026-03-03T21:17:55.770321Z","steps":["trace[1894437779] 'range keys from in-memory index tree' (duration: 148.492584ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:55.985459Z","caller":"traceutil/trace.go:171","msg":"trace[84414819] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"210.418068ms","start":"2026-03-03T21:17:55.775014Z","end":"2026-03-03T21:17:55.985432Z","steps":["trace[84414819] 'process raft request' (duration: 210.343886ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:55.985553Z","caller":"traceutil/trace.go:171","msg":"trace[1522998880] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"211.161637ms","start":"2026-03-03T21:17:55.774316Z","end":"2026-03-03T21:17:55.985478Z","steps":["trace[1522998880] 'process raft request' (duration: 204.485922ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:55.985727Z","caller":"traceutil/trace.go:171","msg":"trace[2056649891] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2017; }","duration":"150.386144ms","start":"2026-03-03T21:17:55.835236Z","end":"2026-03-03T21:17:55.985623Z","steps":["trace[2056649891] 'read index received' (duration: 33.426847ms)","trace[2056649891] 'applied index is now lower than readState.Index' (duration: 116.956937ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T21:17:55.985787Z","caller":"traceutil/trace.go:171","msg":"trace[1891044838] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"210.239994ms","start":"2026-03-03T21:17:55.775515Z","end":"2026-03-03T21:17:55.985755Z","steps":["trace[1891044838] 'process raft request' (duration: 209.883324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:55.986095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.835465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-03T21:17:55.986142Z","caller":"traceutil/trace.go:171","msg":"trace[1207374235] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1930; }","duration":"150.961259ms","start":"2026-03-03T21:17:55.835164Z","end":"2026-03-03T21:17:55.986125Z","steps":["trace[1207374235] 'agreement among raft nodes before linearized reading' (duration: 150.727743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:55.986186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.717393ms","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-03-03T21:17:55.986213Z","caller":"traceutil/trace.go:171","msg":"trace[410956602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1930; }","duration":"110.805655ms","start":"2026-03-03T21:17:55.8754Z","end":"2026-03-03T21:17:55.986206Z","steps":["trace[410956602] 'agreement among raft nodes before linearized reading' (duration: 110.659852ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:56.420574Z","caller":"traceutil/trace.go:171","msg":"trace[1873674306] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"424.359039ms","start":"2026-03-03T21:17:55.996194Z","end":"2026-03-03T21:17:56.420553Z","steps":["trace[1873674306] 'process raft request' (duration: 413.095154ms)","trace[1873674306] 'compare' (duration: 10.995698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:56.420832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:55.996168Z","time spent":"424.559844ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:17:56.421055Z","caller":"traceutil/trace.go:171","msg":"trace[862028465] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"422.958493ms","start":"2026-03-03T21:17:55.998063Z","end":"2026-03-03T21:17:56.421021Z","steps":["trace[862028465] 'process raft request' (duration: 422.355527ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:17:56.420498Z","caller":"traceutil/trace.go:171","msg":"trace[1403426805] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2021; }","duration":"405.081943ms","start":"2026-03-03T21:17:56.015388Z","end":"2026-03-03T21:17:56.42047Z","steps":["trace[1403426805] 'read index received' (duration: 393.9211ms)","trace[1403426805] 'applied index is now lower than readState.Index' (duration: 11.159533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:17:56.421366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:55.998039Z","time spent":"423.103196ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T21:17:56.421503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.031869ms","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-03-03T21:17:56.421616Z","caller":"traceutil/trace.go:171","msg":"trace[1569360081] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1932; }","duration":"406.253854ms","start":"2026-03-03T21:17:56.015333Z","end":"2026-03-03T21:17:56.421587Z","steps":["trace[1569360081] 'agreement among raft nodes before linearized reading' (duration: 405.851524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:17:56.421675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:17:56.015319Z","time spent":"406.346347ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-03T21:17:56.559959Z","caller":"traceutil/trace.go:171","msg":"trace[1584857989] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"132.257358ms","start":"2026-03-03T21:17:56.427662Z","end":"2026-03-03T21:17:56.559919Z","steps":["trace[1584857989] 'process raft request' (duration: 127.669588ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:18:05.651792Z","caller":"traceutil/trace.go:171","msg":"trace[2055672675] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"176.041107ms","start":"2026-03-03T21:18:05.475709Z","end":"2026-03-03T21:18:05.65175Z","steps":["trace[2055672675] 'read index received' (duration: 175.962415ms)","trace[2055672675] 'applied index is now lower than readState.Index' (duration: 77.482µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T21:18:05.651974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.250153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T21:18:05.652021Z","caller":"traceutil/trace.go:171","msg":"trace[682537698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1971; }","duration":"176.377005ms","start":"2026-03-03T21:18:05.475628Z","end":"2026-03-03T21:18:05.652005Z","steps":["trace[682537698] 'agreement among raft nodes before linearized reading' (duration: 176.251782ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T21:18:05.65205Z","caller":"traceutil/trace.go:171","msg":"trace[1117685199] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"390.362787ms","start":"2026-03-03T21:18:05.26164Z","end":"2026-03-03T21:18:05.652003Z","steps":["trace[1117685199] 'process raft request' (duration: 389.917086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T21:18:05.652267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T21:18:05.261611Z","time spent":"390.540002ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T21:18:05.728572Z","caller":"traceutil/trace.go:171","msg":"trace[443485958] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"220.717969ms","start":"2026-03-03T21:18:05.507743Z","end":"2026-03-03T21:18:05.728461Z","steps":["trace[443485958] 'process raft request' (duration: 181.434778ms)","trace[443485958] 'compare' (duration: 35.297926ms)"],"step_count":2}