{"level":"warn","ts":"2026-03-19T13:35:02.814577Z","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-19T13:35:02.814653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-19T13:35:02.814708Z","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-19T13:35:02.814717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-03-19T13:35:02.814734Z","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-19T13:35:02.815355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-03-19T13:35:02.81542Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-19T13:35:02.819071Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.473506ms"} {"level":"info","ts":"2026-03-19T13:35:02.824077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-03-19T13:35:02.824134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T13:35:02.824149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-03-19T13:35:02.824157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T13:35:02.824163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-03-19T13:35:02.824191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-03-19T13:35:02.833393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T13:35:02.834735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T13:35:02.837691Z","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-19T13:35:02.840136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T13:35:02.840528Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T13:35:02.84119Z","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-19T13:35:02.841256Z","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-19T13:35:02.841269Z","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-19T13:35:02.842613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-03-19T13:35:02.842813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-03-19T13:35:02.843721Z","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-19T13:35:02.843886Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T13:35:02.843907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T13:35:02.843835Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-03-19T13:35:02.844678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-03-19T13:35:03.124999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T13:35:03.125051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T13:35:03.125138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-03-19T13:35:03.125199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-03-19T13:35:03.125218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-03-19T13:35:03.126517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-03-19T13:35:03.126538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-03-19T13:35:03.127804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:35:03.12905Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T13:35:03.129177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:35:03.129246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:35:03.132385Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:35:03.132481Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:35:03.132529Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:35:03.132446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T13:35:03.13266Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T13:35:03.132678Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T13:35:03.139505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"warn","ts":"2026-03-19T13:35:16.628647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.895633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T13:35:16.628751Z","caller":"traceutil/trace.go:171","msg":"trace[844038523] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:356; }","duration":"440.076567ms","start":"2026-03-19T13:35:16.188653Z","end":"2026-03-19T13:35:16.628729Z","steps":["trace[844038523] 'range keys from in-memory index tree' (duration: 439.791981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:16.62881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.188632Z","time spent":"440.166738ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-03-19T13:35:17.15775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156305708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:17.658746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156305708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:17.661049Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.030807641s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:35:17.661453Z","caller":"traceutil/trace.go:171","msg":"trace[1068598719] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"1.004098119s","start":"2026-03-19T13:35:16.65733Z","end":"2026-03-19T13:35:17.661428Z","steps":["trace[1068598719] 'read index received' (duration: 1.003866244s)","trace[1068598719] 'applied index is now lower than readState.Index' (duration: 230.465µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:17.661482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.630075Z","time spent":"1.031400142s","remote":"127.0.0.1:34230","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T13:35:17.661621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.378455ms","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-19T13:35:17.661657Z","caller":"traceutil/trace.go:171","msg":"trace[1191024791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"822.498767ms","start":"2026-03-19T13:35:16.839146Z","end":"2026-03-19T13:35:17.661645Z","steps":["trace[1191024791] 'agreement among raft nodes before linearized reading' (duration: 822.403805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:17.661694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.83913Z","time spent":"822.555748ms","remote":"127.0.0.1:34436","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-19T13:35:17.661694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.825748ms","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-19T13:35:17.661759Z","caller":"traceutil/trace.go:171","msg":"trace[434355679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"684.95283ms","start":"2026-03-19T13:35:16.976785Z","end":"2026-03-19T13:35:17.661738Z","steps":["trace[434355679] 'agreement among raft nodes before linearized reading' (duration: 684.793227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:17.661786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.194375ms","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-19T13:35:17.661813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.976765Z","time spent":"685.039041ms","remote":"127.0.0.1:34436","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-19T13:35:17.661856Z","caller":"traceutil/trace.go:171","msg":"trace[2093319939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"794.364228ms","start":"2026-03-19T13:35:16.867475Z","end":"2026-03-19T13:35:17.661839Z","steps":["trace[2093319939] 'agreement among raft nodes before linearized reading' (duration: 794.148004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:17.661891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.867466Z","time spent":"794.416449ms","remote":"127.0.0.1:34436","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-19T13:35:17.662144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004812313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-03-19T13:35:17.662204Z","caller":"traceutil/trace.go:171","msg":"trace[1644886414] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"1.004901665s","start":"2026-03-19T13:35:16.657287Z","end":"2026-03-19T13:35:17.662188Z","steps":["trace[1644886414] 'agreement among raft nodes before linearized reading' (duration: 1.004772431s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:17.662259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:16.657223Z","time spent":"1.005023757s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-19T13:35:18.338689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.362524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:18.338775Z","caller":"traceutil/trace.go:171","msg":"trace[958068333] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"676.188413ms","start":"2026-03-19T13:35:17.662572Z","end":"2026-03-19T13:35:18.338761Z","steps":["trace[958068333] 'process raft request' (duration: 322.236267ms)","trace[958068333] 'compare' (duration: 353.241682ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:18.338841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:17.66255Z","time spent":"676.268205ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:19.053929Z","caller":"traceutil/trace.go:171","msg":"trace[188711694] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"1.382526634s","start":"2026-03-19T13:35:17.671367Z","end":"2026-03-19T13:35:19.053894Z","steps":["trace[188711694] 'process raft request' (duration: 1.290982249s)","trace[188711694] 'compare' (duration: 91.31266ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:19.054085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:17.671344Z","time spent":"1.382660717s","remote":"127.0.0.1:34436","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-19T13:35:19.054616Z","caller":"traceutil/trace.go:171","msg":"trace[923293440] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"1.383109656s","start":"2026-03-19T13:35:17.671449Z","end":"2026-03-19T13:35:19.054558Z","steps":["trace[923293440] 'process raft request' (duration: 1.382370841s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:19.054828Z","caller":"traceutil/trace.go:171","msg":"trace[164430434] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:367; }","duration":"1.090068016s","start":"2026-03-19T13:35:17.964741Z","end":"2026-03-19T13:35:19.054809Z","steps":["trace[164430434] 'read index received' (duration: 20.074094ms)","trace[164430434] 'applied index is now lower than readState.Index' (duration: 1.069992902s)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:19.054864Z","caller":"traceutil/trace.go:171","msg":"trace[318701952] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"1.38335742s","start":"2026-03-19T13:35:17.671481Z","end":"2026-03-19T13:35:19.054838Z","steps":["trace[318701952] 'process raft request' (duration: 1.382932932s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:19.054938Z","caller":"traceutil/trace.go:171","msg":"trace[2042167575] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"1.379568587s","start":"2026-03-19T13:35:17.675268Z","end":"2026-03-19T13:35:19.054836Z","steps":["trace[2042167575] 'process raft request' (duration: 1.379451824s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.054966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090220729s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:35:19.055068Z","caller":"traceutil/trace.go:171","msg":"trace[351581871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:361; }","duration":"1.090327421s","start":"2026-03-19T13:35:17.964729Z","end":"2026-03-19T13:35:19.055057Z","steps":["trace[351581871] 'agreement among raft nodes before linearized reading' (duration: 1.090207008s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.05506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:17.67143Z","time spent":"1.383546873s","remote":"127.0.0.1:34436","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-19T13:35:19.05507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:17.675254Z","time spent":"1.37974586s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:19.055241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:17.67144Z","time spent":"1.38338004s","remote":"127.0.0.1:34436","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-19T13:35:19.060619Z","caller":"traceutil/trace.go:171","msg":"trace[956842059] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"914.120374ms","start":"2026-03-19T13:35:18.146486Z","end":"2026-03-19T13:35:19.060606Z","steps":["trace[956842059] 'process raft request' (duration: 913.926861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.060712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:18.146451Z","time spent":"914.220526ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:19.060877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.089485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-19T13:35:19.060911Z","caller":"traceutil/trace.go:171","msg":"trace[1249864644] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:362; }","duration":"719.173297ms","start":"2026-03-19T13:35:18.341727Z","end":"2026-03-19T13:35:19.0609Z","steps":["trace[1249864644] 'agreement among raft nodes before linearized reading' (duration: 719.117266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.060921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.410146ms","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-19T13:35:19.060931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:18.341709Z","time spent":"719.217568ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-19T13:35:19.060947Z","caller":"traceutil/trace.go:171","msg":"trace[732775087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"956.472966ms","start":"2026-03-19T13:35:18.104466Z","end":"2026-03-19T13:35:19.060939Z","steps":["trace[732775087] 'agreement among raft nodes before linearized reading' (duration: 956.423355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.060975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:18.104444Z","time spent":"956.525648ms","remote":"127.0.0.1:34436","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-19T13:35:19.503697Z","caller":"traceutil/trace.go:171","msg":"trace[1765341157] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"436.835454ms","start":"2026-03-19T13:35:19.066838Z","end":"2026-03-19T13:35:19.503674Z","steps":["trace[1765341157] 'process raft request' (duration: 436.748263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.503832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:19.066827Z","time spent":"436.936696ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:19.504113Z","caller":"traceutil/trace.go:171","msg":"trace[1810603535] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"437.238952ms","start":"2026-03-19T13:35:19.066835Z","end":"2026-03-19T13:35:19.504074Z","steps":["trace[1810603535] 'read index received' (duration: 349.964599ms)","trace[1810603535] 'applied index is now lower than readState.Index' (duration: 87.270973ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:19.504186Z","caller":"traceutil/trace.go:171","msg":"trace[1798093775] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"439.23585ms","start":"2026-03-19T13:35:19.064918Z","end":"2026-03-19T13:35:19.504154Z","steps":["trace[1798093775] 'process raft request' (duration: 351.803065ms)","trace[1798093775] 'compare' (duration: 86.652611ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:19.504294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.432015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-19T13:35:19.504345Z","caller":"traceutil/trace.go:171","msg":"trace[801104692] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:364; }","duration":"437.512717ms","start":"2026-03-19T13:35:19.066817Z","end":"2026-03-19T13:35:19.50433Z","steps":["trace[801104692] 'agreement among raft nodes before linearized reading' (duration: 437.376654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:19.504343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:19.064905Z","time spent":"439.348312ms","remote":"127.0.0.1:34436","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-19T13:35:19.504401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:19.066808Z","time spent":"437.580278ms","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-19T13:35:19.639354Z","caller":"traceutil/trace.go:171","msg":"trace[1854384679] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"122.140502ms","start":"2026-03-19T13:35:19.517179Z","end":"2026-03-19T13:35:19.639319Z","steps":["trace[1854384679] 'process raft request' (duration: 115.554096ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:31.251196Z","caller":"traceutil/trace.go:171","msg":"trace[2116041506] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"137.291572ms","start":"2026-03-19T13:35:31.113884Z","end":"2026-03-19T13:35:31.251176Z","steps":["trace[2116041506] 'process raft request' (duration: 137.17055ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:31.257301Z","caller":"traceutil/trace.go:171","msg":"trace[1719364584] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"142.312778ms","start":"2026-03-19T13:35:31.114964Z","end":"2026-03-19T13:35:31.257277Z","steps":["trace[1719364584] 'process raft request' (duration: 142.104634ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:31.257359Z","caller":"traceutil/trace.go:171","msg":"trace[1476941513] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"139.886151ms","start":"2026-03-19T13:35:31.117454Z","end":"2026-03-19T13:35:31.25734Z","steps":["trace[1476941513] 'process raft request' (duration: 139.738468ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:32.389885Z","caller":"traceutil/trace.go:171","msg":"trace[665224204] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"131.759386ms","start":"2026-03-19T13:35:32.258102Z","end":"2026-03-19T13:35:32.389861Z","steps":["trace[665224204] 'process raft request' (duration: 131.721026ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:32.389987Z","caller":"traceutil/trace.go:171","msg":"trace[2012808417] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"133.117282ms","start":"2026-03-19T13:35:32.256815Z","end":"2026-03-19T13:35:32.389932Z","steps":["trace[2012808417] 'process raft request' (duration: 97.752504ms)","trace[2012808417] 'compare' (duration: 35.103903ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:35.276111Z","caller":"traceutil/trace.go:171","msg":"trace[391893876] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"194.888346ms","start":"2026-03-19T13:35:35.081202Z","end":"2026-03-19T13:35:35.276091Z","steps":["trace[391893876] 'process raft request' (duration: 194.762194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:41.868712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.773419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T13:35:41.868954Z","caller":"traceutil/trace.go:171","msg":"trace[1696843102] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"457.743875ms","start":"2026-03-19T13:35:41.411193Z","end":"2026-03-19T13:35:41.868937Z","steps":["trace[1696843102] 'process raft request' (duration: 457.623033ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:41.868966Z","caller":"traceutil/trace.go:171","msg":"trace[997027596] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"462.425925ms","start":"2026-03-19T13:35:41.406501Z","end":"2026-03-19T13:35:41.868926Z","steps":["trace[997027596] 'read index received' (duration: 43.440883ms)","trace[997027596] 'applied index is now lower than readState.Index' (duration: 418.980712ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:41.86906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:41.411171Z","time spent":"457.825836ms","remote":"127.0.0.1:34436","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-19T13:35:41.869314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.417938ms","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-19T13:35:41.869364Z","caller":"traceutil/trace.go:171","msg":"trace[623458416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"556.504909ms","start":"2026-03-19T13:35:41.312844Z","end":"2026-03-19T13:35:41.869348Z","steps":["trace[623458416] 'agreement among raft nodes before linearized reading' (duration: 556.243494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:41.869403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:41.31283Z","time spent":"556.564171ms","remote":"127.0.0.1:34436","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-19T13:35:41.869561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.039823ms","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-19T13:35:41.86993Z","caller":"traceutil/trace.go:171","msg":"trace[654999201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"214.444881ms","start":"2026-03-19T13:35:41.655465Z","end":"2026-03-19T13:35:41.86991Z","steps":["trace[654999201] 'agreement among raft nodes before linearized reading' (duration: 213.673267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:42.439728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156305968,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:42.58537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.703862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:42.585564Z","caller":"traceutil/trace.go:171","msg":"trace[1813814816] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:502; }","duration":"646.069055ms","start":"2026-03-19T13:35:41.939479Z","end":"2026-03-19T13:35:42.585548Z","steps":["trace[1813814816] 'read index received' (duration: 511.108449ms)","trace[1813814816] 'applied index is now lower than readState.Index' (duration: 134.959656ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:42.585728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.255749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-klzf7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-19T13:35:42.585757Z","caller":"traceutil/trace.go:171","msg":"trace[1884490504] range","detail":"{range_begin:/registry/pods/kube-system/cilium-klzf7; range_end:; response_count:1; response_revision:487; }","duration":"646.32889ms","start":"2026-03-19T13:35:41.939419Z","end":"2026-03-19T13:35:42.585748Z","steps":["trace[1884490504] 'agreement among raft nodes before linearized reading' (duration: 646.174467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:42.585788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:41.939398Z","time spent":"646.383661ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-klzf7\" "} {"level":"info","ts":"2026-03-19T13:35:42.58581Z","caller":"traceutil/trace.go:171","msg":"trace[1658751528] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"710.789656ms","start":"2026-03-19T13:35:41.874988Z","end":"2026-03-19T13:35:42.585778Z","steps":["trace[1658751528] 'process raft request' (duration: 710.47918ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:42.585844Z","caller":"traceutil/trace.go:171","msg":"trace[443777004] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"711.592261ms","start":"2026-03-19T13:35:41.874221Z","end":"2026-03-19T13:35:42.585813Z","steps":["trace[443777004] 'process raft request' (duration: 576.352039ms)","trace[443777004] 'compare' (duration: 134.398627ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:42.585958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:41.874965Z","time spent":"710.912468ms","remote":"127.0.0.1:34436","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-19T13:35:42.585978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:41.874199Z","time spent":"711.709525ms","remote":"127.0.0.1:34436","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-19T13:35:43.548671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156305971,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:44.049273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156305971,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:35:44.152079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.551755168s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:35:44.152489Z","caller":"traceutil/trace.go:171","msg":"trace[403031921] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"1.103865242s","start":"2026-03-19T13:35:43.048601Z","end":"2026-03-19T13:35:44.152466Z","steps":["trace[403031921] 'read index received' (duration: 1.103640258s)","trace[403031921] 'applied index is now lower than readState.Index' (duration: 223.924µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:44.152592Z","caller":"traceutil/trace.go:171","msg":"trace[387885274] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.552361079s","start":"2026-03-19T13:35:42.600221Z","end":"2026-03-19T13:35:44.152582Z","steps":["trace[387885274] 'process raft request' (duration: 1.552083264s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:44.152698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:42.600202Z","time spent":"1.552418181s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:35:44.152714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.930183ms","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-19T13:35:44.152731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104102237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:35:44.15274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.52654ms","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-19T13:35:44.152786Z","caller":"traceutil/trace.go:171","msg":"trace[713075458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"743.055325ms","start":"2026-03-19T13:35:43.409711Z","end":"2026-03-19T13:35:44.152767Z","steps":["trace[713075458] 'agreement among raft nodes before linearized reading' (duration: 742.909272ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:44.152805Z","caller":"traceutil/trace.go:171","msg":"trace[713722926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"279.637991ms","start":"2026-03-19T13:35:43.873149Z","end":"2026-03-19T13:35:44.152787Z","steps":["trace[713722926] 'agreement among raft nodes before linearized reading' (duration: 279.511878ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:44.152808Z","caller":"traceutil/trace.go:171","msg":"trace[1823539466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"1.10421545s","start":"2026-03-19T13:35:43.04856Z","end":"2026-03-19T13:35:44.152776Z","steps":["trace[1823539466] 'agreement among raft nodes before linearized reading' (duration: 1.104080897s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:44.152832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:43.40968Z","time spent":"743.138626ms","remote":"127.0.0.1:34436","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-19T13:35:44.152861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:43.048542Z","time spent":"1.104309411s","remote":"127.0.0.1:34200","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-19T13:35:45.24169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.197117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:45.241889Z","caller":"traceutil/trace.go:171","msg":"trace[1715650509] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"1.080210178s","start":"2026-03-19T13:35:44.161659Z","end":"2026-03-19T13:35:45.241869Z","steps":["trace[1715650509] 'read index received' (duration: 143.757196ms)","trace[1715650509] 'applied index is now lower than readState.Index' (duration: 936.451832ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:45.242053Z","caller":"traceutil/trace.go:171","msg":"trace[1330012346] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.085040571s","start":"2026-03-19T13:35:44.156996Z","end":"2026-03-19T13:35:45.242036Z","steps":["trace[1330012346] 'process raft request' (duration: 148.427815ms)","trace[1330012346] 'compare' (duration: 936.032824ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:45.24213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.650162ms","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-19T13:35:45.242151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:44.156976Z","time spent":"1.085127093s","remote":"127.0.0.1:34436","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-19T13:35:45.242185Z","caller":"traceutil/trace.go:171","msg":"trace[2117586970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"652.746595ms","start":"2026-03-19T13:35:44.589425Z","end":"2026-03-19T13:35:45.242171Z","steps":["trace[2117586970] 'agreement among raft nodes before linearized reading' (duration: 652.625812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:45.242219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:44.589405Z","time spent":"652.806365ms","remote":"127.0.0.1:34436","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-19T13:35:45.242345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.603692ms","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-19T13:35:45.242425Z","caller":"traceutil/trace.go:171","msg":"trace[945298591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"651.712875ms","start":"2026-03-19T13:35:44.590689Z","end":"2026-03-19T13:35:45.242402Z","steps":["trace[945298591] 'agreement among raft nodes before linearized reading' (duration: 651.561372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:45.242474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:44.59068Z","time spent":"651.781186ms","remote":"127.0.0.1:34436","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-19T13:35:45.242805Z","caller":"traceutil/trace.go:171","msg":"trace[891383865] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.085213485s","start":"2026-03-19T13:35:44.157575Z","end":"2026-03-19T13:35:45.242789Z","steps":["trace[891383865] 'process raft request' (duration: 1.084227206s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:45.243031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:44.157561Z","time spent":"1.085306326s","remote":"127.0.0.1:34436","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-19T13:35:45.243155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080917913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:35:45.243252Z","caller":"traceutil/trace.go:171","msg":"trace[561696293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"1.081624895s","start":"2026-03-19T13:35:44.161611Z","end":"2026-03-19T13:35:45.243236Z","steps":["trace[561696293] 'agreement among raft nodes before linearized reading' (duration: 1.080418043s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:45.243292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:44.161523Z","time spent":"1.081757958s","remote":"127.0.0.1:34200","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-19T13:35:45.350086Z","caller":"traceutil/trace.go:171","msg":"trace[2056603424] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"103.714728ms","start":"2026-03-19T13:35:45.246354Z","end":"2026-03-19T13:35:45.350068Z","steps":["trace[2056603424] 'process raft request' (duration: 103.417772ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:45.350085Z","caller":"traceutil/trace.go:171","msg":"trace[774278833] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"103.867601ms","start":"2026-03-19T13:35:45.24619Z","end":"2026-03-19T13:35:45.350057Z","steps":["trace[774278833] 'process raft request' (duration: 102.329091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:47.443964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.619848ms","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-19T13:35:47.444108Z","caller":"traceutil/trace.go:171","msg":"trace[1906511617] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:525; }","duration":"294.781341ms","start":"2026-03-19T13:35:47.149309Z","end":"2026-03-19T13:35:47.44409Z","steps":["trace[1906511617] 'range keys from in-memory index tree' (duration: 294.604378ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:47.449759Z","caller":"traceutil/trace.go:171","msg":"trace[1020438710] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"202.722356ms","start":"2026-03-19T13:35:47.247019Z","end":"2026-03-19T13:35:47.449742Z","steps":["trace[1020438710] 'read index received' (duration: 202.692916ms)","trace[1020438710] 'applied index is now lower than readState.Index' (duration: 28.46µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:47.449875Z","caller":"traceutil/trace.go:171","msg":"trace[891514437] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"208.808353ms","start":"2026-03-19T13:35:47.241038Z","end":"2026-03-19T13:35:47.449846Z","steps":["trace[891514437] 'process raft request' (duration: 208.588139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:47.449943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.91197ms","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-19T13:35:47.450021Z","caller":"traceutil/trace.go:171","msg":"trace[413822136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"203.033553ms","start":"2026-03-19T13:35:47.24697Z","end":"2026-03-19T13:35:47.450004Z","steps":["trace[413822136] 'agreement among raft nodes before linearized reading' (duration: 202.854099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:47.453403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.782945ms","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-19T13:35:47.453461Z","caller":"traceutil/trace.go:171","msg":"trace[1125572598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"205.849146ms","start":"2026-03-19T13:35:47.247597Z","end":"2026-03-19T13:35:47.453446Z","steps":["trace[1125572598] 'agreement among raft nodes before linearized reading' (duration: 205.267336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:57.183825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.413646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:57.183918Z","caller":"traceutil/trace.go:171","msg":"trace[1462915553] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"147.56468ms","start":"2026-03-19T13:35:57.036342Z","end":"2026-03-19T13:35:57.183906Z","steps":["trace[1462915553] 'read index received' (duration: 22.928611ms)","trace[1462915553] 'applied index is now lower than readState.Index' (duration: 124.635229ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:57.183987Z","caller":"traceutil/trace.go:171","msg":"trace[457601402] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"178.595184ms","start":"2026-03-19T13:35:57.005356Z","end":"2026-03-19T13:35:57.183951Z","steps":["trace[457601402] 'process raft request' (duration: 54.002015ms)","trace[457601402] 'compare' (duration: 124.317174ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:57.184044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.696903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-jwgfx\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-03-19T13:35:57.184086Z","caller":"traceutil/trace.go:171","msg":"trace[182291134] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-jwgfx; range_end:; response_count:1; response_revision:627; }","duration":"147.765773ms","start":"2026-03-19T13:35:57.036309Z","end":"2026-03-19T13:35:57.184075Z","steps":["trace[182291134] 'agreement among raft nodes before linearized reading' (duration: 147.629091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:57.631038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.429531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:35:57.631161Z","caller":"traceutil/trace.go:171","msg":"trace[1600728557] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"439.049127ms","start":"2026-03-19T13:35:57.19209Z","end":"2026-03-19T13:35:57.63114Z","steps":["trace[1600728557] 'process raft request' (duration: 103.454123ms)","trace[1600728557] 'compare' (duration: 335.249227ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:35:57.631219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:57.192069Z","time spent":"439.126939ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":762,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:35:57.634421Z","caller":"traceutil/trace.go:171","msg":"trace[811257259] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"136.255592ms","start":"2026-03-19T13:35:57.498146Z","end":"2026-03-19T13:35:57.634401Z","steps":["trace[811257259] 'read index received' (duration: 136.091529ms)","trace[811257259] 'applied index is now lower than readState.Index' (duration: 163.383µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:35:57.634716Z","caller":"traceutil/trace.go:171","msg":"trace[64073022] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"438.221151ms","start":"2026-03-19T13:35:57.196459Z","end":"2026-03-19T13:35:57.63468Z","steps":["trace[64073022] 'process raft request' (duration: 437.833153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:57.634804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.937881ms","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-19T13:35:57.634829Z","caller":"traceutil/trace.go:171","msg":"trace[1274509791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"130.971472ms","start":"2026-03-19T13:35:57.503849Z","end":"2026-03-19T13:35:57.634821Z","steps":["trace[1274509791] 'agreement among raft nodes before linearized reading' (duration: 130.919671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:57.634826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6466ms","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-19T13:35:57.634865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:35:57.19645Z","time spent":"438.337974ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5067,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:35:57.63491Z","caller":"traceutil/trace.go:171","msg":"trace[1994100310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"136.763373ms","start":"2026-03-19T13:35:57.498118Z","end":"2026-03-19T13:35:57.634882Z","steps":["trace[1994100310] 'agreement among raft nodes before linearized reading' (duration: 136.533649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:35:57.634752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.377008ms","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-19T13:35:57.635014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.43712ms","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-19T13:35:57.635084Z","caller":"traceutil/trace.go:171","msg":"trace[556606609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"132.524821ms","start":"2026-03-19T13:35:57.502544Z","end":"2026-03-19T13:35:57.635069Z","steps":["trace[556606609] 'agreement among raft nodes before linearized reading' (duration: 132.292927ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:35:57.635045Z","caller":"traceutil/trace.go:171","msg":"trace[996877889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"132.693585ms","start":"2026-03-19T13:35:57.502345Z","end":"2026-03-19T13:35:57.635039Z","steps":["trace[996877889] 'agreement among raft nodes before linearized reading' (duration: 132.315788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:04.173638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:36:04.674063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:36:04.911057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240847968s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:36:04.911463Z","caller":"traceutil/trace.go:171","msg":"trace[1126533182] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.241305336s","start":"2026-03-19T13:36:03.670131Z","end":"2026-03-19T13:36:04.911436Z","steps":["trace[1126533182] 'process raft request' (duration: 1.241091783s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:04.911683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:03.670114Z","time spent":"1.241426809s","remote":"127.0.0.1:34436","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-19T13:36:05.671135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.984047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:36:05.671232Z","caller":"traceutil/trace.go:171","msg":"trace[98996947] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"1.997801073s","start":"2026-03-19T13:36:03.673414Z","end":"2026-03-19T13:36:05.671215Z","steps":["trace[98996947] 'read index received' (duration: 1.23780653s)","trace[98996947] 'applied index is now lower than readState.Index' (duration: 759.993193ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:05.671331Z","caller":"traceutil/trace.go:171","msg":"trace[1513432536] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.997938567s","start":"2026-03-19T13:36:03.673359Z","end":"2026-03-19T13:36:05.671298Z","steps":["trace[1513432536] 'process raft request' (duration: 1.753697924s)","trace[1513432536] 'compare' (duration: 243.658911ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:36:05.671365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997930767s","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-19T13:36:05.67143Z","caller":"traceutil/trace.go:171","msg":"trace[1961334100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"1.998002508s","start":"2026-03-19T13:36:03.673412Z","end":"2026-03-19T13:36:05.671414Z","steps":["trace[1961334100] 'agreement among raft nodes before linearized reading' (duration: 1.997891326s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:05.671457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:03.673346Z","time spent":"1.998045879s","remote":"127.0.0.1:34436","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-19T13:36:05.671467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:03.673396Z","time spent":"1.998061829s","remote":"127.0.0.1:34436","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-19T13:36:05.705385Z","caller":"traceutil/trace.go:171","msg":"trace[1322994457] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"463.989451ms","start":"2026-03-19T13:36:05.241383Z","end":"2026-03-19T13:36:05.705372Z","steps":["trace[1322994457] 'process raft request' (duration: 463.916069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:05.705479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.241361Z","time spent":"464.068853ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:36:05.705502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.032051263s","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-19T13:36:05.70552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.042536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:36:05.705577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657175257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:05.705578Z","caller":"traceutil/trace.go:171","msg":"trace[1667089701] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:685; }","duration":"517.110447ms","start":"2026-03-19T13:36:05.188454Z","end":"2026-03-19T13:36:05.705564Z","steps":["trace[1667089701] 'agreement among raft nodes before linearized reading' (duration: 517.003845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:05.705577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.041175ms","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-19T13:36:05.7056Z","caller":"traceutil/trace.go:171","msg":"trace[1857091296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.657245969s","start":"2026-03-19T13:36:04.048347Z","end":"2026-03-19T13:36:05.705593Z","steps":["trace[1857091296] 'agreement among raft nodes before linearized reading' (duration: 1.657210408s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:05.70563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:04.048317Z","time spent":"1.657306859s","remote":"127.0.0.1:34200","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-19T13:36:05.705628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.1884Z","time spent":"517.207219ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-03-19T13:36:05.705539Z","caller":"traceutil/trace.go:171","msg":"trace[1928744102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"2.032096724s","start":"2026-03-19T13:36:03.673431Z","end":"2026-03-19T13:36:05.705528Z","steps":["trace[1928744102] 'agreement among raft nodes before linearized reading' (duration: 2.032005162s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:05.70564Z","caller":"traceutil/trace.go:171","msg":"trace[800744380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"760.143987ms","start":"2026-03-19T13:36:04.945474Z","end":"2026-03-19T13:36:05.705618Z","steps":["trace[800744380] 'agreement among raft nodes before linearized reading' (duration: 760.013075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:05.705665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:03.673428Z","time spent":"2.032227506s","remote":"127.0.0.1:34436","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-19T13:36:05.705685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:04.945455Z","time spent":"760.221469ms","remote":"127.0.0.1:34436","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-19T13:36:06.206423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306339,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T13:36:06.295997Z","caller":"traceutil/trace.go:171","msg":"trace[514916985] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"621.589849ms","start":"2026-03-19T13:36:05.67437Z","end":"2026-03-19T13:36:06.29596Z","steps":["trace[514916985] 'process raft request' (duration: 620.977257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:06.296184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.674356Z","time spent":"621.722892ms","remote":"127.0.0.1:34436","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-19T13:36:06.479503Z","caller":"traceutil/trace.go:171","msg":"trace[1046100700] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"769.426394ms","start":"2026-03-19T13:36:05.710061Z","end":"2026-03-19T13:36:06.479488Z","steps":["trace[1046100700] 'process raft request' (duration: 680.390505ms)","trace[1046100700] 'compare' (duration: 88.861046ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:06.479504Z","caller":"traceutil/trace.go:171","msg":"trace[1374637099] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"769.320812ms","start":"2026-03-19T13:36:05.71016Z","end":"2026-03-19T13:36:06.47948Z","steps":["trace[1374637099] 'process raft request' (duration: 769.242751ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:06.479561Z","caller":"traceutil/trace.go:171","msg":"trace[2057320255] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"531.190035ms","start":"2026-03-19T13:36:05.948344Z","end":"2026-03-19T13:36:06.479534Z","steps":["trace[2057320255] 'process raft request' (duration: 531.086623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:06.479623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.710052Z","time spent":"769.508746ms","remote":"127.0.0.1:34436","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-19T13:36:06.479626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.710137Z","time spent":"769.428714ms","remote":"127.0.0.1:34436","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-19T13:36:06.479682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.948319Z","time spent":"531.298268ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:36:06.479852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.535201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:06.479896Z","caller":"traceutil/trace.go:171","msg":"trace[694963552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"773.607024ms","start":"2026-03-19T13:36:05.706278Z","end":"2026-03-19T13:36:06.479885Z","steps":["trace[694963552] 'agreement among raft nodes before linearized reading' (duration: 773.530682ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:06.479907Z","caller":"traceutil/trace.go:171","msg":"trace[53759413] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:708; }","duration":"773.160624ms","start":"2026-03-19T13:36:05.7063Z","end":"2026-03-19T13:36:06.479461Z","steps":["trace[53759413] 'read index received' (duration: 588.889748ms)","trace[53759413] 'applied index is now lower than readState.Index' (duration: 184.269966ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:36:06.479932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:05.70627Z","time spent":"773.655414ms","remote":"127.0.0.1:34208","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-19T13:36:06.479994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.936839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:06.480024Z","caller":"traceutil/trace.go:171","msg":"trace[802172679] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:689; }","duration":"106.001371ms","start":"2026-03-19T13:36:06.374014Z","end":"2026-03-19T13:36:06.480015Z","steps":["trace[802172679] 'agreement among raft nodes before linearized reading' (duration: 105.9513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:06.480025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.526642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T13:36:06.480044Z","caller":"traceutil/trace.go:171","msg":"trace[333931733] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:689; }","duration":"288.577693ms","start":"2026-03-19T13:36:06.19146Z","end":"2026-03-19T13:36:06.480038Z","steps":["trace[333931733] 'agreement among raft nodes before linearized reading' (duration: 288.498132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.54827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:07.986078Z","caller":"traceutil/trace.go:171","msg":"trace[1793955950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"939.665912ms","start":"2026-03-19T13:36:07.046398Z","end":"2026-03-19T13:36:07.986064Z","steps":["trace[1793955950] 'range keys from in-memory index tree' (duration: 939.500139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:07.046384Z","time spent":"939.716863ms","remote":"127.0.0.1:34208","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-19T13:36:07.986226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.824672ms","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-19T13:36:07.986274Z","caller":"traceutil/trace.go:171","msg":"trace[1777140854] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:690; }","duration":"836.874723ms","start":"2026-03-19T13:36:07.14939Z","end":"2026-03-19T13:36:07.986265Z","steps":["trace[1777140854] 'range keys from in-memory index tree' (duration: 836.814931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377844803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T13:36:07.986378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.462007ms","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-19T13:36:07.986415Z","caller":"traceutil/trace.go:171","msg":"trace[593848471] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:690; }","duration":"1.377927704s","start":"2026-03-19T13:36:06.608476Z","end":"2026-03-19T13:36:07.986403Z","steps":["trace[593848471] 'count revisions from in-memory index tree' (duration: 1.377795822s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:06.608455Z","time spent":"1.377980126s","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":81,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-19T13:36:07.986445Z","caller":"traceutil/trace.go:171","msg":"trace[571911805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"311.5815ms","start":"2026-03-19T13:36:07.674846Z","end":"2026-03-19T13:36:07.986427Z","steps":["trace[571911805] 'range keys from in-memory index tree' (duration: 311.288884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:07.674825Z","time spent":"311.658241ms","remote":"127.0.0.1:34436","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-19T13:36:07.986551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098406273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:07.986566Z","caller":"traceutil/trace.go:171","msg":"trace[323563843] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:690; }","duration":"1.098465884s","start":"2026-03-19T13:36:06.888096Z","end":"2026-03-19T13:36:07.986562Z","steps":["trace[323563843] 'count revisions from in-memory index tree' (duration: 1.09832725s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:06.888073Z","time spent":"1.098502894s","remote":"127.0.0.1:34322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:36:07.986664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070466143s","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-19T13:36:07.98669Z","caller":"traceutil/trace.go:171","msg":"trace[704707629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"1.070516833s","start":"2026-03-19T13:36:06.916166Z","end":"2026-03-19T13:36:07.986683Z","steps":["trace[704707629] 'range keys from in-memory index tree' (duration: 1.070356381s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:07.986708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:06.916148Z","time spent":"1.070554604s","remote":"127.0.0.1:34436","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-19T13:36:08.331822Z","caller":"traceutil/trace.go:171","msg":"trace[441553811] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"341.969946ms","start":"2026-03-19T13:36:07.989834Z","end":"2026-03-19T13:36:08.331804Z","steps":["trace[441553811] 'process raft request' (duration: 341.877855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:08.331929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:07.989814Z","time spent":"342.061609ms","remote":"127.0.0.1:34436","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-19T13:36:08.603146Z","caller":"traceutil/trace.go:171","msg":"trace[705315147] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"555.18639ms","start":"2026-03-19T13:36:08.04794Z","end":"2026-03-19T13:36:08.603126Z","steps":["trace[705315147] 'read index received' (duration: 283.830294ms)","trace[705315147] 'applied index is now lower than readState.Index' (duration: 271.355026ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:08.603398Z","caller":"traceutil/trace.go:171","msg":"trace[1481990013] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"613.501027ms","start":"2026-03-19T13:36:07.989876Z","end":"2026-03-19T13:36:08.603377Z","steps":["trace[1481990013] 'process raft request' (duration: 611.56286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:08.603456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.497006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:36:08.60349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.942175ms","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-19T13:36:08.603496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:07.989856Z","time spent":"613.589339ms","remote":"127.0.0.1:34436","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-19T13:36:08.603483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.322564ms","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-19T13:36:08.60351Z","caller":"traceutil/trace.go:171","msg":"trace[1318384300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"555.593837ms","start":"2026-03-19T13:36:08.047901Z","end":"2026-03-19T13:36:08.603495Z","steps":["trace[1318384300] 'agreement among raft nodes before linearized reading' (duration: 555.454545ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:08.60353Z","caller":"traceutil/trace.go:171","msg":"trace[76264876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"304.023586ms","start":"2026-03-19T13:36:08.299496Z","end":"2026-03-19T13:36:08.603519Z","steps":["trace[76264876] 'agreement among raft nodes before linearized reading' (duration: 303.879033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:08.603539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.498667ms","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-19T13:36:08.603548Z","caller":"traceutil/trace.go:171","msg":"trace[1580155645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:692; }","duration":"119.437686ms","start":"2026-03-19T13:36:08.484096Z","end":"2026-03-19T13:36:08.603534Z","steps":["trace[1580155645] 'agreement among raft nodes before linearized reading' (duration: 119.288813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:08.603548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:08.047886Z","time spent":"555.654459ms","remote":"127.0.0.1:34200","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-19T13:36:08.603562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:08.299479Z","time spent":"304.075088ms","remote":"127.0.0.1:34436","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-19T13:36:08.603575Z","caller":"traceutil/trace.go:171","msg":"trace[1201518591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"119.569429ms","start":"2026-03-19T13:36:08.483995Z","end":"2026-03-19T13:36:08.603565Z","steps":["trace[1201518591] 'agreement among raft nodes before linearized reading' (duration: 119.430316ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:09.085236Z","caller":"traceutil/trace.go:171","msg":"trace[2070941882] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"103.057345ms","start":"2026-03-19T13:36:08.982138Z","end":"2026-03-19T13:36:09.085195Z","steps":["trace[2070941882] 'process raft request' (duration: 102.827781ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:28.987666Z","caller":"traceutil/trace.go:171","msg":"trace[922688257] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"292.850084ms","start":"2026-03-19T13:36:28.694793Z","end":"2026-03-19T13:36:28.987643Z","steps":["trace[922688257] 'read index received' (duration: 292.676951ms)","trace[922688257] 'applied index is now lower than readState.Index' (duration: 170.003µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:28.987734Z","caller":"traceutil/trace.go:171","msg":"trace[777781537] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"550.335679ms","start":"2026-03-19T13:36:28.437368Z","end":"2026-03-19T13:36:28.987704Z","steps":["trace[777781537] 'process raft request' (duration: 550.149165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:28.988064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.275763ms","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-19T13:36:28.988104Z","caller":"traceutil/trace.go:171","msg":"trace[1124646296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"293.337804ms","start":"2026-03-19T13:36:28.694755Z","end":"2026-03-19T13:36:28.988093Z","steps":["trace[1124646296] 'agreement among raft nodes before linearized reading' (duration: 293.009758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:28.987971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.407532ms","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-19T13:36:28.988217Z","caller":"traceutil/trace.go:171","msg":"trace[537076311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"267.690327ms","start":"2026-03-19T13:36:28.720505Z","end":"2026-03-19T13:36:28.988196Z","steps":["trace[537076311] 'agreement among raft nodes before linearized reading' (duration: 267.30473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:28.987978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.75243ms","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-19T13:36:28.988325Z","caller":"traceutil/trace.go:171","msg":"trace[788962130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"267.115606ms","start":"2026-03-19T13:36:28.72118Z","end":"2026-03-19T13:36:28.988295Z","steps":["trace[788962130] 'agreement among raft nodes before linearized reading' (duration: 266.660668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:28.98799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.28891ms","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-19T13:36:28.988397Z","caller":"traceutil/trace.go:171","msg":"trace[702458926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"267.718048ms","start":"2026-03-19T13:36:28.720667Z","end":"2026-03-19T13:36:28.988385Z","steps":["trace[702458926] 'agreement among raft nodes before linearized reading' (duration: 267.169448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:28.988387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:28.437354Z","time spent":"550.757247ms","remote":"127.0.0.1:34436","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-19T13:36:29.309479Z","caller":"traceutil/trace.go:171","msg":"trace[1402892568] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"317.582664ms","start":"2026-03-19T13:36:28.991864Z","end":"2026-03-19T13:36:29.309447Z","steps":["trace[1402892568] 'process raft request' (duration: 317.426821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:29.30975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:28.991844Z","time spent":"317.830308ms","remote":"127.0.0.1:34436","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-19T13:36:29.323359Z","caller":"traceutil/trace.go:171","msg":"trace[371740995] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"330.350485ms","start":"2026-03-19T13:36:28.992986Z","end":"2026-03-19T13:36:29.323336Z","steps":["trace[371740995] 'process raft request' (duration: 330.294644ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:29.323415Z","caller":"traceutil/trace.go:171","msg":"trace[1739940596] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"331.009627ms","start":"2026-03-19T13:36:28.99238Z","end":"2026-03-19T13:36:29.32339Z","steps":["trace[1739940596] 'process raft request' (duration: 330.861634ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:29.323394Z","caller":"traceutil/trace.go:171","msg":"trace[127060026] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"331.13508ms","start":"2026-03-19T13:36:28.992234Z","end":"2026-03-19T13:36:29.323369Z","steps":["trace[127060026] 'process raft request' (duration: 330.869585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:29.323506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:28.992976Z","time spent":"330.451787ms","remote":"127.0.0.1:34436","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-19T13:36:29.323637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:28.992369Z","time spent":"331.09658ms","remote":"127.0.0.1:34436","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-19T13:36:29.323642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:28.992213Z","time spent":"331.360324ms","remote":"127.0.0.1:34436","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-19T13:36:31.430013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.644749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:36:31.430122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.622252ms","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-19T13:36:31.43016Z","caller":"traceutil/trace.go:171","msg":"trace[1777619982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"380.871654ms","start":"2026-03-19T13:36:31.04927Z","end":"2026-03-19T13:36:31.430142Z","steps":["trace[1777619982] 'range keys from in-memory index tree' (duration: 380.525167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:31.430322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:31.049247Z","time spent":"381.061048ms","remote":"127.0.0.1:34200","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-19T13:36:31.430433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.646896ms","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-19T13:36:31.430549Z","caller":"traceutil/trace.go:171","msg":"trace[712007233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"103.741257ms","start":"2026-03-19T13:36:31.326755Z","end":"2026-03-19T13:36:31.430497Z","steps":["trace[712007233] 'range keys from in-memory index tree' (duration: 103.400901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:31.430544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.945122ms","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-19T13:36:31.430571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.743037ms","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-19T13:36:31.430619Z","caller":"traceutil/trace.go:171","msg":"trace[56866904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"103.064234ms","start":"2026-03-19T13:36:31.327535Z","end":"2026-03-19T13:36:31.4306Z","steps":["trace[56866904] 'range keys from in-memory index tree' (duration: 102.81313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:31.430663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.873184ms","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-19T13:36:31.43069Z","caller":"traceutil/trace.go:171","msg":"trace[1006570809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"103.84507ms","start":"2026-03-19T13:36:31.326798Z","end":"2026-03-19T13:36:31.430643Z","steps":["trace[1006570809] 'range keys from in-memory index tree' (duration: 103.571844ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:31.430708Z","caller":"traceutil/trace.go:171","msg":"trace[653997013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"437.955056ms","start":"2026-03-19T13:36:30.992743Z","end":"2026-03-19T13:36:31.430698Z","steps":["trace[653997013] 'range keys from in-memory index tree' (duration: 437.761252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:31.430745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:30.992725Z","time spent":"438.012086ms","remote":"127.0.0.1:34436","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-19T13:36:31.430199Z","caller":"traceutil/trace.go:171","msg":"trace[1631504282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"116.745304ms","start":"2026-03-19T13:36:31.313436Z","end":"2026-03-19T13:36:31.430182Z","steps":["trace[1631504282] 'range keys from in-memory index tree' (duration: 116.443209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:31.430211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.965268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:31.43093Z","caller":"traceutil/trace.go:171","msg":"trace[1496415256] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:758; }","duration":"108.775753ms","start":"2026-03-19T13:36:31.322144Z","end":"2026-03-19T13:36:31.43092Z","steps":["trace[1496415256] 'count revisions from in-memory index tree' (duration: 107.821455ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:31.702834Z","caller":"traceutil/trace.go:171","msg":"trace[383578061] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"268.645225ms","start":"2026-03-19T13:36:31.43416Z","end":"2026-03-19T13:36:31.702805Z","steps":["trace[383578061] 'process raft request' (duration: 268.446722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:32.893231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.187043844s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:36:32.893868Z","caller":"traceutil/trace.go:171","msg":"trace[1278750598] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.459111184s","start":"2026-03-19T13:36:31.434731Z","end":"2026-03-19T13:36:32.893842Z","steps":["trace[1278750598] 'process raft request' (duration: 1.45886978s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:32.894044Z","caller":"traceutil/trace.go:171","msg":"trace[1668925835] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.45836572s","start":"2026-03-19T13:36:31.435665Z","end":"2026-03-19T13:36:32.894031Z","steps":["trace[1668925835] 'process raft request' (duration: 1.458147266s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:32.894072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:31.434711Z","time spent":"1.459285047s","remote":"127.0.0.1:34436","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-19T13:36:32.894149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:31.43565Z","time spent":"1.458434862s","remote":"127.0.0.1:34436","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-19T13:36:32.89444Z","caller":"traceutil/trace.go:171","msg":"trace[457002853] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.458622836s","start":"2026-03-19T13:36:31.435674Z","end":"2026-03-19T13:36:32.894297Z","steps":["trace[457002853] 'process raft request' (duration: 1.458303099s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:32.894561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:31.435648Z","time spent":"1.45886126s","remote":"127.0.0.1:34436","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-19T13:36:32.893867Z","caller":"traceutil/trace.go:171","msg":"trace[158823403] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.458258738s","start":"2026-03-19T13:36:31.435589Z","end":"2026-03-19T13:36:32.893848Z","steps":["trace[158823403] 'process raft request' (duration: 1.458177386s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:32.894728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:31.43558Z","time spent":"1.459097104s","remote":"127.0.0.1:34436","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-19T13:36:32.995374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.154408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T13:36:45.172191Z","caller":"traceutil/trace.go:171","msg":"trace[302777486] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"167.433676ms","start":"2026-03-19T13:36:45.004736Z","end":"2026-03-19T13:36:45.172169Z","steps":["trace[302777486] 'read index received' (duration: 167.288863ms)","trace[302777486] 'applied index is now lower than readState.Index' (duration: 143.973µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:36:45.17257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.817393ms","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-19T13:36:45.172585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.727893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:36:45.172621Z","caller":"traceutil/trace.go:171","msg":"trace[348806989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:845; }","duration":"147.805754ms","start":"2026-03-19T13:36:45.024806Z","end":"2026-03-19T13:36:45.172612Z","steps":["trace[348806989] 'agreement among raft nodes before linearized reading' (duration: 147.481778ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:45.17266Z","caller":"traceutil/trace.go:171","msg":"trace[1987049483] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"195.920707ms","start":"2026-03-19T13:36:44.976729Z","end":"2026-03-19T13:36:45.17265Z","steps":["trace[1987049483] 'process raft request' (duration: 195.305105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:45.172689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.380786ms","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-19T13:36:45.172625Z","caller":"traceutil/trace.go:171","msg":"trace[724174340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"167.893945ms","start":"2026-03-19T13:36:45.004715Z","end":"2026-03-19T13:36:45.172609Z","steps":["trace[724174340] 'agreement among raft nodes before linearized reading' (duration: 167.575579ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:45.172747Z","caller":"traceutil/trace.go:171","msg":"trace[1848128026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:845; }","duration":"137.472777ms","start":"2026-03-19T13:36:45.035261Z","end":"2026-03-19T13:36:45.172733Z","steps":["trace[1848128026] 'agreement among raft nodes before linearized reading' (duration: 137.321644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:47.940084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306778,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:36:48.311136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133438098s","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-19T13:36:48.311199Z","caller":"traceutil/trace.go:171","msg":"trace[1113876212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"1.13357175s","start":"2026-03-19T13:36:47.177612Z","end":"2026-03-19T13:36:48.311184Z","steps":["trace[1113876212] 'range keys from in-memory index tree' (duration: 1.133187273s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.177569Z","time spent":"1.133650932s","remote":"127.0.0.1:34436","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-19T13:36:48.311301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947424596s","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-19T13:36:48.311354Z","caller":"traceutil/trace.go:171","msg":"trace[1405713913] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:850; }","duration":"1.947538208s","start":"2026-03-19T13:36:46.363804Z","end":"2026-03-19T13:36:48.311342Z","steps":["trace[1405713913] 'count revisions from in-memory index tree' (duration: 1.947307214s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:46.363778Z","time spent":"1.94759736s","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:36:48.311484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26326285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:36:48.311479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1288811s","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-19T13:36:48.311502Z","caller":"traceutil/trace.go:171","msg":"trace[1938723463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"1.2633155s","start":"2026-03-19T13:36:47.048181Z","end":"2026-03-19T13:36:48.311497Z","steps":["trace[1938723463] 'range keys from in-memory index tree' (duration: 1.263139548s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.048126Z","time spent":"1.263386211s","remote":"127.0.0.1:34200","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-19T13:36:48.311513Z","caller":"traceutil/trace.go:171","msg":"trace[633074028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"1.128938162s","start":"2026-03-19T13:36:47.182565Z","end":"2026-03-19T13:36:48.311503Z","steps":["trace[633074028] 'range keys from in-memory index tree' (duration: 1.128736108s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791263763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:36:48.311539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.182553Z","time spent":"1.128980703s","remote":"127.0.0.1:34436","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-19T13:36:48.311482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123697283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:36:48.311611Z","caller":"traceutil/trace.go:171","msg":"trace[288576606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"1.123930398s","start":"2026-03-19T13:36:47.187653Z","end":"2026-03-19T13:36:48.311583Z","steps":["trace[288576606] 'range keys from in-memory index tree' (duration: 1.123382617s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.187632Z","time spent":"1.124028499s","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T13:36:48.311696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124210413s","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-19T13:36:48.311718Z","caller":"traceutil/trace.go:171","msg":"trace[2042872662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"1.124286324s","start":"2026-03-19T13:36:47.187426Z","end":"2026-03-19T13:36:48.311712Z","steps":["trace[2042872662] 'range keys from in-memory index tree' (duration: 1.1240711s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.187394Z","time spent":"1.124333224s","remote":"127.0.0.1:34436","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-19T13:36:48.311641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161759265s","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-19T13:36:48.311552Z","caller":"traceutil/trace.go:171","msg":"trace[1400715509] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:850; }","duration":"1.791325675s","start":"2026-03-19T13:36:46.520218Z","end":"2026-03-19T13:36:48.311543Z","steps":["trace[1400715509] 'count revisions from in-memory index tree' (duration: 1.791161502s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.311777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:46.520197Z","time spent":"1.791567059s","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-19T13:36:48.311778Z","caller":"traceutil/trace.go:171","msg":"trace[1813614601] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:850; }","duration":"1.161909858s","start":"2026-03-19T13:36:47.149848Z","end":"2026-03-19T13:36:48.311758Z","steps":["trace[1813614601] 'range keys from in-memory index tree' (duration: 1.161739705s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.441196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306778,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:36:48.624754Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.207479823s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:36:48.625096Z","caller":"traceutil/trace.go:171","msg":"trace[1548517607] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"1.207989021s","start":"2026-03-19T13:36:47.417083Z","end":"2026-03-19T13:36:48.625072Z","steps":["trace[1548517607] 'process raft request' (duration: 1.207842099s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.625245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.417047Z","time spent":"1.208120174s","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:36:48.626199Z","caller":"traceutil/trace.go:171","msg":"trace[2109424062] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"309.201365ms","start":"2026-03-19T13:36:48.31698Z","end":"2026-03-19T13:36:48.626181Z","steps":["trace[2109424062] 'process raft request' (duration: 309.142964ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:36:48.626223Z","caller":"traceutil/trace.go:171","msg":"trace[2128775993] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"310.190274ms","start":"2026-03-19T13:36:48.316005Z","end":"2026-03-19T13:36:48.626195Z","steps":["trace[2128775993] 'process raft request' (duration: 310.003811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.626303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:48.316973Z","time spent":"309.261216ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:36:48.626385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:48.31599Z","time spent":"310.316416ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:36:48.626458Z","caller":"traceutil/trace.go:171","msg":"trace[1530482548] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"309.47821ms","start":"2026-03-19T13:36:48.316972Z","end":"2026-03-19T13:36:48.62645Z","steps":["trace[1530482548] 'process raft request' (duration: 309.180804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.626518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:48.316945Z","time spent":"309.536701ms","remote":"127.0.0.1:34436","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-19T13:36:48.626808Z","caller":"traceutil/trace.go:171","msg":"trace[307605326] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:884; }","duration":"1.187208178s","start":"2026-03-19T13:36:47.439576Z","end":"2026-03-19T13:36:48.626784Z","steps":["trace[307605326] 'read index received' (duration: 1.185365183s)","trace[307605326] 'applied index is now lower than readState.Index' (duration: 1.841545ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:36:48.626936Z","caller":"traceutil/trace.go:171","msg":"trace[690157040] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"308.644843ms","start":"2026-03-19T13:36:48.318279Z","end":"2026-03-19T13:36:48.626924Z","steps":["trace[690157040] 'process raft request' (duration: 308.145164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.627001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:48.318269Z","time spent":"308.690535ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:36:48.627107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187531023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:48.627138Z","caller":"traceutil/trace.go:171","msg":"trace[1720725612] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:855; }","duration":"1.187611585s","start":"2026-03-19T13:36:47.439518Z","end":"2026-03-19T13:36:48.627129Z","steps":["trace[1720725612] 'agreement among raft nodes before linearized reading' (duration: 1.187548874s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.627135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.829332ms","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-19T13:36:48.627167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.439496Z","time spent":"1.187659085s","remote":"127.0.0.1:34386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-03-19T13:36:48.627177Z","caller":"traceutil/trace.go:171","msg":"trace[1354033001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:855; }","duration":"807.931184ms","start":"2026-03-19T13:36:47.819234Z","end":"2026-03-19T13:36:48.627165Z","steps":["trace[1354033001] 'agreement among raft nodes before linearized reading' (duration: 807.806841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.627232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:47.819203Z","time spent":"807.998114ms","remote":"127.0.0.1:34436","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-19T13:36:48.627356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.21334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:36:48.62743Z","caller":"traceutil/trace.go:171","msg":"trace[112400038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"314.318532ms","start":"2026-03-19T13:36:48.313094Z","end":"2026-03-19T13:36:48.627412Z","steps":["trace[112400038] 'agreement among raft nodes before linearized reading' (duration: 314.193719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:36:48.627468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:36:48.313081Z","time spent":"314.380063ms","remote":"127.0.0.1:34208","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-19T13:37:02.895731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.138269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:02.895852Z","caller":"traceutil/trace.go:171","msg":"trace[1714846881] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"156.380515ms","start":"2026-03-19T13:37:02.739447Z","end":"2026-03-19T13:37:02.895827Z","steps":["trace[1714846881] 'process raft request' (duration: 36.061173ms)","trace[1714846881] 'compare' (duration: 120.050627ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:02.974183Z","caller":"traceutil/trace.go:171","msg":"trace[1270254537] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"233.727263ms","start":"2026-03-19T13:37:02.740433Z","end":"2026-03-19T13:37:02.97416Z","steps":["trace[1270254537] 'process raft request' (duration: 233.580671ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:02.974527Z","caller":"traceutil/trace.go:171","msg":"trace[926330750] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"233.927167ms","start":"2026-03-19T13:37:02.740589Z","end":"2026-03-19T13:37:02.974517Z","steps":["trace[926330750] 'process raft request' (duration: 233.708253ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:02.974562Z","caller":"traceutil/trace.go:171","msg":"trace[2059137099] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"233.969408ms","start":"2026-03-19T13:37:02.740573Z","end":"2026-03-19T13:37:02.974542Z","steps":["trace[2059137099] 'process raft request' (duration: 233.54769ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:05.043094Z","caller":"traceutil/trace.go:171","msg":"trace[532160022] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"253.850104ms","start":"2026-03-19T13:37:04.789222Z","end":"2026-03-19T13:37:05.043073Z","steps":["trace[532160022] 'read index received' (duration: 253.64352ms)","trace[532160022] 'applied index is now lower than readState.Index' (duration: 205.804µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:05.043208Z","caller":"traceutil/trace.go:171","msg":"trace[586540273] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"300.242894ms","start":"2026-03-19T13:37:04.742955Z","end":"2026-03-19T13:37:05.043197Z","steps":["trace[586540273] 'process raft request' (duration: 299.990029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.043328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:04.742943Z","time spent":"300.291255ms","remote":"127.0.0.1:34436","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-19T13:37:05.043525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.483221ms","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-19T13:37:05.043599Z","caller":"traceutil/trace.go:171","msg":"trace[1481934871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"143.626043ms","start":"2026-03-19T13:37:04.899955Z","end":"2026-03-19T13:37:05.043581Z","steps":["trace[1481934871] 'agreement among raft nodes before linearized reading' (duration: 143.418939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.043809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.578707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:37:05.043911Z","caller":"traceutil/trace.go:171","msg":"trace[299283494] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:923; }","duration":"254.69921ms","start":"2026-03-19T13:37:04.789198Z","end":"2026-03-19T13:37:05.043897Z","steps":["trace[299283494] 'agreement among raft nodes before linearized reading' (duration: 254.579548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:05.549643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:37:06.031427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.330647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:06.031622Z","caller":"traceutil/trace.go:171","msg":"trace[1789355556] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"983.675588ms","start":"2026-03-19T13:37:05.047928Z","end":"2026-03-19T13:37:06.031604Z","steps":["trace[1789355556] 'process raft request' (duration: 800.095746ms)","trace[1789355556] 'compare' (duration: 183.199945ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:06.031731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:05.047908Z","time spent":"983.783149ms","remote":"127.0.0.1:34436","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-19T13:37:06.113666Z","caller":"traceutil/trace.go:171","msg":"trace[1943229680] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:960; }","duration":"1.064516271s","start":"2026-03-19T13:37:05.049126Z","end":"2026-03-19T13:37:06.113642Z","steps":["trace[1943229680] 'read index received' (duration: 798.937094ms)","trace[1943229680] 'applied index is now lower than readState.Index' (duration: 265.578217ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:06.113728Z","caller":"traceutil/trace.go:171","msg":"trace[1254754947] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.064613903s","start":"2026-03-19T13:37:05.049077Z","end":"2026-03-19T13:37:06.113691Z","steps":["trace[1254754947] 'process raft request' (duration: 1.064355178s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:06.113741Z","caller":"traceutil/trace.go:171","msg":"trace[659650934] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.060492195s","start":"2026-03-19T13:37:05.053225Z","end":"2026-03-19T13:37:06.113718Z","steps":["trace[659650934] 'process raft request' (duration: 1.060378482s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:06.113822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064644363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:06.113737Z","caller":"traceutil/trace.go:171","msg":"trace[1201630864] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.063719626s","start":"2026-03-19T13:37:05.049946Z","end":"2026-03-19T13:37:06.113665Z","steps":["trace[1201630864] 'process raft request' (duration: 1.063599723s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:06.113888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:05.049056Z","time spent":"1.064748615s","remote":"127.0.0.1:34436","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-19T13:37:06.114054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:05.04993Z","time spent":"1.064020621s","remote":"127.0.0.1:34436","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-19T13:37:06.113899Z","caller":"traceutil/trace.go:171","msg":"trace[415791872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"1.064772705s","start":"2026-03-19T13:37:05.049086Z","end":"2026-03-19T13:37:06.113859Z","steps":["trace[415791872] 'agreement among raft nodes before linearized reading' (duration: 1.064645903s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:06.114209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:05.049072Z","time spent":"1.065126923s","remote":"127.0.0.1:34200","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-19T13:37:06.113888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:05.053192Z","time spent":"1.060617077s","remote":"127.0.0.1:34436","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-19T13:37:06.883041Z","caller":"traceutil/trace.go:171","msg":"trace[1302186471] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"155.217704ms","start":"2026-03-19T13:37:06.727804Z","end":"2026-03-19T13:37:06.883021Z","steps":["trace[1302186471] 'process raft request' (duration: 155.107972ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:08.305327Z","caller":"traceutil/trace.go:171","msg":"trace[685450334] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"256.647278ms","start":"2026-03-19T13:37:08.048658Z","end":"2026-03-19T13:37:08.305305Z","steps":["trace[685450334] 'read index received' (duration: 256.478864ms)","trace[685450334] 'applied index is now lower than readState.Index' (duration: 167.493µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:08.305686Z","caller":"traceutil/trace.go:171","msg":"trace[79049096] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"266.557675ms","start":"2026-03-19T13:37:08.039118Z","end":"2026-03-19T13:37:08.305675Z","steps":["trace[79049096] 'process raft request' (duration: 266.064836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:08.305884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.215908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:08.305914Z","caller":"traceutil/trace.go:171","msg":"trace[92904328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"257.271689ms","start":"2026-03-19T13:37:08.048633Z","end":"2026-03-19T13:37:08.305905Z","steps":["trace[92904328] 'agreement among raft nodes before linearized reading' (duration: 257.213568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:08.306152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.184739ms","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-19T13:37:08.306211Z","caller":"traceutil/trace.go:171","msg":"trace[960519660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"188.285171ms","start":"2026-03-19T13:37:08.11791Z","end":"2026-03-19T13:37:08.306195Z","steps":["trace[960519660] 'agreement among raft nodes before linearized reading' (duration: 188.104157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:08.306356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.592579ms","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-19T13:37:08.306378Z","caller":"traceutil/trace.go:171","msg":"trace[788388551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"187.624809ms","start":"2026-03-19T13:37:08.118746Z","end":"2026-03-19T13:37:08.306371Z","steps":["trace[788388551] 'agreement among raft nodes before linearized reading' (duration: 187.562218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:08.306475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.449555ms","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-19T13:37:08.306496Z","caller":"traceutil/trace.go:171","msg":"trace[75494817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"188.477435ms","start":"2026-03-19T13:37:08.118011Z","end":"2026-03-19T13:37:08.306488Z","steps":["trace[75494817] 'agreement among raft nodes before linearized reading' (duration: 188.417674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:08.836049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156306960,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:37:09.614791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.169818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:09.615221Z","caller":"traceutil/trace.go:171","msg":"trace[1642048650] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.305660836s","start":"2026-03-19T13:37:08.309542Z","end":"2026-03-19T13:37:09.615203Z","steps":["trace[1642048650] 'process raft request' (duration: 1.30534385s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:09.61546Z","caller":"traceutil/trace.go:171","msg":"trace[1949839182] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.305912482s","start":"2026-03-19T13:37:08.309517Z","end":"2026-03-19T13:37:09.61543Z","steps":["trace[1949839182] 'process raft request' (duration: 766.977409ms)","trace[1949839182] 'compare' (duration: 537.836552ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:09.615624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.309527Z","time spent":"1.305730637s","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:37:09.615682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.309494Z","time spent":"1.306071034s","remote":"127.0.0.1:34436","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-19T13:37:09.914767Z","caller":"traceutil/trace.go:171","msg":"trace[300358732] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.605024871s","start":"2026-03-19T13:37:08.309719Z","end":"2026-03-19T13:37:09.914744Z","steps":["trace[300358732] 'process raft request' (duration: 1.604864598s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.914939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.309708Z","time spent":"1.605130843s","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:37:09.914975Z","caller":"traceutil/trace.go:171","msg":"trace[1001872685] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:969; }","duration":"1.579801043s","start":"2026-03-19T13:37:08.335142Z","end":"2026-03-19T13:37:09.914943Z","steps":["trace[1001872685] 'read index received' (duration: 741.363633ms)","trace[1001872685] 'applied index is now lower than readState.Index' (duration: 838.43506ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:09.915032Z","caller":"traceutil/trace.go:171","msg":"trace[1987386108] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.178019973s","start":"2026-03-19T13:37:08.736994Z","end":"2026-03-19T13:37:09.915014Z","steps":["trace[1987386108] 'process raft request' (duration: 1.177708986s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.915132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.73697Z","time spent":"1.178110955s","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:37:09.915163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579995498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:09.915202Z","caller":"traceutil/trace.go:171","msg":"trace[953909855] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:935; }","duration":"1.580092379s","start":"2026-03-19T13:37:08.3351Z","end":"2026-03-19T13:37:09.915192Z","steps":["trace[953909855] 'agreement among raft nodes before linearized reading' (duration: 1.579991178s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.915242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.335086Z","time spent":"1.58014612s","remote":"127.0.0.1:34338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:37:09.915272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.121308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:09.915304Z","caller":"traceutil/trace.go:171","msg":"trace[1301395286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"866.196699ms","start":"2026-03-19T13:37:09.049098Z","end":"2026-03-19T13:37:09.915294Z","steps":["trace[1301395286] 'agreement among raft nodes before linearized reading' (duration: 866.144548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.915338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:09.049072Z","time spent":"866.26092ms","remote":"127.0.0.1:34200","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-19T13:37:09.91536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.858974ms","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-19T13:37:09.915366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365002989s","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-19T13:37:09.915445Z","caller":"traceutil/trace.go:171","msg":"trace[515996544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"740.973177ms","start":"2026-03-19T13:37:09.174447Z","end":"2026-03-19T13:37:09.91542Z","steps":["trace[515996544] 'agreement among raft nodes before linearized reading' (duration: 740.749962ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:09.915455Z","caller":"traceutil/trace.go:171","msg":"trace[26818609] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:935; }","duration":"1.365131462s","start":"2026-03-19T13:37:08.550299Z","end":"2026-03-19T13:37:09.91543Z","steps":["trace[26818609] 'agreement among raft nodes before linearized reading' (duration: 1.364914228s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:09.915491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:09.174432Z","time spent":"741.050288ms","remote":"127.0.0.1:34436","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-19T13:37:09.915497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:08.550249Z","time spent":"1.365238984s","remote":"127.0.0.1:34436","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-19T13:37:10.341635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.27775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:10.341864Z","caller":"traceutil/trace.go:171","msg":"trace[1288358324] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"422.174461ms","start":"2026-03-19T13:37:09.919673Z","end":"2026-03-19T13:37:10.341847Z","steps":["trace[1288358324] 'process raft request' (duration: 422.062639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:10.342526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:09.919657Z","time spent":"422.238912ms","remote":"127.0.0.1:34436","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-19T13:37:10.346063Z","caller":"traceutil/trace.go:171","msg":"trace[107550264] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"422.378045ms","start":"2026-03-19T13:37:09.919498Z","end":"2026-03-19T13:37:10.341876Z","steps":["trace[107550264] 'process raft request' (duration: 98.805189ms)","trace[107550264] 'compare' (duration: 323.128148ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:10.346177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:09.919475Z","time spent":"426.634585ms","remote":"127.0.0.1:34436","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-19T13:37:11.218736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.656559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:11.21906Z","caller":"traceutil/trace.go:171","msg":"trace[1208311362] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"764.25134ms","start":"2026-03-19T13:37:10.454783Z","end":"2026-03-19T13:37:11.219034Z","steps":["trace[1208311362] 'read index received' (duration: 462.215324ms)","trace[1208311362] 'applied index is now lower than readState.Index' (duration: 302.033976ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:11.219091Z","caller":"traceutil/trace.go:171","msg":"trace[1165658885] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"869.742397ms","start":"2026-03-19T13:37:10.349312Z","end":"2026-03-19T13:37:11.219054Z","steps":["trace[1165658885] 'process raft request' (duration: 567.627949ms)","trace[1165658885] 'compare' (duration: 301.454005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:11.219268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.482394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T13:37:11.219323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:10.349293Z","time spent":"869.883419ms","remote":"127.0.0.1:34436","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-19T13:37:11.219349Z","caller":"traceutil/trace.go:171","msg":"trace[2018446024] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:938; }","duration":"764.608627ms","start":"2026-03-19T13:37:10.454725Z","end":"2026-03-19T13:37:11.219333Z","steps":["trace[2018446024] 'agreement among raft nodes before linearized reading' (duration: 764.488035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:11.21949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:10.454706Z","time spent":"764.768849ms","remote":"127.0.0.1:34354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:37:11.253385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.640934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:11.253449Z","caller":"traceutil/trace.go:171","msg":"trace[278979983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"203.736995ms","start":"2026-03-19T13:37:11.049695Z","end":"2026-03-19T13:37:11.253432Z","steps":["trace[278979983] 'agreement among raft nodes before linearized reading' (duration: 203.627193ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:11.253758Z","caller":"traceutil/trace.go:171","msg":"trace[412022565] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"537.18029ms","start":"2026-03-19T13:37:10.716563Z","end":"2026-03-19T13:37:11.253744Z","steps":["trace[412022565] 'process raft request' (duration: 536.70927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:11.253853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:10.716535Z","time spent":"537.267562ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:37:11.255022Z","caller":"traceutil/trace.go:171","msg":"trace[1736381829] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"658.07833ms","start":"2026-03-19T13:37:10.596915Z","end":"2026-03-19T13:37:11.254993Z","steps":["trace[1736381829] 'process raft request' (duration: 656.234994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:11.255735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:10.596897Z","time spent":"658.193712ms","remote":"127.0.0.1:34354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6172,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:37:11.581104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.529559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:11.581203Z","caller":"traceutil/trace.go:171","msg":"trace[1284979692] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:940; }","duration":"206.701393ms","start":"2026-03-19T13:37:11.374477Z","end":"2026-03-19T13:37:11.581178Z","steps":["trace[1284979692] 'count revisions from in-memory index tree' (duration: 206.430987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.022486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.519087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:12.022839Z","caller":"traceutil/trace.go:171","msg":"trace[1169736673] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"363.585028ms","start":"2026-03-19T13:37:11.659175Z","end":"2026-03-19T13:37:12.02276Z","steps":["trace[1169736673] 'process raft request' (duration: 154.666974ms)","trace[1169736673] 'compare' (duration: 208.304132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:12.023021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:11.659156Z","time spent":"363.817822ms","remote":"127.0.0.1:34436","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-19T13:37:12.104391Z","caller":"traceutil/trace.go:171","msg":"trace[1912103062] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"189.059146ms","start":"2026-03-19T13:37:11.915308Z","end":"2026-03-19T13:37:12.104368Z","steps":["trace[1912103062] 'read index received' (duration: 188.630388ms)","trace[1912103062] 'applied index is now lower than readState.Index' (duration: 427.858µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:12.10451Z","caller":"traceutil/trace.go:171","msg":"trace[695925925] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"325.173877ms","start":"2026-03-19T13:37:11.779326Z","end":"2026-03-19T13:37:12.1045Z","steps":["trace[695925925] 'process raft request' (duration: 324.928182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.104707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:11.779286Z","time spent":"325.308488ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:37:12.104773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.402061ms","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-19T13:37:12.10486Z","caller":"traceutil/trace.go:171","msg":"trace[1082823580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"181.548614ms","start":"2026-03-19T13:37:11.923291Z","end":"2026-03-19T13:37:12.104839Z","steps":["trace[1082823580] 'agreement among raft nodes before linearized reading' (duration: 181.255548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.104892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.529717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:12.105088Z","caller":"traceutil/trace.go:171","msg":"trace[1470864307] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:943; }","duration":"189.776701ms","start":"2026-03-19T13:37:11.915257Z","end":"2026-03-19T13:37:12.105034Z","steps":["trace[1470864307] 'agreement among raft nodes before linearized reading' (duration: 189.425123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.585962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.860498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:12.586228Z","caller":"traceutil/trace.go:171","msg":"trace[1008903579] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:982; }","duration":"235.099533ms","start":"2026-03-19T13:37:12.351111Z","end":"2026-03-19T13:37:12.586211Z","steps":["trace[1008903579] 'read index received' (duration: 38.021µs)","trace[1008903579] 'applied index is now lower than readState.Index' (duration: 235.060522ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:12.586395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.277326ms","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-19T13:37:12.586428Z","caller":"traceutil/trace.go:171","msg":"trace[1634928078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"235.357288ms","start":"2026-03-19T13:37:12.351062Z","end":"2026-03-19T13:37:12.586419Z","steps":["trace[1634928078] 'agreement among raft nodes before linearized reading' (duration: 235.205505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:12.586622Z","caller":"traceutil/trace.go:171","msg":"trace[1754131870] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"476.293292ms","start":"2026-03-19T13:37:12.110301Z","end":"2026-03-19T13:37:12.586594Z","steps":["trace[1754131870] 'process raft request' (duration: 15.72848ms)","trace[1754131870] 'compare' (duration: 459.675025ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:12.58668Z","caller":"traceutil/trace.go:171","msg":"trace[640483060] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"475.506997ms","start":"2026-03-19T13:37:12.111155Z","end":"2026-03-19T13:37:12.586662Z","steps":["trace[640483060] 'process raft request' (duration: 474.927766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.58675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:12.110282Z","time spent":"476.408545ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T13:37:12.586769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:12.111135Z","time spent":"475.579898ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:37:12.586843Z","caller":"traceutil/trace.go:171","msg":"trace[867380919] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"474.749403ms","start":"2026-03-19T13:37:12.112026Z","end":"2026-03-19T13:37:12.586775Z","steps":["trace[867380919] 'process raft request' (duration: 474.13665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:12.587081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:12.112015Z","time spent":"474.928626ms","remote":"127.0.0.1:34436","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-19T13:37:16.548105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156307043,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T13:37:16.728598Z","caller":"traceutil/trace.go:171","msg":"trace[1617133560] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"680.958385ms","start":"2026-03-19T13:37:16.047616Z","end":"2026-03-19T13:37:16.728575Z","steps":["trace[1617133560] 'read index received' (duration: 680.795472ms)","trace[1617133560] 'applied index is now lower than readState.Index' (duration: 161.603µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:16.728623Z","caller":"traceutil/trace.go:171","msg":"trace[1490401729] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"687.875696ms","start":"2026-03-19T13:37:16.040718Z","end":"2026-03-19T13:37:16.728594Z","steps":["trace[1490401729] 'process raft request' (duration: 687.717003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:16.72879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.040694Z","time spent":"687.999469ms","remote":"127.0.0.1:34436","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-19T13:37:16.728878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.800672ms","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-19T13:37:16.72892Z","caller":"traceutil/trace.go:171","msg":"trace[248691849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"126.918855ms","start":"2026-03-19T13:37:16.601988Z","end":"2026-03-19T13:37:16.728907Z","steps":["trace[248691849] 'agreement among raft nodes before linearized reading' (duration: 126.771632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:16.728955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.503206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-28gnr\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-03-19T13:37:16.729011Z","caller":"traceutil/trace.go:171","msg":"trace[184042122] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-28gnr; range_end:; response_count:1; response_revision:980; }","duration":"383.589497ms","start":"2026-03-19T13:37:16.345406Z","end":"2026-03-19T13:37:16.728995Z","steps":["trace[184042122] 'agreement among raft nodes before linearized reading' (duration: 383.374774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:16.729052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.345389Z","time spent":"383.646969ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12665,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-28gnr\" "} {"level":"warn","ts":"2026-03-19T13:37:16.729106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.50757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-19T13:37:16.729375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.133248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:16.729463Z","caller":"traceutil/trace.go:171","msg":"trace[2115423961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"681.858042ms","start":"2026-03-19T13:37:16.047575Z","end":"2026-03-19T13:37:16.729433Z","steps":["trace[2115423961] 'agreement among raft nodes before linearized reading' (duration: 681.094859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:16.729513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.047556Z","time spent":"681.945165ms","remote":"127.0.0.1:34200","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-19T13:37:16.729138Z","caller":"traceutil/trace.go:171","msg":"trace[617060915] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:980; }","duration":"533.574152ms","start":"2026-03-19T13:37:16.195556Z","end":"2026-03-19T13:37:16.72913Z","steps":["trace[617060915] 'agreement among raft nodes before linearized reading' (duration: 533.166074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:16.729584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.195537Z","time spent":"534.034801ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-03-19T13:37:17.549531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314621156307047,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:37:18.031374Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.299581865s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T13:37:18.137337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.020158ms","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-19T13:37:18.137479Z","caller":"traceutil/trace.go:171","msg":"trace[1708058298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:980; }","duration":"988.185611ms","start":"2026-03-19T13:37:17.149274Z","end":"2026-03-19T13:37:18.137459Z","steps":["trace[1708058298] 'range keys from in-memory index tree' (duration: 987.998417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.137857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24603ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-19T13:37:18.137999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.731648Z","time spent":"1.406322505s","remote":"127.0.0.1:34230","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T13:37:18.153146Z","caller":"traceutil/trace.go:171","msg":"trace[1918730849] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.419694389s","start":"2026-03-19T13:37:16.733426Z","end":"2026-03-19T13:37:18.153121Z","steps":["trace[1918730849] 'process raft request' (duration: 1.419516586s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.153262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.733416Z","time spent":"1.419779912s","remote":"127.0.0.1:34436","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-19T13:37:18.15339Z","caller":"traceutil/trace.go:171","msg":"trace[251235693] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.41919404s","start":"2026-03-19T13:37:16.734175Z","end":"2026-03-19T13:37:18.153369Z","steps":["trace[251235693] 'process raft request' (duration: 1.418908935s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:18.153463Z","caller":"traceutil/trace.go:171","msg":"trace[341498210] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1019; }","duration":"1.104445892s","start":"2026-03-19T13:37:17.048991Z","end":"2026-03-19T13:37:18.153436Z","steps":["trace[341498210] 'read index received' (duration: 982.636445ms)","trace[341498210] 'applied index is now lower than readState.Index' (duration: 121.808297ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:18.153484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.734167Z","time spent":"1.419272812s","remote":"127.0.0.1:34436","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-19T13:37:18.153554Z","caller":"traceutil/trace.go:171","msg":"trace[1796171959] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.410776281s","start":"2026-03-19T13:37:16.742752Z","end":"2026-03-19T13:37:18.153528Z","steps":["trace[1796171959] 'process raft request' (duration: 1.410561086s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.153615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104599145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:37:18.153722Z","caller":"traceutil/trace.go:171","msg":"trace[1882525906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"1.104753608s","start":"2026-03-19T13:37:17.048945Z","end":"2026-03-19T13:37:18.153699Z","steps":["trace[1882525906] 'agreement among raft nodes before linearized reading' (duration: 1.104582485s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.15377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:17.048928Z","time spent":"1.104832869s","remote":"127.0.0.1:34200","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-19T13:37:18.153784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:16.742736Z","time spent":"1.410936873s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13708,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:37:18.154656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.87779ms","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-19T13:37:18.155278Z","caller":"traceutil/trace.go:171","msg":"trace[1166529387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"838.533063ms","start":"2026-03-19T13:37:17.316728Z","end":"2026-03-19T13:37:18.155261Z","steps":["trace[1166529387] 'agreement among raft nodes before linearized reading' (duration: 837.712807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.155325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:17.316709Z","time spent":"838.608224ms","remote":"127.0.0.1:34436","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-19T13:37:18.155094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.845411ms","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-19T13:37:18.155436Z","caller":"traceutil/trace.go:171","msg":"trace[1816612011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"386.224408ms","start":"2026-03-19T13:37:17.769194Z","end":"2026-03-19T13:37:18.155419Z","steps":["trace[1816612011] 'agreement among raft nodes before linearized reading' (duration: 385.475844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.155496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:17.769175Z","time spent":"386.305119ms","remote":"127.0.0.1:34436","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-19T13:37:18.155181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.335524ms","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-19T13:37:18.155596Z","caller":"traceutil/trace.go:171","msg":"trace[2128434115] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:984; }","duration":"537.789781ms","start":"2026-03-19T13:37:17.617796Z","end":"2026-03-19T13:37:18.155585Z","steps":["trace[2128434115] 'agreement among raft nodes before linearized reading' (duration: 537.262042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.15563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:17.617783Z","time spent":"537.839092ms","remote":"127.0.0.1:34436","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":"warn","ts":"2026-03-19T13:37:18.155207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.7463ms","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-19T13:37:18.155712Z","caller":"traceutil/trace.go:171","msg":"trace[1893014655] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:984; }","duration":"796.287509ms","start":"2026-03-19T13:37:17.359417Z","end":"2026-03-19T13:37:18.155704Z","steps":["trace[1893014655] 'agreement among raft nodes before linearized reading' (duration: 795.690939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:18.155755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:37:17.359402Z","time spent":"796.33768ms","remote":"127.0.0.1:34436","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-19T13:37:34.429783Z","caller":"traceutil/trace.go:171","msg":"trace[2082211757] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"109.965951ms","start":"2026-03-19T13:37:34.319796Z","end":"2026-03-19T13:37:34.429762Z","steps":["trace[2082211757] 'process raft request' (duration: 109.927491ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:34.429814Z","caller":"traceutil/trace.go:171","msg":"trace[903154842] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"110.541513ms","start":"2026-03-19T13:37:34.319253Z","end":"2026-03-19T13:37:34.429794Z","steps":["trace[903154842] 'process raft request' (duration: 110.39783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:46.659743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.462131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:46.659869Z","caller":"traceutil/trace.go:171","msg":"trace[1740192269] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"177.63363ms","start":"2026-03-19T13:37:46.48221Z","end":"2026-03-19T13:37:46.659844Z","steps":["trace[1740192269] 'process raft request' (duration: 45.992645ms)","trace[1740192269] 'compare' (duration: 131.36789ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:50.687001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.561635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:37:50.687295Z","caller":"traceutil/trace.go:171","msg":"trace[672536050] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1166; }","duration":"209.16013ms","start":"2026-03-19T13:37:50.478114Z","end":"2026-03-19T13:37:50.687274Z","steps":["trace[672536050] 'read index received' (duration: 6.27468ms)","trace[672536050] 'applied index is now lower than readState.Index' (duration: 202.88467ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:37:50.687377Z","caller":"traceutil/trace.go:171","msg":"trace[457377203] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"258.911185ms","start":"2026-03-19T13:37:50.428443Z","end":"2026-03-19T13:37:50.687354Z","steps":["trace[457377203] 'process raft request' (duration: 258.64762ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:37:50.687393Z","caller":"traceutil/trace.go:171","msg":"trace[125030827] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"259.198711ms","start":"2026-03-19T13:37:50.427938Z","end":"2026-03-19T13:37:50.687137Z","steps":["trace[125030827] 'process raft request' (duration: 109.384721ms)","trace[125030827] 'compare' (duration: 149.28736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:37:50.687491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.358373ms","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-19T13:37:50.687523Z","caller":"traceutil/trace.go:171","msg":"trace[1386467916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"209.430775ms","start":"2026-03-19T13:37:50.478083Z","end":"2026-03-19T13:37:50.687514Z","steps":["trace[1386467916] 'agreement among raft nodes before linearized reading' (duration: 209.298763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:37:50.68763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.752828ms","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-19T13:37:50.687712Z","caller":"traceutil/trace.go:171","msg":"trace[89077051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1122; }","duration":"201.88742ms","start":"2026-03-19T13:37:50.485806Z","end":"2026-03-19T13:37:50.687693Z","steps":["trace[89077051] 'agreement among raft nodes before linearized reading' (duration: 201.687877ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:03.509435Z","caller":"traceutil/trace.go:171","msg":"trace[806880901] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"168.430565ms","start":"2026-03-19T13:38:03.34091Z","end":"2026-03-19T13:38:03.50934Z","steps":["trace[806880901] 'process raft request' (duration: 168.274481ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.293188Z","caller":"traceutil/trace.go:171","msg":"trace[821190321] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"217.03324ms","start":"2026-03-19T13:38:04.07613Z","end":"2026-03-19T13:38:04.293163Z","steps":["trace[821190321] 'process raft request' (duration: 216.903447ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.676161Z","caller":"traceutil/trace.go:171","msg":"trace[1259456354] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"123.881258ms","start":"2026-03-19T13:38:04.552256Z","end":"2026-03-19T13:38:04.676137Z","steps":["trace[1259456354] 'process raft request' (duration: 123.772496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:04.85753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.319178ms","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-19T13:38:04.857607Z","caller":"traceutil/trace.go:171","msg":"trace[1921799127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"110.403889ms","start":"2026-03-19T13:38:04.747186Z","end":"2026-03-19T13:38:04.857589Z","steps":["trace[1921799127] 'range keys from in-memory index tree' (duration: 110.180415ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.964949Z","caller":"traceutil/trace.go:171","msg":"trace[212839036] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"103.215424ms","start":"2026-03-19T13:38:04.861691Z","end":"2026-03-19T13:38:04.964907Z","steps":["trace[212839036] 'process raft request' (duration: 103.02246ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.965035Z","caller":"traceutil/trace.go:171","msg":"trace[1019712900] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"103.325356ms","start":"2026-03-19T13:38:04.861699Z","end":"2026-03-19T13:38:04.965024Z","steps":["trace[1019712900] 'process raft request' (duration: 103.141833ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.968858Z","caller":"traceutil/trace.go:171","msg":"trace[1703143485] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"105.599339ms","start":"2026-03-19T13:38:04.863238Z","end":"2026-03-19T13:38:04.968837Z","steps":["trace[1703143485] 'process raft request' (duration: 105.564398ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:04.968886Z","caller":"traceutil/trace.go:171","msg":"trace[2127372996] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"105.866525ms","start":"2026-03-19T13:38:04.863Z","end":"2026-03-19T13:38:04.968867Z","steps":["trace[2127372996] 'process raft request' (duration: 105.724212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:11.825538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.11436ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T13:38:13.104812Z","caller":"traceutil/trace.go:171","msg":"trace[851289258] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"101.223576ms","start":"2026-03-19T13:38:13.00356Z","end":"2026-03-19T13:38:13.104783Z","steps":["trace[851289258] 'read index received' (duration: 101.023652ms)","trace[851289258] 'applied index is now lower than readState.Index' (duration: 198.764µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:13.104877Z","caller":"traceutil/trace.go:171","msg":"trace[2097868509] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"102.375187ms","start":"2026-03-19T13:38:13.002463Z","end":"2026-03-19T13:38:13.104838Z","steps":["trace[2097868509] 'process raft request' (duration: 102.150493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:13.105089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.497721ms","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-19T13:38:13.105189Z","caller":"traceutil/trace.go:171","msg":"trace[1217294142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1212; }","duration":"101.631354ms","start":"2026-03-19T13:38:13.003534Z","end":"2026-03-19T13:38:13.105165Z","steps":["trace[1217294142] 'agreement among raft nodes before linearized reading' (duration: 101.361169ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:13.105996Z","caller":"traceutil/trace.go:171","msg":"trace[313497246] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"102.128083ms","start":"2026-03-19T13:38:13.003842Z","end":"2026-03-19T13:38:13.10597Z","steps":["trace[313497246] 'process raft request' (duration: 101.889437ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:13.106054Z","caller":"traceutil/trace.go:171","msg":"trace[243169976] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"100.513243ms","start":"2026-03-19T13:38:13.005514Z","end":"2026-03-19T13:38:13.106028Z","steps":["trace[243169976] 'process raft request' (duration: 100.34331ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:17.351345Z","caller":"traceutil/trace.go:171","msg":"trace[1767338082] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"181.172586ms","start":"2026-03-19T13:38:17.170144Z","end":"2026-03-19T13:38:17.351317Z","steps":["trace[1767338082] 'process raft request' (duration: 117.913974ms)","trace[1767338082] 'compare' (duration: 63.012418ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:17.351681Z","caller":"traceutil/trace.go:171","msg":"trace[1149840561] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"181.329199ms","start":"2026-03-19T13:38:17.170307Z","end":"2026-03-19T13:38:17.351636Z","steps":["trace[1149840561] 'process raft request' (duration: 180.960732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:18.993801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.258834ms","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-19T13:38:18.993867Z","caller":"traceutil/trace.go:171","msg":"trace[1303585582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"237.333975ms","start":"2026-03-19T13:38:18.756517Z","end":"2026-03-19T13:38:18.993851Z","steps":["trace[1303585582] 'range keys from in-memory index tree' (duration: 236.986828ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:19.106288Z","caller":"traceutil/trace.go:171","msg":"trace[1149708930] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"108.706219ms","start":"2026-03-19T13:38:18.997558Z","end":"2026-03-19T13:38:19.106265Z","steps":["trace[1149708930] 'process raft request' (duration: 108.295532ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:21.619811Z","caller":"traceutil/trace.go:171","msg":"trace[847097695] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"149.341785ms","start":"2026-03-19T13:38:21.470439Z","end":"2026-03-19T13:38:21.619781Z","steps":["trace[847097695] 'process raft request' (duration: 149.154152ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:22.026962Z","caller":"traceutil/trace.go:171","msg":"trace[1850827377] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"555.563634ms","start":"2026-03-19T13:38:21.471365Z","end":"2026-03-19T13:38:22.026929Z","steps":["trace[1850827377] 'process raft request' (duration: 489.897537ms)","trace[1850827377] 'compare' (duration: 65.413973ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:38:22.027189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:38:21.471339Z","time spent":"555.699247ms","remote":"127.0.0.1:34436","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-19T13:38:22.027726Z","caller":"traceutil/trace.go:171","msg":"trace[1772062284] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"556.233838ms","start":"2026-03-19T13:38:21.471462Z","end":"2026-03-19T13:38:22.027696Z","steps":["trace[1772062284] 'process raft request' (duration: 555.422312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:22.027902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:38:21.471454Z","time spent":"556.35521ms","remote":"127.0.0.1:34436","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-19T13:38:22.035463Z","caller":"traceutil/trace.go:171","msg":"trace[1541480916] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"411.736193ms","start":"2026-03-19T13:38:21.623716Z","end":"2026-03-19T13:38:22.035452Z","steps":["trace[1541480916] 'process raft request' (duration: 411.634521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:22.035533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:38:21.623695Z","time spent":"411.803084ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:38:22.035578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.788658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:38:22.035649Z","caller":"traceutil/trace.go:171","msg":"trace[246631662] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1250; }","duration":"180.89896ms","start":"2026-03-19T13:38:21.854732Z","end":"2026-03-19T13:38:22.035631Z","steps":["trace[246631662] 'agreement among raft nodes before linearized reading' (duration: 180.794369ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:22.035668Z","caller":"traceutil/trace.go:171","msg":"trace[1437821333] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1300; }","duration":"180.628076ms","start":"2026-03-19T13:38:21.854785Z","end":"2026-03-19T13:38:22.035413Z","steps":["trace[1437821333] 'read index received' (duration: 106.403097ms)","trace[1437821333] 'applied index is now lower than readState.Index' (duration: 74.224218ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:27.580119Z","caller":"traceutil/trace.go:171","msg":"trace[1828024140] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"110.746067ms","start":"2026-03-19T13:38:27.469346Z","end":"2026-03-19T13:38:27.580092Z","steps":["trace[1828024140] 'process raft request' (duration: 110.618394ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:27.649297Z","caller":"traceutil/trace.go:171","msg":"trace[313324350] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"179.751119ms","start":"2026-03-19T13:38:27.469511Z","end":"2026-03-19T13:38:27.649262Z","steps":["trace[313324350] 'process raft request' (duration: 179.560685ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:31.445422Z","caller":"traceutil/trace.go:171","msg":"trace[1339996960] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"114.645472ms","start":"2026-03-19T13:38:31.330712Z","end":"2026-03-19T13:38:31.445357Z","steps":["trace[1339996960] 'process raft request' (duration: 114.463238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:31.853494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.751326ms","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-19T13:38:31.853599Z","caller":"traceutil/trace.go:171","msg":"trace[376303902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"103.849428ms","start":"2026-03-19T13:38:31.74971Z","end":"2026-03-19T13:38:31.853559Z","steps":["trace[376303902] 'range keys from in-memory index tree' (duration: 103.569923ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:32.488407Z","caller":"traceutil/trace.go:171","msg":"trace[1832153781] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"177.342144ms","start":"2026-03-19T13:38:32.311041Z","end":"2026-03-19T13:38:32.488383Z","steps":["trace[1832153781] 'read index received' (duration: 177.133501ms)","trace[1832153781] 'applied index is now lower than readState.Index' (duration: 207.713µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:32.488462Z","caller":"traceutil/trace.go:171","msg":"trace[1704705095] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"261.890928ms","start":"2026-03-19T13:38:32.226548Z","end":"2026-03-19T13:38:32.488439Z","steps":["trace[1704705095] 'process raft request' (duration: 261.700955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:32.488645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.583318ms","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-19T13:38:32.488825Z","caller":"traceutil/trace.go:171","msg":"trace[1061660474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"177.773532ms","start":"2026-03-19T13:38:32.311028Z","end":"2026-03-19T13:38:32.488801Z","steps":["trace[1061660474] 'agreement among raft nodes before linearized reading' (duration: 177.443716ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:32.660483Z","caller":"traceutil/trace.go:171","msg":"trace[167293313] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"167.655011ms","start":"2026-03-19T13:38:32.492794Z","end":"2026-03-19T13:38:32.660449Z","steps":["trace[167293313] 'process raft request' (duration: 110.160268ms)","trace[167293313] 'compare' (duration: 57.386521ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:32.827227Z","caller":"traceutil/trace.go:171","msg":"trace[637877586] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"151.258922ms","start":"2026-03-19T13:38:32.675936Z","end":"2026-03-19T13:38:32.827195Z","steps":["trace[637877586] 'process raft request' (duration: 143.039158ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:34.165727Z","caller":"traceutil/trace.go:171","msg":"trace[1212491953] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"297.362637ms","start":"2026-03-19T13:38:33.868344Z","end":"2026-03-19T13:38:34.165707Z","steps":["trace[1212491953] 'read index received' (duration: 297.200734ms)","trace[1212491953] 'applied index is now lower than readState.Index' (duration: 161.043µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:38:34.166038Z","caller":"traceutil/trace.go:171","msg":"trace[1895443545] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"403.150251ms","start":"2026-03-19T13:38:33.762875Z","end":"2026-03-19T13:38:34.166026Z","steps":["trace[1895443545] 'process raft request' (duration: 402.718013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:34.166184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.740449ms","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-19T13:38:34.166236Z","caller":"traceutil/trace.go:171","msg":"trace[2127452443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"282.840941ms","start":"2026-03-19T13:38:33.883381Z","end":"2026-03-19T13:38:34.166222Z","steps":["trace[2127452443] 'agreement among raft nodes before linearized reading' (duration: 282.688388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:34.166203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:38:33.762854Z","time spent":"403.267982ms","remote":"127.0.0.1:34436","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-19T13:38:34.166258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.225038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:38:34.166362Z","caller":"traceutil/trace.go:171","msg":"trace[155688002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"117.348892ms","start":"2026-03-19T13:38:34.048992Z","end":"2026-03-19T13:38:34.166341Z","steps":["trace[155688002] 'agreement among raft nodes before linearized reading' (duration: 117.189069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:34.166259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.893246ms","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-19T13:38:34.166761Z","caller":"traceutil/trace.go:171","msg":"trace[1504460401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"298.420836ms","start":"2026-03-19T13:38:33.868306Z","end":"2026-03-19T13:38:34.166727Z","steps":["trace[1504460401] 'agreement among raft nodes before linearized reading' (duration: 297.773253ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:36.278305Z","caller":"traceutil/trace.go:171","msg":"trace[1783994643] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"103.617384ms","start":"2026-03-19T13:38:36.174664Z","end":"2026-03-19T13:38:36.278281Z","steps":["trace[1783994643] 'process raft request' (duration: 103.251316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:38:36.48767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.471276ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-19T13:38:36.488024Z","caller":"traceutil/trace.go:171","msg":"trace[2045200172] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"203.17482ms","start":"2026-03-19T13:38:36.284828Z","end":"2026-03-19T13:38:36.488002Z","steps":["trace[2045200172] 'process raft request' (duration: 203.13839ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:36.488141Z","caller":"traceutil/trace.go:171","msg":"trace[416951606] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"203.65742ms","start":"2026-03-19T13:38:36.284449Z","end":"2026-03-19T13:38:36.488107Z","steps":["trace[416951606] 'process raft request' (duration: 203.481786ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:38:36.488204Z","caller":"traceutil/trace.go:171","msg":"trace[1921468288] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"204.954355ms","start":"2026-03-19T13:38:36.283166Z","end":"2026-03-19T13:38:36.48812Z","steps":["trace[1921468288] 'process raft request' (duration: 204.664458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:40:35.459363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.88897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:40:35.459639Z","caller":"traceutil/trace.go:171","msg":"trace[2147445720] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"344.334008ms","start":"2026-03-19T13:40:35.115282Z","end":"2026-03-19T13:40:35.459616Z","steps":["trace[2147445720] 'process raft request' (duration: 344.216346ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:40:35.459689Z","caller":"traceutil/trace.go:171","msg":"trace[1212442487] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"507.463896ms","start":"2026-03-19T13:40:34.952109Z","end":"2026-03-19T13:40:35.459573Z","steps":["trace[1212442487] 'process raft request' (duration: 189.24232ms)","trace[1212442487] 'compare' (duration: 317.416341ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:40:35.45974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.115237Z","time spent":"344.45597ms","remote":"127.0.0.1:34436","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-19T13:40:35.459708Z","caller":"traceutil/trace.go:171","msg":"trace[1656636747] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"410.732546ms","start":"2026-03-19T13:40:35.048903Z","end":"2026-03-19T13:40:35.459636Z","steps":["trace[1656636747] 'read index received' (duration: 92.374318ms)","trace[1656636747] 'applied index is now lower than readState.Index' (duration: 318.355978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:40:35.45982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.91507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:40:35.459854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:34.952091Z","time spent":"507.674489ms","remote":"127.0.0.1:34436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:40:35.459877Z","caller":"traceutil/trace.go:171","msg":"trace[1498720251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"411.033392ms","start":"2026-03-19T13:40:35.04883Z","end":"2026-03-19T13:40:35.459863Z","steps":["trace[1498720251] 'agreement among raft nodes before linearized reading' (duration: 410.945761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:40:35.459904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.048804Z","time spent":"411.095013ms","remote":"127.0.0.1:34200","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-19T13:40:35.459991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.744742ms","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-19T13:40:35.45998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.365838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:40:35.460041Z","caller":"traceutil/trace.go:171","msg":"trace[556243752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1821; }","duration":"405.839213ms","start":"2026-03-19T13:40:35.054191Z","end":"2026-03-19T13:40:35.460031Z","steps":["trace[556243752] 'agreement among raft nodes before linearized reading' (duration: 405.66671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:40:35.460077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.054168Z","time spent":"405.901675ms","remote":"127.0.0.1:34436","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-19T13:40:35.460081Z","caller":"traceutil/trace.go:171","msg":"trace[1930004002] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1821; }","duration":"318.532402ms","start":"2026-03-19T13:40:35.141527Z","end":"2026-03-19T13:40:35.460059Z","steps":["trace[1930004002] 'agreement among raft nodes before linearized reading' (duration: 318.357449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:40:35.460127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.141513Z","time spent":"318.603663ms","remote":"127.0.0.1:45014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:40:35.460179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.828861ms","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-19T13:40:35.460218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.717225ms","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-19T13:40:35.460215Z","caller":"traceutil/trace.go:171","msg":"trace[1960107207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"391.916252ms","start":"2026-03-19T13:40:35.068288Z","end":"2026-03-19T13:40:35.460204Z","steps":["trace[1960107207] 'agreement among raft nodes before linearized reading' (duration: 391.82464ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:40:35.460243Z","caller":"traceutil/trace.go:171","msg":"trace[1213788861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1821; }","duration":"395.760685ms","start":"2026-03-19T13:40:35.064477Z","end":"2026-03-19T13:40:35.460237Z","steps":["trace[1213788861] 'agreement among raft nodes before linearized reading' (duration: 395.672923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:40:35.460258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.064467Z","time spent":"395.787935ms","remote":"127.0.0.1:34436","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-19T13:40:35.460251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:40:35.06826Z","time spent":"391.984964ms","remote":"127.0.0.1:34436","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-19T13:40:35.667697Z","caller":"traceutil/trace.go:171","msg":"trace[927533594] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"202.595782ms","start":"2026-03-19T13:40:35.465072Z","end":"2026-03-19T13:40:35.667667Z","steps":["trace[927533594] 'process raft request' (duration: 104.993225ms)","trace[927533594] 'compare' (duration: 97.445734ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:40:35.667991Z","caller":"traceutil/trace.go:171","msg":"trace[1755472129] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"200.748186ms","start":"2026-03-19T13:40:35.467218Z","end":"2026-03-19T13:40:35.667966Z","steps":["trace[1755472129] 'process raft request' (duration: 200.643294ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:40:35.668072Z","caller":"traceutil/trace.go:171","msg":"trace[1554216913] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"202.833245ms","start":"2026-03-19T13:40:35.465197Z","end":"2026-03-19T13:40:35.66803Z","steps":["trace[1554216913] 'process raft request' (duration: 202.429898ms)"],"step_count":1}