{"level":"warn","ts":"2026-04-22T13:10:29.78009Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T13:10:29.780194Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-04-22T13:10:29.780272Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T13:10:29.78029Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-22T13:10:29.78032Z","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-04-22T13:10:29.781197Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-22T13:10:29.781292Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-04-22T13:10:29.786553Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.00144ms"} {"level":"info","ts":"2026-04-22T13:10:29.793212Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-22T13:10:29.793309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T13:10:29.793346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-22T13:10:29.793369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T13:10:29.793385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-22T13:10:29.793437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-22T13:10:29.801565Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T13:10:29.802878Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T13:10:29.80407Z","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-04-22T13:10:29.806154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T13:10:29.806614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T13:10:29.806842Z","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-04-22T13:10:29.806933Z","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-04-22T13:10:29.80695Z","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-04-22T13:10:29.807538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-22T13:10:29.808549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-22T13:10:29.813274Z","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-04-22T13:10:29.813339Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-22T13:10:29.813518Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-22T13:10:29.81386Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T13:10:29.814043Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T13:10:30.794207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T13:10:30.79427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T13:10:30.794308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-22T13:10:30.794328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-22T13:10:30.794339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-22T13:10:30.794354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-22T13:10:30.794368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-22T13:10:30.795024Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:30.795812Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T13:10:30.795944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:30.795982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:30.797078Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:30.797193Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:30.797229Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:30.799005Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-22T13:10:30.799264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T13:10:30.79929Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T13:10:30.800208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-22T13:10:33.368056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.932301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-22T13:10:33.368244Z","caller":"traceutil/trace.go:171","msg":"trace[2046765183] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"252.111303ms","start":"2026-04-22T13:10:33.116118Z","end":"2026-04-22T13:10:33.368229Z","steps":["trace[2046765183] 'read index received' (duration: 27.026612ms)","trace[2046765183] 'applied index is now lower than readState.Index' (duration: 225.083601ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:33.36839Z","caller":"traceutil/trace.go:171","msg":"trace[748994113] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"317.554843ms","start":"2026-04-22T13:10:33.05076Z","end":"2026-04-22T13:10:33.368315Z","steps":["trace[748994113] 'process raft request' (duration: 92.457841ms)","trace[748994113] 'compare' (duration: 220.820748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:33.368523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:33.050744Z","time spent":"317.722516ms","remote":"127.0.0.1:46160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:10:33.368686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.560982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T13:10:33.368763Z","caller":"traceutil/trace.go:171","msg":"trace[1925555390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"252.675715ms","start":"2026-04-22T13:10:33.116074Z","end":"2026-04-22T13:10:33.368749Z","steps":["trace[1925555390] 'agreement among raft nodes before linearized reading' (duration: 252.324058ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:34.265166Z","caller":"traceutil/trace.go:171","msg":"trace[882526302] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"476.982026ms","start":"2026-04-22T13:10:33.788157Z","end":"2026-04-22T13:10:34.265139Z","steps":["trace[882526302] 'process raft request' (duration: 418.185171ms)","trace[882526302] 'compare' (duration: 58.10975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:34.265303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:33.788135Z","time spent":"477.113078ms","remote":"127.0.0.1:46164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:10:39.589817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016455192s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:10:39.590398Z","caller":"traceutil/trace.go:171","msg":"trace[1778506828] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.017139208s","start":"2026-04-22T13:10:38.573232Z","end":"2026-04-22T13:10:39.590371Z","steps":["trace[1778506828] 'process raft request' (duration: 1.016980144s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:39.590595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:38.573208Z","time spent":"1.017303151s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:10:40.100182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428340728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:40.703125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.04452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:40.703248Z","caller":"traceutil/trace.go:171","msg":"trace[473253253] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"1.104100886s","start":"2026-04-22T13:10:39.599123Z","end":"2026-04-22T13:10:40.703224Z","steps":["trace[473253253] 'read index received' (duration: 624.522443ms)","trace[473253253] 'applied index is now lower than readState.Index' (duration: 479.574873ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:40.703261Z","caller":"traceutil/trace.go:171","msg":"trace[1452244385] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"2.125583058s","start":"2026-04-22T13:10:38.577646Z","end":"2026-04-22T13:10:40.703229Z","steps":["trace[1452244385] 'process raft request' (duration: 1.646171179s)","trace[1452244385] 'compare' (duration: 478.892427ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:40.703376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:38.577631Z","time spent":"2.1256921s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:10:40.703394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.106773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:10:40.703458Z","caller":"traceutil/trace.go:171","msg":"trace[1360408162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:331; }","duration":"914.202685ms","start":"2026-04-22T13:10:39.789221Z","end":"2026-04-22T13:10:40.703423Z","steps":["trace[1360408162] 'agreement among raft nodes before linearized reading' (duration: 914.120713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:40.703513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:39.789195Z","time spent":"914.306737ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:10:40.703679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.837994ms","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-04-22T13:10:40.703736Z","caller":"traceutil/trace.go:171","msg":"trace[598659340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:331; }","duration":"306.927936ms","start":"2026-04-22T13:10:40.396793Z","end":"2026-04-22T13:10:40.703721Z","steps":["trace[598659340] 'agreement among raft nodes before linearized reading' (duration: 306.841064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:40.7037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104240539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:10:40.703781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.396771Z","time spent":"307.000377ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:10:40.703776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.091506ms","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-04-22T13:10:40.703808Z","caller":"traceutil/trace.go:171","msg":"trace[570482372] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:331; }","duration":"1.104701129s","start":"2026-04-22T13:10:39.599083Z","end":"2026-04-22T13:10:40.703784Z","steps":["trace[570482372] 'agreement among raft nodes before linearized reading' (duration: 1.104225419s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:10:40.703835Z","caller":"traceutil/trace.go:171","msg":"trace[1625330825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"696.25964ms","start":"2026-04-22T13:10:40.007559Z","end":"2026-04-22T13:10:40.703819Z","steps":["trace[1625330825] 'agreement among raft nodes before linearized reading' (duration: 696.134407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:40.70386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:39.599064Z","time spent":"1.104783791s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-22T13:10:40.703897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.00753Z","time spent":"696.353431ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:10:41.643149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.105244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:10:41.64338Z","caller":"traceutil/trace.go:171","msg":"trace[1714957137] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"934.142635ms","start":"2026-04-22T13:10:40.709219Z","end":"2026-04-22T13:10:41.643362Z","steps":["trace[1714957137] 'process raft request' (duration: 934.107184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:41.643456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.709208Z","time spent":"934.216386ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:41.643664Z","caller":"traceutil/trace.go:171","msg":"trace[1280397371] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"934.663706ms","start":"2026-04-22T13:10:40.708989Z","end":"2026-04-22T13:10:41.643653Z","steps":["trace[1280397371] 'process raft request' (duration: 934.261157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:41.643727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.708973Z","time spent":"934.722927ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:10:41.643647Z","caller":"traceutil/trace.go:171","msg":"trace[1960011951] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"935.2984ms","start":"2026-04-22T13:10:40.708309Z","end":"2026-04-22T13:10:41.643608Z","steps":["trace[1960011951] 'read index received' (duration: 488.665854ms)","trace[1960011951] 'applied index is now lower than readState.Index' (duration: 446.630846ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:10:41.643969Z","caller":"traceutil/trace.go:171","msg":"trace[924273438] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"935.970614ms","start":"2026-04-22T13:10:40.70798Z","end":"2026-04-22T13:10:41.64395Z","steps":["trace[924273438] 'process raft request' (duration: 488.987031ms)","trace[924273438] 'compare' (duration: 445.91605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:10:41.644186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.707958Z","time spent":"936.083727ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:10:41.644294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.988685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-22T13:10:41.644571Z","caller":"traceutil/trace.go:171","msg":"trace[394886020] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:334; }","duration":"936.406325ms","start":"2026-04-22T13:10:40.70814Z","end":"2026-04-22T13:10:41.644546Z","steps":["trace[394886020] 'agreement among raft nodes before linearized reading' (duration: 936.045546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:41.644622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:40.70809Z","time spent":"936.523497ms","remote":"127.0.0.1:45952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T13:10:41.645567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.287791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-22T13:10:41.645666Z","caller":"traceutil/trace.go:171","msg":"trace[688981064] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:334; }","duration":"426.449654ms","start":"2026-04-22T13:10:41.219195Z","end":"2026-04-22T13:10:41.645644Z","steps":["trace[688981064] 'agreement among raft nodes before linearized reading' (duration: 424.819509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:41.64572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:41.219171Z","time spent":"426.539297ms","remote":"127.0.0.1:46026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-22T13:11:11.147965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.183667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:11.148066Z","caller":"traceutil/trace.go:171","msg":"trace[1567787870] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:531; }","duration":"206.31102ms","start":"2026-04-22T13:11:10.941734Z","end":"2026-04-22T13:11:11.148045Z","steps":["trace[1567787870] 'range keys from in-memory index tree' (duration: 206.101146ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:12.108255Z","caller":"traceutil/trace.go:171","msg":"trace[1202978578] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"762.774925ms","start":"2026-04-22T13:11:11.345426Z","end":"2026-04-22T13:11:12.108201Z","steps":["trace[1202978578] 'process raft request' (duration: 762.647052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.108404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.345409Z","time spent":"762.926529ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.116101Z","caller":"traceutil/trace.go:171","msg":"trace[870322033] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"757.262534ms","start":"2026-04-22T13:11:11.358814Z","end":"2026-04-22T13:11:12.116076Z","steps":["trace[870322033] 'process raft request' (duration: 757.095321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:12.116259Z","caller":"traceutil/trace.go:171","msg":"trace[26427971] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"746.679135ms","start":"2026-04-22T13:11:11.369547Z","end":"2026-04-22T13:11:12.116226Z","steps":["trace[26427971] 'process raft request' (duration: 746.509021ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:12.116254Z","caller":"traceutil/trace.go:171","msg":"trace[1057187876] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"753.534863ms","start":"2026-04-22T13:11:11.36268Z","end":"2026-04-22T13:11:12.116214Z","steps":["trace[1057187876] 'process raft request' (duration: 753.330299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.1164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.369539Z","time spent":"746.788207ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.116417Z","caller":"traceutil/trace.go:171","msg":"trace[356751135] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"746.789677ms","start":"2026-04-22T13:11:11.369588Z","end":"2026-04-22T13:11:12.116378Z","steps":["trace[356751135] 'process raft request' (duration: 746.560262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.116623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.369581Z","time spent":"746.94316ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.116641Z","caller":"traceutil/trace.go:171","msg":"trace[884939064] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"733.963536ms","start":"2026-04-22T13:11:11.382664Z","end":"2026-04-22T13:11:12.116628Z","steps":["trace[884939064] 'process raft request' (duration: 733.513796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.11642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.362674Z","time spent":"753.675087ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.116707Z","caller":"traceutil/trace.go:171","msg":"trace[2032033212] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"733.987947ms","start":"2026-04-22T13:11:11.382714Z","end":"2026-04-22T13:11:12.116702Z","steps":["trace[2032033212] 'process raft request' (duration: 733.807563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.116842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.382711Z","time spent":"734.015937ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:11:12.116679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.382649Z","time spent":"734.012827ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.117083Z","caller":"traceutil/trace.go:171","msg":"trace[70179211] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"734.303013ms","start":"2026-04-22T13:11:11.382774Z","end":"2026-04-22T13:11:12.117077Z","steps":["trace[70179211] 'process raft request' (duration: 733.806712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.117117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.38277Z","time spent":"734.331363ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.11722Z","caller":"traceutil/trace.go:171","msg":"trace[440043548] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"724.975559ms","start":"2026-04-22T13:11:11.392224Z","end":"2026-04-22T13:11:12.1172Z","steps":["trace[440043548] 'process raft request' (duration: 724.747994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.117291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.392206Z","time spent":"725.054871ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.117497Z","caller":"traceutil/trace.go:171","msg":"trace[1144954748] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"708.207482ms","start":"2026-04-22T13:11:11.409282Z","end":"2026-04-22T13:11:12.117489Z","steps":["trace[1144954748] 'process raft request' (duration: 708.063359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.117485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.358797Z","time spent":"757.392108ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:11:12.117539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.40925Z","time spent":"708.272203ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.117719Z","caller":"traceutil/trace.go:171","msg":"trace[1257177543] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"691.063068ms","start":"2026-04-22T13:11:11.426641Z","end":"2026-04-22T13:11:12.117704Z","steps":["trace[1257177543] 'process raft request' (duration: 690.800622ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:12.117855Z","caller":"traceutil/trace.go:171","msg":"trace[1396166334] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:552; }","duration":"469.75186ms","start":"2026-04-22T13:11:11.648088Z","end":"2026-04-22T13:11:12.11784Z","steps":["trace[1396166334] 'read index received' (duration: 460.444666ms)","trace[1396166334] 'applied index is now lower than readState.Index' (duration: 9.306364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:12.117824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.426613Z","time spent":"691.18523ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:12.11792Z","caller":"traceutil/trace.go:171","msg":"trace[1776345038] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"591.462951ms","start":"2026-04-22T13:11:11.526451Z","end":"2026-04-22T13:11:12.117914Z","steps":["trace[1776345038] 'process raft request' (duration: 591.273407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.117966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.526423Z","time spent":"591.517803ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:11:12.118071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.988895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:12.118101Z","caller":"traceutil/trace.go:171","msg":"trace[404154282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"470.077727ms","start":"2026-04-22T13:11:11.648015Z","end":"2026-04-22T13:11:12.118093Z","steps":["trace[404154282] 'agreement among raft nodes before linearized reading' (duration: 470.009905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.118129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.648Z","time spent":"470.124088ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:11:12.124247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.544501ms","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-04-22T13:11:12.126758Z","caller":"traceutil/trace.go:171","msg":"trace[2067850673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"330.067546ms","start":"2026-04-22T13:11:11.79667Z","end":"2026-04-22T13:11:12.126738Z","steps":["trace[2067850673] 'agreement among raft nodes before linearized reading' (duration: 327.463189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.126888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.796663Z","time spent":"330.202869ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:11:12.125943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.83637ms","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-04-22T13:11:12.127138Z","caller":"traceutil/trace.go:171","msg":"trace[1230570204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"331.103028ms","start":"2026-04-22T13:11:11.796026Z","end":"2026-04-22T13:11:12.127129Z","steps":["trace[1230570204] 'agreement among raft nodes before linearized reading' (duration: 329.85228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.127204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.795993Z","time spent":"331.201431ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:12.126123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.645642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:11:12.127411Z","caller":"traceutil/trace.go:171","msg":"trace[54592881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"333.980551ms","start":"2026-04-22T13:11:11.793422Z","end":"2026-04-22T13:11:12.127402Z","steps":["trace[54592881] 'agreement among raft nodes before linearized reading' (duration: 332.630952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.127632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:11.79339Z","time spent":"334.221867ms","remote":"127.0.0.1:46092","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-04-22T13:11:18.352723Z","caller":"traceutil/trace.go:171","msg":"trace[1321969096] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"148.625216ms","start":"2026-04-22T13:11:18.204072Z","end":"2026-04-22T13:11:18.352697Z","steps":["trace[1321969096] 'read index received' (duration: 147.310567ms)","trace[1321969096] 'applied index is now lower than readState.Index' (duration: 1.313879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:18.352866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.777439ms","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-04-22T13:11:18.3529Z","caller":"traceutil/trace.go:171","msg":"trace[1804252982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"148.85299ms","start":"2026-04-22T13:11:18.204039Z","end":"2026-04-22T13:11:18.352892Z","steps":["trace[1804252982] 'agreement among raft nodes before linearized reading' (duration: 148.741098ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:18.35321Z","caller":"traceutil/trace.go:171","msg":"trace[1155374223] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"177.849974ms","start":"2026-04-22T13:11:18.175264Z","end":"2026-04-22T13:11:18.353114Z","steps":["trace[1155374223] 'process raft request' (duration: 176.946754ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:18.589637Z","caller":"traceutil/trace.go:171","msg":"trace[343202861] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"172.112436ms","start":"2026-04-22T13:11:18.417501Z","end":"2026-04-22T13:11:18.589613Z","steps":["trace[343202861] 'process raft request' (duration: 171.952563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:19.847657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.501858ms","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-04-22T13:11:19.84777Z","caller":"traceutil/trace.go:171","msg":"trace[1067493304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"950.656472ms","start":"2026-04-22T13:11:18.897093Z","end":"2026-04-22T13:11:19.847749Z","steps":["trace[1067493304] 'range keys from in-memory index tree' (duration: 950.338565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:19.847758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075774157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f7kk9\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-22T13:11:19.847811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:18.897073Z","time spent":"950.728933ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T13:11:19.847844Z","caller":"traceutil/trace.go:171","msg":"trace[618303909] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f7kk9; range_end:; response_count:1; response_revision:643; }","duration":"1.07589411s","start":"2026-04-22T13:11:18.771926Z","end":"2026-04-22T13:11:19.84782Z","steps":["trace[618303909] 'range keys from in-memory index tree' (duration: 1.075679795s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:19.84789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:18.771909Z","time spent":"1.075970162s","remote":"127.0.0.1:46026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-f7kk9\" "} {"level":"info","ts":"2026-04-22T13:11:20.091708Z","caller":"traceutil/trace.go:171","msg":"trace[2066469513] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"443.948282ms","start":"2026-04-22T13:11:19.647733Z","end":"2026-04-22T13:11:20.091682Z","steps":["trace[2066469513] 'read index received' (duration: 442.621912ms)","trace[2066469513] 'applied index is now lower than readState.Index' (duration: 1.32517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:20.091881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.117565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:20.091981Z","caller":"traceutil/trace.go:171","msg":"trace[260796722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"444.250019ms","start":"2026-04-22T13:11:19.647709Z","end":"2026-04-22T13:11:20.091959Z","steps":["trace[260796722] 'agreement among raft nodes before linearized reading' (duration: 444.074315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:20.092055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:19.647694Z","time spent":"444.351361ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T13:11:20.091921Z","caller":"traceutil/trace.go:171","msg":"trace[299342960] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.02854064s","start":"2026-04-22T13:11:19.06334Z","end":"2026-04-22T13:11:20.09188Z","steps":["trace[299342960] 'process raft request' (duration: 1.026721319s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:20.092348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:19.063322Z","time spent":"1.028921819s","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:22.093667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000075235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:22.093765Z","caller":"traceutil/trace.go:171","msg":"trace[1262459498] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000215698s","start":"2026-04-22T13:11:20.093526Z","end":"2026-04-22T13:11:22.093741Z","steps":["trace[1262459498] 'agreement among raft nodes before linearized reading' (duration: 2.000098905s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.093816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:20.093508Z","time spent":"2.00029743s","remote":"127.0.0.1:45814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:11:22.229887Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.774152294s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T13:11:22.285372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829561326s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:22.285614Z","caller":"traceutil/trace.go:171","msg":"trace[1965279059] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.425328126s","start":"2026-04-22T13:11:19.860268Z","end":"2026-04-22T13:11:22.285596Z","steps":["trace[1965279059] 'process raft request' (duration: 2.425210083s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.285677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:19.860251Z","time spent":"2.425396527s","remote":"127.0.0.1:46026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:22.285657Z","caller":"traceutil/trace.go:171","msg":"trace[534303379] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.432928877s","start":"2026-04-22T13:11:19.852625Z","end":"2026-04-22T13:11:22.285554Z","steps":["trace[534303379] 'process raft request' (duration: 603.100455ms)","trace[534303379] 'compare' (duration: 1.82929014s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:22.285813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:19.852602Z","time spent":"2.43312183s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:22.305992Z","caller":"traceutil/trace.go:171","msg":"trace[666065006] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"2.212399528s","start":"2026-04-22T13:11:20.093558Z","end":"2026-04-22T13:11:22.305958Z","steps":["trace[666065006] 'read index received' (duration: 362.183797ms)","trace[666065006] 'applied index is now lower than readState.Index' (duration: 1.850212161s)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:22.306083Z","caller":"traceutil/trace.go:171","msg":"trace[318502933] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"208.463575ms","start":"2026-04-22T13:11:22.0976Z","end":"2026-04-22T13:11:22.306063Z","steps":["trace[318502933] 'process raft request' (duration: 208.404684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.306339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.856146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:22.306418Z","caller":"traceutil/trace.go:171","msg":"trace[1662003468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"204.966888ms","start":"2026-04-22T13:11:22.101434Z","end":"2026-04-22T13:11:22.306401Z","steps":["trace[1662003468] 'agreement among raft nodes before linearized reading' (duration: 204.860695ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:22.30642Z","caller":"traceutil/trace.go:171","msg":"trace[1515214266] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"2.154102873s","start":"2026-04-22T13:11:20.152303Z","end":"2026-04-22T13:11:22.306406Z","steps":["trace[1515214266] 'process raft request' (duration: 2.153510779s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.306352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128266029s","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-04-22T13:11:22.306533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123893621s","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-04-22T13:11:22.306567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:20.152284Z","time spent":"2.154230685s","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:22.30659Z","caller":"traceutil/trace.go:171","msg":"trace[1300165487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"2.128567296s","start":"2026-04-22T13:11:20.177995Z","end":"2026-04-22T13:11:22.306563Z","steps":["trace[1300165487] 'agreement among raft nodes before linearized reading' (duration: 2.128139896s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:22.306585Z","caller":"traceutil/trace.go:171","msg":"trace[1107230937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"2.123972463s","start":"2026-04-22T13:11:20.182597Z","end":"2026-04-22T13:11:22.306569Z","steps":["trace[1107230937] 'agreement among raft nodes before linearized reading' (duration: 2.123792799s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.306648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:20.177958Z","time spent":"2.128673598s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:11:22.306647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:20.182583Z","time spent":"2.124051035s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:11:22.306339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923621099s","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-04-22T13:11:22.3068Z","caller":"traceutil/trace.go:171","msg":"trace[248174507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.92413978s","start":"2026-04-22T13:11:20.382647Z","end":"2026-04-22T13:11:22.306787Z","steps":["trace[248174507] 'agreement among raft nodes before linearized reading' (duration: 1.923609409s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.306869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:20.382623Z","time spent":"1.924231683s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:22.307434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.257067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:22.307525Z","caller":"traceutil/trace.go:171","msg":"trace[985911032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"381.37686ms","start":"2026-04-22T13:11:21.926129Z","end":"2026-04-22T13:11:22.307506Z","steps":["trace[985911032] 'agreement among raft nodes before linearized reading' (duration: 380.095782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:22.307575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:21.926112Z","time spent":"381.451532ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T13:11:24.529777Z","caller":"traceutil/trace.go:171","msg":"trace[227603053] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"464.060666ms","start":"2026-04-22T13:11:24.065696Z","end":"2026-04-22T13:11:24.529756Z","steps":["trace[227603053] 'process raft request' (duration: 463.956524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:24.529907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:24.065669Z","time spent":"464.178448ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:24.533876Z","caller":"traceutil/trace.go:171","msg":"trace[1284171492] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"244.203572ms","start":"2026-04-22T13:11:24.289636Z","end":"2026-04-22T13:11:24.53384Z","steps":["trace[1284171492] 'read index received' (duration: 240.262663ms)","trace[1284171492] 'applied index is now lower than readState.Index' (duration: 3.939409ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:24.534031Z","caller":"traceutil/trace.go:171","msg":"trace[621870789] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"266.969297ms","start":"2026-04-22T13:11:24.26705Z","end":"2026-04-22T13:11:24.534019Z","steps":["trace[621870789] 'process raft request' (duration: 266.554068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:24.534801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.483915ms","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-04-22T13:11:24.539671Z","caller":"traceutil/trace.go:171","msg":"trace[1092204368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"220.359916ms","start":"2026-04-22T13:11:24.319278Z","end":"2026-04-22T13:11:24.539638Z","steps":["trace[1092204368] 'agreement among raft nodes before linearized reading' (duration: 215.392653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:24.539451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.719769ms","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-04-22T13:11:24.539825Z","caller":"traceutil/trace.go:171","msg":"trace[2134498147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"223.106257ms","start":"2026-04-22T13:11:24.316703Z","end":"2026-04-22T13:11:24.539809Z","steps":["trace[2134498147] 'agreement among raft nodes before linearized reading' (duration: 217.91162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:24.539944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.151673ms","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-04-22T13:11:24.540005Z","caller":"traceutil/trace.go:171","msg":"trace[283429903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"217.208714ms","start":"2026-04-22T13:11:24.322756Z","end":"2026-04-22T13:11:24.539965Z","steps":["trace[283429903] 'agreement among raft nodes before linearized reading' (duration: 211.880253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:24.540095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.411853ms","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-04-22T13:11:24.540143Z","caller":"traceutil/trace.go:171","msg":"trace[2080502529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"250.591608ms","start":"2026-04-22T13:11:24.289538Z","end":"2026-04-22T13:11:24.540129Z","steps":["trace[2080502529] 'agreement among raft nodes before linearized reading' (duration: 244.727025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.119425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428341669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:11:39.62063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428341669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:11:39.629761Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.014472013s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:11:39.630804Z","caller":"traceutil/trace.go:171","msg":"trace[865688284] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.01520264s","start":"2026-04-22T13:11:38.615197Z","end":"2026-04-22T13:11:39.6304Z","steps":["trace[865688284] 'process raft request' (duration: 1.014997006s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.630929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.615176Z","time spent":"1.01568155s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:39.935977Z","caller":"traceutil/trace.go:171","msg":"trace[1858639817] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"1.317195198s","start":"2026-04-22T13:11:38.618761Z","end":"2026-04-22T13:11:39.935956Z","steps":["trace[1858639817] 'read index received' (duration: 1.011364233s)","trace[1858639817] 'applied index is now lower than readState.Index' (duration: 305.829925ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:39.936076Z","caller":"traceutil/trace.go:171","msg":"trace[1079396103] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.036704705s","start":"2026-04-22T13:11:38.899355Z","end":"2026-04-22T13:11:39.936059Z","steps":["trace[1079396103] 'process raft request' (duration: 1.036549781s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:39.936059Z","caller":"traceutil/trace.go:171","msg":"trace[232148370] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"1.317229538s","start":"2026-04-22T13:11:38.618802Z","end":"2026-04-22T13:11:39.936032Z","steps":["trace[232148370] 'process raft request' (duration: 1.316975493s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.936168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.899328Z","time spent":"1.036781407s","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:39.936244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317405012s","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-04-22T13:11:39.936381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.618788Z","time spent":"1.31734365s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:39.936411Z","caller":"traceutil/trace.go:171","msg":"trace[1909926508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"1.317668168s","start":"2026-04-22T13:11:38.618735Z","end":"2026-04-22T13:11:39.936403Z","steps":["trace[1909926508] 'agreement among raft nodes before linearized reading' (duration: 1.31732605s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.936644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.618725Z","time spent":"1.317910543s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:39.941141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322065477s","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-04-22T13:11:39.941343Z","caller":"traceutil/trace.go:171","msg":"trace[144567244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"1.322270702s","start":"2026-04-22T13:11:38.619013Z","end":"2026-04-22T13:11:39.941284Z","steps":["trace[144567244] 'agreement among raft nodes before linearized reading' (duration: 1.321995406s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.941433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.618994Z","time spent":"1.322428725s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:39.941702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.037473ms","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-04-22T13:11:39.941781Z","caller":"traceutil/trace.go:171","msg":"trace[906408129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"183.149456ms","start":"2026-04-22T13:11:39.758621Z","end":"2026-04-22T13:11:39.941771Z","steps":["trace[906408129] 'agreement among raft nodes before linearized reading' (duration: 183.025943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.941828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294137337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:39.941918Z","caller":"traceutil/trace.go:171","msg":"trace[628542609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"1.29427328s","start":"2026-04-22T13:11:38.647624Z","end":"2026-04-22T13:11:39.941897Z","steps":["trace[628542609] 'agreement among raft nodes before linearized reading' (duration: 1.294056545s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:39.941979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:38.647613Z","time spent":"1.294355181s","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T13:12:15.564839Z","caller":"traceutil/trace.go:171","msg":"trace[58154734] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"156.785609ms","start":"2026-04-22T13:12:15.407991Z","end":"2026-04-22T13:12:15.564777Z","steps":["trace[58154734] 'read index received' (duration: 156.551544ms)","trace[58154734] 'applied index is now lower than readState.Index' (duration: 232.735µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:15.564969Z","caller":"traceutil/trace.go:171","msg":"trace[1041751831] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"389.512174ms","start":"2026-04-22T13:12:15.175422Z","end":"2026-04-22T13:12:15.564934Z","steps":["trace[1041751831] 'process raft request' (duration: 389.185136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:15.565025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.011565ms","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-04-22T13:12:15.565072Z","caller":"traceutil/trace.go:171","msg":"trace[799475541] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1029; }","duration":"157.125247ms","start":"2026-04-22T13:12:15.40793Z","end":"2026-04-22T13:12:15.565055Z","steps":["trace[799475541] 'agreement among raft nodes before linearized reading' (duration: 156.999015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:15.565169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:15.175393Z","time spent":"389.662936ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:16.567032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.936128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:12:16.567148Z","caller":"traceutil/trace.go:171","msg":"trace[1287346388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"764.127202ms","start":"2026-04-22T13:12:15.802997Z","end":"2026-04-22T13:12:16.567124Z","steps":["trace[1287346388] 'range keys from in-memory index tree' (duration: 763.736543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:16.567198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:15.802973Z","time spent":"764.214743ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:12:16.567218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.432406ms","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-04-22T13:12:16.56727Z","caller":"traceutil/trace.go:171","msg":"trace[2119345293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"406.484287ms","start":"2026-04-22T13:12:16.160763Z","end":"2026-04-22T13:12:16.567247Z","steps":["trace[2119345293] 'range keys from in-memory index tree' (duration: 406.344964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:16.567309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.160751Z","time spent":"406.549699ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:12:16.567038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.106912ms","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-04-22T13:12:16.5674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.994097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:16.567435Z","caller":"traceutil/trace.go:171","msg":"trace[1464996737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"442.613952ms","start":"2026-04-22T13:12:16.124808Z","end":"2026-04-22T13:12:16.567422Z","steps":["trace[1464996737] 'range keys from in-memory index tree' (duration: 441.893726ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:16.567473Z","caller":"traceutil/trace.go:171","msg":"trace[1472385619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"919.12049ms","start":"2026-04-22T13:12:15.648336Z","end":"2026-04-22T13:12:16.567456Z","steps":["trace[1472385619] 'range keys from in-memory index tree' (duration: 918.848494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:16.567476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.124786Z","time spent":"442.675064ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:12:16.567504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:15.648314Z","time spent":"919.183852ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:12:16.567573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.497918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-22T13:12:16.567154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.000461ms","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-04-22T13:12:16.567607Z","caller":"traceutil/trace.go:171","msg":"trace[678581340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"402.575109ms","start":"2026-04-22T13:12:16.16502Z","end":"2026-04-22T13:12:16.567595Z","steps":["trace[678581340] 'range keys from in-memory index tree' (duration: 402.375015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:16.567636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.164998Z","time spent":"402.63139ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T13:12:16.567633Z","caller":"traceutil/trace.go:171","msg":"trace[1201283749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"408.516953ms","start":"2026-04-22T13:12:16.159104Z","end":"2026-04-22T13:12:16.567621Z","steps":["trace[1201283749] 'range keys from in-memory index tree' (duration: 407.861148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:16.567743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.159082Z","time spent":"408.653686ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:12:16.567727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.588482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:16.567808Z","caller":"traceutil/trace.go:171","msg":"trace[332276118] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1029; }","duration":"252.722706ms","start":"2026-04-22T13:12:16.315065Z","end":"2026-04-22T13:12:16.567787Z","steps":["trace[332276118] 'count revisions from in-memory index tree' (duration: 252.46325ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:17.307413Z","caller":"traceutil/trace.go:171","msg":"trace[1230898111] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"735.964256ms","start":"2026-04-22T13:12:16.571416Z","end":"2026-04-22T13:12:17.30738Z","steps":["trace[1230898111] 'process raft request' (duration: 735.801052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:17.307646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.571401Z","time spent":"736.144689ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:18.293654Z","caller":"traceutil/trace.go:171","msg":"trace[245202618] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"1.721616568s","start":"2026-04-22T13:12:16.572009Z","end":"2026-04-22T13:12:18.293625Z","steps":["trace[245202618] 'process raft request' (duration: 1.644748572s)","trace[245202618] 'compare' (duration: 76.647851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:18.2938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.571989Z","time spent":"1.72173903s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:18.294072Z","caller":"traceutil/trace.go:171","msg":"trace[896869782] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"1.721963315s","start":"2026-04-22T13:12:16.572074Z","end":"2026-04-22T13:12:18.294037Z","steps":["trace[896869782] 'process raft request' (duration: 1.721516235s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.294296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.572069Z","time spent":"1.722107119s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:18.294556Z","caller":"traceutil/trace.go:171","msg":"trace[1446260507] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"1.722241413s","start":"2026-04-22T13:12:16.572283Z","end":"2026-04-22T13:12:18.294525Z","steps":["trace[1446260507] 'process raft request' (duration: 1.721636738s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.294732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.572274Z","time spent":"1.722360075s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:18.294965Z","caller":"traceutil/trace.go:171","msg":"trace[371136117] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1066; }","duration":"1.011841305s","start":"2026-04-22T13:12:17.283105Z","end":"2026-04-22T13:12:18.294946Z","steps":["trace[371136117] 'read index received' (duration: 24.066254ms)","trace[371136117] 'applied index is now lower than readState.Index' (duration: 987.773201ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:18.295124Z","caller":"traceutil/trace.go:171","msg":"trace[753504351] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"1.722063539s","start":"2026-04-22T13:12:16.573047Z","end":"2026-04-22T13:12:18.295111Z","steps":["trace[753504351] 'process raft request' (duration: 1.721328601s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.295203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:16.573036Z","time spent":"1.72211827s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:18.295912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012771335s","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-04-22T13:12:18.296037Z","caller":"traceutil/trace.go:171","msg":"trace[1385666410] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1034; }","duration":"1.012922668s","start":"2026-04-22T13:12:17.283073Z","end":"2026-04-22T13:12:18.295996Z","steps":["trace[1385666410] 'agreement among raft nodes before linearized reading' (duration: 1.012719024s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.296101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:17.283052Z","time spent":"1.013036581s","remote":"127.0.0.1:46030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:12:18.300867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.421581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:18.300923Z","caller":"traceutil/trace.go:171","msg":"trace[728843706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"641.512043ms","start":"2026-04-22T13:12:17.659398Z","end":"2026-04-22T13:12:18.30091Z","steps":["trace[728843706] 'agreement among raft nodes before linearized reading' (duration: 641.409911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.300956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:17.659381Z","time spent":"641.568294ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:12:18.300957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.065151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:18.300858Z","caller":"traceutil/trace.go:171","msg":"trace[228973384] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"731.070645ms","start":"2026-04-22T13:12:17.569763Z","end":"2026-04-22T13:12:18.300833Z","steps":["trace[228973384] 'process raft request' (duration: 730.963363ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:18.300998Z","caller":"traceutil/trace.go:171","msg":"trace[1665670343] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1035; }","duration":"582.155183ms","start":"2026-04-22T13:12:17.718832Z","end":"2026-04-22T13:12:18.300987Z","steps":["trace[1665670343] 'agreement among raft nodes before linearized reading' (duration: 582.078481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:18.301034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:17.718804Z","time spent":"582.220165ms","remote":"127.0.0.1:46070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:12:18.301052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:17.569737Z","time spent":"731.274839ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:44.069697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.343682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:44.069813Z","caller":"traceutil/trace.go:171","msg":"trace[713386835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"420.516205ms","start":"2026-04-22T13:12:43.649264Z","end":"2026-04-22T13:12:44.069781Z","steps":["trace[713386835] 'range keys from in-memory index tree' (duration: 420.225059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:44.069856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:43.649233Z","time spent":"420.613669ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:12:44.938827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:12:45.439613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:12:45.755677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.327057579s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:12:45.756311Z","caller":"traceutil/trace.go:171","msg":"trace[1159654477] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"1.318582448s","start":"2026-04-22T13:12:44.437695Z","end":"2026-04-22T13:12:45.756278Z","steps":["trace[1159654477] 'read index received' (duration: 1.318337941s)","trace[1159654477] 'applied index is now lower than readState.Index' (duration: 243.297µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:45.756441Z","caller":"traceutil/trace.go:171","msg":"trace[601913705] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.32795461s","start":"2026-04-22T13:12:44.428437Z","end":"2026-04-22T13:12:45.756392Z","steps":["trace[601913705] 'process raft request' (duration: 1.327675613s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.756651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.428407Z","time spent":"1.328141525s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:45.75677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319038088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:12:45.756887Z","caller":"traceutil/trace.go:171","msg":"trace[310673558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1116; }","duration":"1.319212472s","start":"2026-04-22T13:12:44.437645Z","end":"2026-04-22T13:12:45.756858Z","steps":["trace[310673558] 'agreement among raft nodes before linearized reading' (duration: 1.318873454s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.756933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.437623Z","time spent":"1.319301894s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:12:45.779804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338918058s","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-04-22T13:12:45.779813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145832475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:45.779868Z","caller":"traceutil/trace.go:171","msg":"trace[967981347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1117; }","duration":"1.339008801s","start":"2026-04-22T13:12:44.440845Z","end":"2026-04-22T13:12:45.779854Z","steps":["trace[967981347] 'agreement among raft nodes before linearized reading' (duration: 1.338808916s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.779907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.440835Z","time spent":"1.339064072s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T13:12:45.779917Z","caller":"traceutil/trace.go:171","msg":"trace[626158864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1117; }","duration":"1.145996169s","start":"2026-04-22T13:12:44.633895Z","end":"2026-04-22T13:12:45.779891Z","steps":["trace[626158864] 'agreement among raft nodes before linearized reading' (duration: 1.145799635s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.779969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.633879Z","time spent":"1.146078321s","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:12:45.780127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157575722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:12:45.780157Z","caller":"traceutil/trace.go:171","msg":"trace[1577849175] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:1117; }","duration":"1.157694304s","start":"2026-04-22T13:12:44.622453Z","end":"2026-04-22T13:12:45.780147Z","steps":["trace[1577849175] 'agreement among raft nodes before linearized reading' (duration: 1.157608192s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.780182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.622436Z","time spent":"1.157739995s","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-22T13:12:45.780353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279559024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:45.780392Z","caller":"traceutil/trace.go:171","msg":"trace[640491503] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1117; }","duration":"1.279647606s","start":"2026-04-22T13:12:44.500734Z","end":"2026-04-22T13:12:45.780382Z","steps":["trace[640491503] 'agreement among raft nodes before linearized reading' (duration: 1.279588765s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.78042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.500696Z","time spent":"1.279716818s","remote":"127.0.0.1:32972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:12:45.780426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.406431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:45.780461Z","caller":"traceutil/trace.go:171","msg":"trace[970520389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"132.490043ms","start":"2026-04-22T13:12:45.64796Z","end":"2026-04-22T13:12:45.78045Z","steps":["trace[970520389] 'agreement among raft nodes before linearized reading' (duration: 132.420201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.780672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329532136s","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-04-22T13:12:45.780856Z","caller":"traceutil/trace.go:171","msg":"trace[93873733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1117; }","duration":"1.329695499s","start":"2026-04-22T13:12:44.451107Z","end":"2026-04-22T13:12:45.780803Z","steps":["trace[93873733] 'agreement among raft nodes before linearized reading' (duration: 1.329436233s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.780957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.451099Z","time spent":"1.329795421s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:12:45.781018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332057902s","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-04-22T13:12:45.78106Z","caller":"traceutil/trace.go:171","msg":"trace[1996709122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"1.332123914s","start":"2026-04-22T13:12:44.448923Z","end":"2026-04-22T13:12:45.781047Z","steps":["trace[1996709122] 'agreement among raft nodes before linearized reading' (duration: 1.331964301s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:45.780356Z","caller":"traceutil/trace.go:171","msg":"trace[260194291] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"333.815998ms","start":"2026-04-22T13:12:45.446525Z","end":"2026-04-22T13:12:45.780341Z","steps":["trace[260194291] 'process raft request' (duration: 332.997499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.781096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:44.448912Z","time spent":"1.332176605s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:12:45.781134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.181152ms","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-04-22T13:12:45.781187Z","caller":"traceutil/trace.go:171","msg":"trace[842073750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"239.304215ms","start":"2026-04-22T13:12:45.541866Z","end":"2026-04-22T13:12:45.781171Z","steps":["trace[842073750] 'agreement among raft nodes before linearized reading' (duration: 239.164132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:45.781258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:45.446463Z","time spent":"334.694857ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:48.088431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.920743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:12:48.088559Z","caller":"traceutil/trace.go:171","msg":"trace[278146713] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"256.982947ms","start":"2026-04-22T13:12:47.831551Z","end":"2026-04-22T13:12:48.088534Z","steps":["trace[278146713] 'process raft request' (duration: 112.846509ms)","trace[278146713] 'compare' (duration: 143.77916ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:48.124515Z","caller":"traceutil/trace.go:171","msg":"trace[1464360887] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"290.582998ms","start":"2026-04-22T13:12:47.8339Z","end":"2026-04-22T13:12:48.124483Z","steps":["trace[1464360887] 'process raft request' (duration: 290.538837ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:48.12499Z","caller":"traceutil/trace.go:171","msg":"trace[314561022] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"291.197171ms","start":"2026-04-22T13:12:47.833722Z","end":"2026-04-22T13:12:48.124919Z","steps":["trace[314561022] 'process raft request' (duration: 290.631119ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:48.125016Z","caller":"traceutil/trace.go:171","msg":"trace[1375249498] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"292.46252ms","start":"2026-04-22T13:12:47.832512Z","end":"2026-04-22T13:12:48.124975Z","steps":["trace[1375249498] 'process raft request' (duration: 291.674982ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:48.125064Z","caller":"traceutil/trace.go:171","msg":"trace[36045426] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"291.157051ms","start":"2026-04-22T13:12:47.833866Z","end":"2026-04-22T13:12:48.125023Z","steps":["trace[36045426] 'process raft request' (duration: 290.538317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:49.750216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.841999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:49.750347Z","caller":"traceutil/trace.go:171","msg":"trace[1640330139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"102.050793ms","start":"2026-04-22T13:12:49.648272Z","end":"2026-04-22T13:12:49.750323Z","steps":["trace[1640330139] 'range keys from in-memory index tree' (duration: 101.627453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:50.198847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.849322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T13:12:50.198966Z","caller":"traceutil/trace.go:171","msg":"trace[234127643] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"105.773187ms","start":"2026-04-22T13:12:50.093171Z","end":"2026-04-22T13:12:50.198944Z","steps":["trace[234127643] 'read index received' (duration: 72.552µs)","trace[234127643] 'applied index is now lower than readState.Index' (duration: 105.698735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:50.199239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.051013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:12:50.199331Z","caller":"traceutil/trace.go:171","msg":"trace[529020615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"106.155927ms","start":"2026-04-22T13:12:50.093152Z","end":"2026-04-22T13:12:50.199308Z","steps":["trace[529020615] 'agreement among raft nodes before linearized reading' (duration: 105.872139ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:52.379717Z","caller":"traceutil/trace.go:171","msg":"trace[2018529442] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"113.33043ms","start":"2026-04-22T13:12:52.26636Z","end":"2026-04-22T13:12:52.37969Z","steps":["trace[2018529442] 'process raft request' (duration: 113.154376ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:52.380922Z","caller":"traceutil/trace.go:171","msg":"trace[2094252860] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"113.849401ms","start":"2026-04-22T13:12:52.267046Z","end":"2026-04-22T13:12:52.380896Z","steps":["trace[2094252860] 'process raft request' (duration: 112.599303ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:52.381337Z","caller":"traceutil/trace.go:171","msg":"trace[104958963] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"113.725067ms","start":"2026-04-22T13:12:52.267598Z","end":"2026-04-22T13:12:52.381323Z","steps":["trace[104958963] 'process raft request' (duration: 113.227656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:04.961166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:05.461464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:05.523142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.630296239s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:13:05.523678Z","caller":"traceutil/trace.go:171","msg":"trace[1215989335] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.630927243s","start":"2026-04-22T13:13:03.892711Z","end":"2026-04-22T13:13:05.523638Z","steps":["trace[1215989335] 'process raft request' (duration: 1.630689558s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:05.523665Z","caller":"traceutil/trace.go:171","msg":"trace[2115724083] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"1.062520108s","start":"2026-04-22T13:13:04.461092Z","end":"2026-04-22T13:13:05.523612Z","steps":["trace[2115724083] 'read index received' (duration: 1.062275892s)","trace[2115724083] 'applied index is now lower than readState.Index' (duration: 242.746µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:05.523885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:03.892688Z","time spent":"1.631051445s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:05.523977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062860375s","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-04-22T13:13:05.524029Z","caller":"traceutil/trace.go:171","msg":"trace[625582166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.062982598s","start":"2026-04-22T13:13:04.461035Z","end":"2026-04-22T13:13:05.524018Z","steps":["trace[625582166] 'agreement among raft nodes before linearized reading' (duration: 1.062759193s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.524074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.461013Z","time spent":"1.063051451s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:05.52428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015765488s","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-04-22T13:13:05.524319Z","caller":"traceutil/trace.go:171","msg":"trace[783959180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.015813659s","start":"2026-04-22T13:13:04.508496Z","end":"2026-04-22T13:13:05.52431Z","steps":["trace[783959180] 'agreement among raft nodes before linearized reading' (duration: 1.015720507s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.524356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.508491Z","time spent":"1.01585614s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:05.524534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.551615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:13:05.524628Z","caller":"traceutil/trace.go:171","msg":"trace[792306077] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:1227; }","duration":"900.719479ms","start":"2026-04-22T13:13:04.623889Z","end":"2026-04-22T13:13:05.524608Z","steps":["trace[792306077] 'agreement among raft nodes before linearized reading' (duration: 900.395212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.524678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.623864Z","time spent":"900.8064ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-22T13:13:05.524738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015140313s","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-04-22T13:13:05.524792Z","caller":"traceutil/trace.go:171","msg":"trace[1553832715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"1.015204575s","start":"2026-04-22T13:13:04.509573Z","end":"2026-04-22T13:13:05.524778Z","steps":["trace[1553832715] 'agreement among raft nodes before linearized reading' (duration: 1.015055181s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.524828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.509569Z","time spent":"1.015252546s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:05.524828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013119907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T13:13:05.524856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.761009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:05.524876Z","caller":"traceutil/trace.go:171","msg":"trace[2046231590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"1.013168197s","start":"2026-04-22T13:13:04.511692Z","end":"2026-04-22T13:13:05.524861Z","steps":["trace[2046231590] 'agreement among raft nodes before linearized reading' (duration: 1.013079685s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:05.524899Z","caller":"traceutil/trace.go:171","msg":"trace[1162755761] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1227; }","duration":"735.863691ms","start":"2026-04-22T13:13:04.789025Z","end":"2026-04-22T13:13:05.524888Z","steps":["trace[1162755761] 'agreement among raft nodes before linearized reading' (duration: 735.789599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.524909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.511689Z","time spent":"1.013213658s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:13:05.524935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.788996Z","time spent":"735.930163ms","remote":"127.0.0.1:33030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:05.525011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.519262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:05.525039Z","caller":"traceutil/trace.go:171","msg":"trace[121806913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"865.587293ms","start":"2026-04-22T13:13:04.659443Z","end":"2026-04-22T13:13:05.52503Z","steps":["trace[121806913] 'agreement among raft nodes before linearized reading' (duration: 865.543872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.525064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.659419Z","time spent":"865.637624ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:13:05.524737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014557269s","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-04-22T13:13:05.525134Z","caller":"traceutil/trace.go:171","msg":"trace[727747796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"1.014966379s","start":"2026-04-22T13:13:04.510156Z","end":"2026-04-22T13:13:05.525123Z","steps":["trace[727747796] 'agreement among raft nodes before linearized reading' (duration: 1.014376486s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:05.525178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:04.510152Z","time spent":"1.01501427s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T13:13:20.23857Z","caller":"traceutil/trace.go:171","msg":"trace[608306674] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"311.917323ms","start":"2026-04-22T13:13:19.926623Z","end":"2026-04-22T13:13:20.238541Z","steps":["trace[608306674] 'process raft request' (duration: 311.707217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.238759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:19.926608Z","time spent":"312.056585ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:20.34119Z","caller":"traceutil/trace.go:171","msg":"trace[375992037] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"276.177581ms","start":"2026-04-22T13:13:20.064956Z","end":"2026-04-22T13:13:20.341134Z","steps":["trace[375992037] 'process raft request' (duration: 275.929185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.962856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:21.463298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428342821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:13:21.81005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.420125865s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T13:13:21.810794Z","caller":"traceutil/trace.go:171","msg":"trace[352247137] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"1.421040955s","start":"2026-04-22T13:13:20.389727Z","end":"2026-04-22T13:13:21.810768Z","steps":["trace[352247137] 'process raft request' (duration: 1.420657676s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.810956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.389701Z","time spent":"1.421156948s","remote":"127.0.0.1:46026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:21.885116Z","caller":"traceutil/trace.go:171","msg":"trace[19218739] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1348; }","duration":"1.422561699s","start":"2026-04-22T13:13:20.462525Z","end":"2026-04-22T13:13:21.885087Z","steps":["trace[19218739] 'read index received' (duration: 1.347842946s)","trace[19218739] 'applied index is now lower than readState.Index' (duration: 74.717583ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:21.885549Z","caller":"traceutil/trace.go:171","msg":"trace[238853981] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"303.160262ms","start":"2026-04-22T13:13:21.582377Z","end":"2026-04-22T13:13:21.885537Z","steps":["trace[238853981] 'process raft request' (duration: 302.558059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.885644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:21.582346Z","time spent":"303.229855ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:21.885817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423343508s","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":"info","ts":"2026-04-22T13:13:21.885846Z","caller":"traceutil/trace.go:171","msg":"trace[718820714] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1298; }","duration":"1.42342495s","start":"2026-04-22T13:13:20.462412Z","end":"2026-04-22T13:13:21.885837Z","steps":["trace[718820714] 'agreement among raft nodes before linearized reading' (duration: 1.423379669s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.885867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.462383Z","time spent":"1.423479261s","remote":"127.0.0.1:46060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:21.893352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244587605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:21.893437Z","caller":"traceutil/trace.go:171","msg":"trace[934803301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"1.244721838s","start":"2026-04-22T13:13:20.648698Z","end":"2026-04-22T13:13:21.89342Z","steps":["trace[934803301] 'agreement among raft nodes before linearized reading' (duration: 1.244580655s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.893475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.648679Z","time spent":"1.244789659s","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:13:21.893805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.990241ms","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-04-22T13:13:21.893844Z","caller":"traceutil/trace.go:171","msg":"trace[3432298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"255.038851ms","start":"2026-04-22T13:13:21.638796Z","end":"2026-04-22T13:13:21.893834Z","steps":["trace[3432298] 'agreement among raft nodes before linearized reading' (duration: 254.916159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.894004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.259691ms","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-04-22T13:13:21.894107Z","caller":"traceutil/trace.go:171","msg":"trace[1321200631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1301; }","duration":"257.424236ms","start":"2026-04-22T13:13:21.636657Z","end":"2026-04-22T13:13:21.894082Z","steps":["trace[1321200631] 'agreement among raft nodes before linearized reading' (duration: 257.113008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.894442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.607998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T13:13:21.894543Z","caller":"traceutil/trace.go:171","msg":"trace[117759069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"256.732581ms","start":"2026-04-22T13:13:21.637797Z","end":"2026-04-22T13:13:21.89453Z","steps":["trace[117759069] 'agreement among raft nodes before linearized reading' (duration: 256.536497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.894729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.631305ms","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-04-22T13:13:21.894776Z","caller":"traceutil/trace.go:171","msg":"trace[1525678890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"255.734108ms","start":"2026-04-22T13:13:21.639032Z","end":"2026-04-22T13:13:21.894766Z","steps":["trace[1525678890] 'agreement among raft nodes before linearized reading' (duration: 255.625536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:21.896179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.264172ms","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-04-22T13:13:21.896266Z","caller":"traceutil/trace.go:171","msg":"trace[1897227509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"257.377135ms","start":"2026-04-22T13:13:21.638875Z","end":"2026-04-22T13:13:21.896252Z","steps":["trace[1897227509] 'agreement among raft nodes before linearized reading' (duration: 254.530671ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.036298Z","caller":"traceutil/trace.go:171","msg":"trace[852947278] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1354; }","duration":"130.703072ms","start":"2026-04-22T13:13:21.90556Z","end":"2026-04-22T13:13:22.036263Z","steps":["trace[852947278] 'read index received' (duration: 19.373259ms)","trace[852947278] 'applied index is now lower than readState.Index' (duration: 111.328263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:22.036512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.909107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:22.036565Z","caller":"traceutil/trace.go:171","msg":"trace[302939437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"131.01795ms","start":"2026-04-22T13:13:21.905528Z","end":"2026-04-22T13:13:22.036546Z","steps":["trace[302939437] 'agreement among raft nodes before linearized reading' (duration: 130.872446ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.037234Z","caller":"traceutil/trace.go:171","msg":"trace[1826250510] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"136.298441ms","start":"2026-04-22T13:13:21.900917Z","end":"2026-04-22T13:13:22.037216Z","steps":["trace[1826250510] 'process raft request' (duration: 90.795898ms)","trace[1826250510] 'compare' (duration: 44.174882ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:22.037411Z","caller":"traceutil/trace.go:171","msg":"trace[32781034] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"136.443944ms","start":"2026-04-22T13:13:21.900957Z","end":"2026-04-22T13:13:22.037401Z","steps":["trace[32781034] 'process raft request' (duration: 135.136494ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.037562Z","caller":"traceutil/trace.go:171","msg":"trace[1669020876] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"136.555266ms","start":"2026-04-22T13:13:21.900992Z","end":"2026-04-22T13:13:22.037547Z","steps":["trace[1669020876] 'process raft request' (duration: 135.174074ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.038263Z","caller":"traceutil/trace.go:171","msg":"trace[95219890] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"136.173297ms","start":"2026-04-22T13:13:21.902063Z","end":"2026-04-22T13:13:22.038237Z","steps":["trace[95219890] 'process raft request' (duration: 134.147731ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.858374Z","caller":"traceutil/trace.go:171","msg":"trace[708201229] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"290.559867ms","start":"2026-04-22T13:13:22.567795Z","end":"2026-04-22T13:13:22.858355Z","steps":["trace[708201229] 'read index received' (duration: 290.372073ms)","trace[708201229] 'applied index is now lower than readState.Index' (duration: 186.924µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:22.858562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.740882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:22.858593Z","caller":"traceutil/trace.go:171","msg":"trace[577231772] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1308; }","duration":"290.825223ms","start":"2026-04-22T13:13:22.567757Z","end":"2026-04-22T13:13:22.858582Z","steps":["trace[577231772] 'agreement among raft nodes before linearized reading' (duration: 290.68959ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:22.858835Z","caller":"traceutil/trace.go:171","msg":"trace[959782453] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"381.458437ms","start":"2026-04-22T13:13:22.477368Z","end":"2026-04-22T13:13:22.858826Z","steps":["trace[959782453] 'process raft request' (duration: 380.864104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:22.858914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:22.477336Z","time spent":"381.526889ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:13:23.611576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.245349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:23.611703Z","caller":"traceutil/trace.go:171","msg":"trace[66361626] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"628.029708ms","start":"2026-04-22T13:13:22.983653Z","end":"2026-04-22T13:13:23.611683Z","steps":["trace[66361626] 'read index received' (duration: 184.514623ms)","trace[66361626] 'applied index is now lower than readState.Index' (duration: 443.513815ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:23.611942Z","caller":"traceutil/trace.go:171","msg":"trace[1717120619] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"737.838207ms","start":"2026-04-22T13:13:22.874086Z","end":"2026-04-22T13:13:23.611924Z","steps":["trace[1717120619] 'process raft request' (duration: 294.155488ms)","trace[1717120619] 'compare' (duration: 442.41667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:23.612016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:22.87405Z","time spent":"737.93714ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T13:13:23.612826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.161664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:23.612877Z","caller":"traceutil/trace.go:171","msg":"trace[444183346] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1309; }","duration":"629.279566ms","start":"2026-04-22T13:13:22.983584Z","end":"2026-04-22T13:13:23.612863Z","steps":["trace[444183346] 'agreement among raft nodes before linearized reading' (duration: 628.151521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:23.61292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:22.98356Z","time spent":"629.350377ms","remote":"127.0.0.1:33160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:13:24.071725Z","caller":"traceutil/trace.go:171","msg":"trace[632478844] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"785.293373ms","start":"2026-04-22T13:13:23.286409Z","end":"2026-04-22T13:13:24.071702Z","steps":["trace[632478844] 'process raft request' (duration: 777.566527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.07201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.286384Z","time spent":"785.518118ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:13:24.081705Z","caller":"traceutil/trace.go:171","msg":"trace[1740323580] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"460.512359ms","start":"2026-04-22T13:13:23.621164Z","end":"2026-04-22T13:13:24.081676Z","steps":["trace[1740323580] 'read index received' (duration: 442.826888ms)","trace[1740323580] 'applied index is now lower than readState.Index' (duration: 17.684111ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:24.08178Z","caller":"traceutil/trace.go:171","msg":"trace[1022597264] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"460.832957ms","start":"2026-04-22T13:13:23.620926Z","end":"2026-04-22T13:13:24.081759Z","steps":["trace[1022597264] 'process raft request' (duration: 460.559771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.081966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.775647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-22T13:13:24.081992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.620906Z","time spent":"461.054702ms","remote":"127.0.0.1:45900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:13:24.082012Z","caller":"traceutil/trace.go:171","msg":"trace[1930212528] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1312; }","duration":"460.890109ms","start":"2026-04-22T13:13:23.621109Z","end":"2026-04-22T13:13:24.081999Z","steps":["trace[1930212528] 'agreement among raft nodes before linearized reading' (duration: 460.709914ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:24.081784Z","caller":"traceutil/trace.go:171","msg":"trace[604179963] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"190.132819ms","start":"2026-04-22T13:13:23.891614Z","end":"2026-04-22T13:13:24.081747Z","steps":["trace[604179963] 'process raft request' (duration: 189.993496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.082045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.621085Z","time spent":"460.95255ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-22T13:13:24.123989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.440948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:24.12414Z","caller":"traceutil/trace.go:171","msg":"trace[1456713980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"475.668874ms","start":"2026-04-22T13:13:23.648444Z","end":"2026-04-22T13:13:24.124113Z","steps":["trace[1456713980] 'agreement among raft nodes before linearized reading' (duration: 475.379956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.124196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.648419Z","time spent":"475.766365ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:13:24.124621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.842956ms","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-04-22T13:13:24.124664Z","caller":"traceutil/trace.go:171","msg":"trace[1766327294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"186.919148ms","start":"2026-04-22T13:13:23.93773Z","end":"2026-04-22T13:13:24.124649Z","steps":["trace[1766327294] 'agreement among raft nodes before linearized reading' (duration: 186.748294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.989633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:24.626748Z","time spent":"362.880627ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:13:25.607199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.113842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:25.607439Z","caller":"traceutil/trace.go:171","msg":"trace[1311429896] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"616.597378ms","start":"2026-04-22T13:13:24.990741Z","end":"2026-04-22T13:13:25.607339Z","steps":["trace[1311429896] 'process raft request' (duration: 474.21023ms)","trace[1311429896] 'compare' (duration: 141.920848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:25.607923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:24.990717Z","time spent":"617.162901ms","remote":"127.0.0.1:45852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:38.966432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.500211ms","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-04-22T13:13:38.9666Z","caller":"traceutil/trace.go:171","msg":"trace[1412412303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1404; }","duration":"625.691225ms","start":"2026-04-22T13:13:38.340887Z","end":"2026-04-22T13:13:38.966579Z","steps":["trace[1412412303] 'range keys from in-memory index tree' (duration: 625.277177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.966631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.750305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T13:13:38.966658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.340812Z","time spent":"625.829199ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T13:13:38.966695Z","caller":"traceutil/trace.go:171","msg":"trace[1657351756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1404; }","duration":"624.825556ms","start":"2026-04-22T13:13:38.34185Z","end":"2026-04-22T13:13:38.966676Z","steps":["trace[1657351756] 'range keys from in-memory index tree' (duration: 624.660563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.966464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.736114ms","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-04-22T13:13:38.966746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.34184Z","time spent":"624.896389ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:38.966773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.059911ms","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-04-22T13:13:38.966818Z","caller":"traceutil/trace.go:171","msg":"trace[2147238181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1404; }","duration":"620.162552ms","start":"2026-04-22T13:13:38.346615Z","end":"2026-04-22T13:13:38.966778Z","steps":["trace[2147238181] 'range keys from in-memory index tree' (duration: 619.55869ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:38.96689Z","caller":"traceutil/trace.go:171","msg":"trace[317368949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1404; }","duration":"620.233514ms","start":"2026-04-22T13:13:38.346628Z","end":"2026-04-22T13:13:38.966862Z","steps":["trace[317368949] 'range keys from in-memory index tree' (duration: 619.692322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.966884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.508468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T13:13:38.966946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.346603Z","time spent":"620.297976ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:38.966955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.346616Z","time spent":"620.325676ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:13:38.966985Z","caller":"traceutil/trace.go:171","msg":"trace[1405006836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1404; }","duration":"628.66014ms","start":"2026-04-22T13:13:38.338301Z","end":"2026-04-22T13:13:38.966962Z","steps":["trace[1405006836] 'range keys from in-memory index tree' (duration: 628.182741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.967038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.338288Z","time spent":"628.739663ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:13:38.967178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.465758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-22T13:13:38.967301Z","caller":"traceutil/trace.go:171","msg":"trace[954722734] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1404; }","duration":"290.634562ms","start":"2026-04-22T13:13:38.676619Z","end":"2026-04-22T13:13:38.967254Z","steps":["trace[954722734] 'range keys from in-memory index tree' (duration: 290.169252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:39.544424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696267428343038,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T13:13:39.963678Z","caller":"traceutil/trace.go:171","msg":"trace[2057279390] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"990.156373ms","start":"2026-04-22T13:13:38.973483Z","end":"2026-04-22T13:13:39.963639Z","steps":["trace[2057279390] 'process raft request' (duration: 989.919068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:39.963946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.973452Z","time spent":"990.340616ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.145418Z","caller":"traceutil/trace.go:171","msg":"trace[441461394] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"1.171482894s","start":"2026-04-22T13:13:38.973832Z","end":"2026-04-22T13:13:40.145315Z","steps":["trace[441461394] 'process raft request' (duration: 1.171230939s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.145771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.973818Z","time spent":"1.171707139s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.145812Z","caller":"traceutil/trace.go:171","msg":"trace[1780222390] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"1.171883683s","start":"2026-04-22T13:13:38.973897Z","end":"2026-04-22T13:13:40.145781Z","steps":["trace[1780222390] 'process raft request' (duration: 1.171368452s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.145991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.973864Z","time spent":"1.172027876s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.147167Z","caller":"traceutil/trace.go:171","msg":"trace[1681473869] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"1.173207111s","start":"2026-04-22T13:13:38.973942Z","end":"2026-04-22T13:13:40.147149Z","steps":["trace[1681473869] 'process raft request' (duration: 1.171671998s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.147253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.973902Z","time spent":"1.173312315s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.147608Z","caller":"traceutil/trace.go:171","msg":"trace[39387834] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"1.173542058s","start":"2026-04-22T13:13:38.974054Z","end":"2026-04-22T13:13:40.147596Z","steps":["trace[39387834] 'process raft request' (duration: 1.172868383s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.147679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.974042Z","time spent":"1.17360304s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.147962Z","caller":"traceutil/trace.go:171","msg":"trace[73013934] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"1.173830566s","start":"2026-04-22T13:13:38.974121Z","end":"2026-04-22T13:13:40.147951Z","steps":["trace[73013934] 'process raft request' (duration: 1.173019307s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.148036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.974094Z","time spent":"1.173905737s","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:40.148329Z","caller":"traceutil/trace.go:171","msg":"trace[557063312] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1460; }","duration":"1.104356149s","start":"2026-04-22T13:13:39.043959Z","end":"2026-04-22T13:13:40.148315Z","steps":["trace[557063312] 'read index received' (duration: 919.53866ms)","trace[557063312] 'applied index is now lower than readState.Index' (duration: 184.815909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:40.153425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.482734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:13:40.153452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.470235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-22T13:13:40.153476Z","caller":"traceutil/trace.go:171","msg":"trace[35115979] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1410; }","duration":"161.583026ms","start":"2026-04-22T13:13:39.99188Z","end":"2026-04-22T13:13:40.153463Z","steps":["trace[35115979] 'agreement among raft nodes before linearized reading' (duration: 161.494754ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.153493Z","caller":"traceutil/trace.go:171","msg":"trace[1845773758] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1410; }","duration":"970.569718ms","start":"2026-04-22T13:13:39.182913Z","end":"2026-04-22T13:13:40.153483Z","steps":["trace[1845773758] 'agreement among raft nodes before linearized reading' (duration: 970.440426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.153529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.182882Z","time spent":"970.639899ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-22T13:13:40.153681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.855866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:40.153994Z","caller":"traceutil/trace.go:171","msg":"trace[1606852751] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1410; }","duration":"456.238544ms","start":"2026-04-22T13:13:39.697748Z","end":"2026-04-22T13:13:40.153986Z","steps":["trace[1606852751] 'agreement among raft nodes before linearized reading' (duration: 455.909797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.154018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.697706Z","time spent":"456.306786ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:40.153797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.110029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:40.154152Z","caller":"traceutil/trace.go:171","msg":"trace[843234767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1410; }","duration":"506.504378ms","start":"2026-04-22T13:13:39.64764Z","end":"2026-04-22T13:13:40.154144Z","steps":["trace[843234767] 'agreement among raft nodes before linearized reading' (duration: 506.13241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.154174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.647616Z","time spent":"506.551678ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:13:40.153876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.809294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:40.154354Z","caller":"traceutil/trace.go:171","msg":"trace[1714427444] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1410; }","duration":"305.307484ms","start":"2026-04-22T13:13:39.849023Z","end":"2026-04-22T13:13:40.154331Z","steps":["trace[1714427444] 'agreement among raft nodes before linearized reading' (duration: 304.815883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.154444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.849008Z","time spent":"305.401337ms","remote":"127.0.0.1:46176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:40.153855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.050296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-04-22T13:13:40.154561Z","caller":"traceutil/trace.go:171","msg":"trace[680210590] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1410; }","duration":"105.794852ms","start":"2026-04-22T13:13:40.048744Z","end":"2026-04-22T13:13:40.154538Z","steps":["trace[680210590] 'agreement among raft nodes before linearized reading' (duration: 104.906773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.153945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109985623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:40.154698Z","caller":"traceutil/trace.go:171","msg":"trace[1266381195] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1410; }","duration":"1.110775581s","start":"2026-04-22T13:13:39.043915Z","end":"2026-04-22T13:13:40.154691Z","steps":["trace[1266381195] 'agreement among raft nodes before linearized reading' (duration: 1.104535713s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.153621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.528863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:13:40.154725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.043895Z","time spent":"1.110819692s","remote":"127.0.0.1:46164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:13:40.155182Z","caller":"traceutil/trace.go:171","msg":"trace[7062087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1410; }","duration":"312.78572ms","start":"2026-04-22T13:13:39.842007Z","end":"2026-04-22T13:13:40.154792Z","steps":["trace[7062087] 'agreement among raft nodes before linearized reading' (duration: 311.452391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:40.155308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:39.841936Z","time spent":"313.349543ms","remote":"127.0.0.1:33128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:14:30.473196Z","caller":"traceutil/trace.go:171","msg":"trace[452792910] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"153.258792ms","start":"2026-04-22T13:14:30.319904Z","end":"2026-04-22T13:14:30.473162Z","steps":["trace[452792910] 'process raft request' (duration: 153.061547ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:16:57.777181Z","caller":"traceutil/trace.go:171","msg":"trace[867921370] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"101.546305ms","start":"2026-04-22T13:16:57.675609Z","end":"2026-04-22T13:16:57.777155Z","steps":["trace[867921370] 'process raft request' (duration: 101.35522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:17:08.009023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.950336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:17:08.009295Z","caller":"traceutil/trace.go:171","msg":"trace[594066017] linearizableReadLoop","detail":"{readStateIndex:2491; appliedIndex:2489; }","duration":"361.02021ms","start":"2026-04-22T13:17:07.648253Z","end":"2026-04-22T13:17:08.009274Z","steps":["trace[594066017] 'read index received' (duration: 74.752806ms)","trace[594066017] 'applied index is now lower than readState.Index' (duration: 286.266253ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:17:08.009388Z","caller":"traceutil/trace.go:171","msg":"trace[31380702] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"403.631841ms","start":"2026-04-22T13:17:07.605745Z","end":"2026-04-22T13:17:08.009377Z","steps":["trace[31380702] 'process raft request' (duration: 403.421496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:17:08.009447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:17:07.605718Z","time spent":"403.696523ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:17:08.009672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.41551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:17:08.009664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.449119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-22T13:17:08.009736Z","caller":"traceutil/trace.go:171","msg":"trace[1233614575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2392; }","duration":"361.509901ms","start":"2026-04-22T13:17:07.648207Z","end":"2026-04-22T13:17:08.009717Z","steps":["trace[1233614575] 'agreement among raft nodes before linearized reading' (duration: 361.409609ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:17:08.009679Z","caller":"traceutil/trace.go:171","msg":"trace[1980434378] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"410.245388ms","start":"2026-04-22T13:17:07.599141Z","end":"2026-04-22T13:17:08.009386Z","steps":["trace[1980434378] 'process raft request' (duration: 123.812691ms)","trace[1980434378] 'compare' (duration: 285.767212ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:17:08.009756Z","caller":"traceutil/trace.go:171","msg":"trace[1362693627] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2392; }","duration":"182.601983ms","start":"2026-04-22T13:17:07.827129Z","end":"2026-04-22T13:17:08.009731Z","steps":["trace[1362693627] 'agreement among raft nodes before linearized reading' (duration: 182.317745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:17:08.009784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:17:07.648188Z","time spent":"361.588784ms","remote":"127.0.0.1:45818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:17:08.009951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:17:07.599105Z","time spent":"410.71399ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}