{"level":"warn","ts":"2026-07-24T10:08:26.73161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T10:08:26.731696Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-07-24T10:08:26.731754Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T10:08:26.731768Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-07-24T10:08:26.731825Z","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-07-24T10:08:26.732475Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-07-24T10:08:26.732578Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-07-24T10:08:26.735346Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.502367ms"} {"level":"info","ts":"2026-07-24T10:08:26.746731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-07-24T10:08:26.746812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T10:08:26.746828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-07-24T10:08:26.7469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T10:08:26.746949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-07-24T10:08:26.747033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-07-24T10:08:26.751053Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T10:08:26.75204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T10:08:26.752966Z","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-07-24T10:08:26.754534Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T10:08:26.75472Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T10:08:26.754753Z","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-07-24T10:08:26.754823Z","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-07-24T10:08:26.754839Z","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-07-24T10:08:26.756133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-07-24T10:08:26.756322Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-07-24T10:08:26.758581Z","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-07-24T10:08:26.75876Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-07-24T10:08:26.758809Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-07-24T10:08:26.758968Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T10:08:26.759037Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T10:08:27.447661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T10:08:27.44772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T10:08:27.447747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-07-24T10:08:27.447761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-07-24T10:08:27.447768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-07-24T10:08:27.447779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-07-24T10:08:27.447788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-07-24T10:08:27.448577Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:08:27.449214Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T10:08:27.44922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T10:08:27.449283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T10:08:27.44965Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:08:27.44973Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:08:27.449757Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T10:08:27.449799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T10:08:27.449865Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T10:08:27.451258Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-07-24T10:08:27.45268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-24T10:08:32.066638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165302884s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:08:32.066826Z","caller":"traceutil/trace.go:171","msg":"trace[2115899855] linearizableReadLoop","detail":"{readStateIndex:155; appliedIndex:153; }","duration":"1.229036728s","start":"2026-07-24T10:08:30.837772Z","end":"2026-07-24T10:08:32.066809Z","steps":["trace[2115899855] 'read index received' (duration: 63.086197ms)","trace[2115899855] 'applied index is now lower than readState.Index' (duration: 1.165949051s)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:08:32.066905Z","caller":"traceutil/trace.go:171","msg":"trace[79907832] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"1.704387947s","start":"2026-07-24T10:08:30.362508Z","end":"2026-07-24T10:08:32.066896Z","steps":["trace[79907832] 'process raft request' (duration: 1.704246894s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:32.066894Z","caller":"traceutil/trace.go:171","msg":"trace[564690953] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"1.796490546s","start":"2026-07-24T10:08:30.270366Z","end":"2026-07-24T10:08:32.066856Z","steps":["trace[564690953] 'process raft request' (duration: 630.456394ms)","trace[564690953] 'compare' (duration: 1.16516835s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:08:32.06698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:30.36248Z","time spent":"1.704458448s","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:08:32.067043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:30.270351Z","time spent":"1.796625159s","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:08:32.067201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229403308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:32.06724Z","caller":"traceutil/trace.go:171","msg":"trace[313249427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:151; }","duration":"1.229491861s","start":"2026-07-24T10:08:30.837734Z","end":"2026-07-24T10:08:32.067226Z","steps":["trace[313249427] 'agreement among raft nodes before linearized reading' (duration: 1.229391458s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.067269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:30.837714Z","time spent":"1.229548333s","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T10:08:32.485079Z","caller":"traceutil/trace.go:171","msg":"trace[885400903] linearizableReadLoop","detail":"{readStateIndex:157; appliedIndex:155; }","duration":"418.187469ms","start":"2026-07-24T10:08:32.066869Z","end":"2026-07-24T10:08:32.485056Z","steps":["trace[885400903] 'read index received' (duration: 413.282298ms)","trace[885400903] 'applied index is now lower than readState.Index' (duration: 4.904221ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:08:32.48521Z","caller":"traceutil/trace.go:171","msg":"trace[477162667] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"537.44963ms","start":"2026-07-24T10:08:31.947745Z","end":"2026-07-24T10:08:32.485194Z","steps":["trace[477162667] 'process raft request' (duration: 537.275905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.485297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:31.947717Z","time spent":"537.525192ms","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:08:32.48511Z","caller":"traceutil/trace.go:171","msg":"trace[1421000021] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"662.788173ms","start":"2026-07-24T10:08:31.822298Z","end":"2026-07-24T10:08:32.485087Z","steps":["trace[1421000021] 'process raft request' (duration: 657.82943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.48542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:31.822276Z","time spent":"663.10029ms","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:08:32.485534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.329486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:32.485587Z","caller":"traceutil/trace.go:171","msg":"trace[1005283123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:153; }","duration":"417.410448ms","start":"2026-07-24T10:08:32.06816Z","end":"2026-07-24T10:08:32.485571Z","steps":["trace[1005283123] 'agreement among raft nodes before linearized reading' (duration: 417.310745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.485629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:32.068146Z","time spent":"417.473019ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:08:32.485647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.820738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:32.485712Z","caller":"traceutil/trace.go:171","msg":"trace[937532622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:153; }","duration":"508.914981ms","start":"2026-07-24T10:08:31.97678Z","end":"2026-07-24T10:08:32.485695Z","steps":["trace[937532622] 'agreement among raft nodes before linearized reading' (duration: 508.829849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.485763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:31.976764Z","time spent":"508.984113ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:08:32.485816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.033172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:32.48586Z","caller":"traceutil/trace.go:171","msg":"trace[324675427] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:0; response_revision:153; }","duration":"416.116464ms","start":"2026-07-24T10:08:32.069728Z","end":"2026-07-24T10:08:32.485844Z","steps":["trace[324675427] 'agreement among raft nodes before linearized reading' (duration: 416.052652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.4859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:32.069709Z","time spent":"416.180006ms","remote":"127.0.0.1:60128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-07-24T10:08:32.486187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.54922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:32.487109Z","caller":"traceutil/trace.go:171","msg":"trace[1437296673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:153; }","duration":"996.515625ms","start":"2026-07-24T10:08:31.490576Z","end":"2026-07-24T10:08:32.487091Z","steps":["trace[1437296673] 'agreement among raft nodes before linearized reading' (duration: 995.523569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:32.487209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:08:31.490528Z","time spent":"996.662281ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T10:08:54.764497Z","caller":"traceutil/trace.go:171","msg":"trace[1048233478] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"156.82886ms","start":"2026-07-24T10:08:54.607649Z","end":"2026-07-24T10:08:54.764478Z","steps":["trace[1048233478] 'read index received' (duration: 156.789869ms)","trace[1048233478] 'applied index is now lower than readState.Index' (duration: 38.37µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:08:54.764622Z","caller":"traceutil/trace.go:171","msg":"trace[1932096347] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"157.708943ms","start":"2026-07-24T10:08:54.606888Z","end":"2026-07-24T10:08:54.764597Z","steps":["trace[1932096347] 'process raft request' (duration: 157.486027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:54.764686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.029904ms","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-07-24T10:08:54.76471Z","caller":"traceutil/trace.go:171","msg":"trace[618736382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"157.107066ms","start":"2026-07-24T10:08:54.607595Z","end":"2026-07-24T10:08:54.764702Z","steps":["trace[618736382] 'agreement among raft nodes before linearized reading' (duration: 156.990383ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:08:54.778439Z","caller":"traceutil/trace.go:171","msg":"trace[851583297] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"169.59694ms","start":"2026-07-24T10:08:54.60882Z","end":"2026-07-24T10:08:54.778417Z","steps":["trace[851583297] 'process raft request' (duration: 169.472336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:08:54.778458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.620153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:08:54.778524Z","caller":"traceutil/trace.go:171","msg":"trace[2050836242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"130.729436ms","start":"2026-07-24T10:08:54.647777Z","end":"2026-07-24T10:08:54.778507Z","steps":["trace[2050836242] 'agreement among raft nodes before linearized reading' (duration: 130.621563ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:02.945849Z","caller":"traceutil/trace.go:171","msg":"trace[581023153] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"112.266895ms","start":"2026-07-24T10:09:02.833537Z","end":"2026-07-24T10:09:02.945804Z","steps":["trace[581023153] 'process raft request' (duration: 84.816695ms)","trace[581023153] 'compare' (duration: 27.256335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:09:07.150122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217706904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T10:09:07.651324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217706904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T10:09:07.972742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.734374243s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T10:09:07.973146Z","caller":"traceutil/trace.go:171","msg":"trace[500925375] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.734857267s","start":"2026-07-24T10:09:06.238266Z","end":"2026-07-24T10:09:07.973123Z","steps":["trace[500925375] 'process raft request' (duration: 1.734696003s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:07.973273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.238246Z","time spent":"1.734965639s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.007309Z","caller":"traceutil/trace.go:171","msg":"trace[776888209] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.767000971s","start":"2026-07-24T10:09:06.240236Z","end":"2026-07-24T10:09:08.007237Z","steps":["trace[776888209] 'process raft request' (duration: 1.766723953s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:08.007392Z","caller":"traceutil/trace.go:171","msg":"trace[1969617714] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.747929865s","start":"2026-07-24T10:09:06.259423Z","end":"2026-07-24T10:09:08.007353Z","steps":["trace[1969617714] 'process raft request' (duration: 1.747735509s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.007495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.240218Z","time spent":"1.767194767s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:08.007524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.259412Z","time spent":"1.748046818s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.007594Z","caller":"traceutil/trace.go:171","msg":"trace[2144021968] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.737784535s","start":"2026-07-24T10:09:06.269786Z","end":"2026-07-24T10:09:08.007571Z","steps":["trace[2144021968] 'process raft request' (duration: 1.737446146s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.007708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.269772Z","time spent":"1.737883097s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.007715Z","caller":"traceutil/trace.go:171","msg":"trace[1906110667] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.750943045s","start":"2026-07-24T10:09:06.256748Z","end":"2026-07-24T10:09:08.007691Z","steps":["trace[1906110667] 'process raft request' (duration: 1.75037368s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:08.007716Z","caller":"traceutil/trace.go:171","msg":"trace[1433135760] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.766638771s","start":"2026-07-24T10:09:06.241029Z","end":"2026-07-24T10:09:08.007667Z","steps":["trace[1433135760] 'process raft request' (duration: 1.766048785s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.00781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.256729Z","time spent":"1.751043867s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.007841Z","caller":"traceutil/trace.go:171","msg":"trace[1932413745] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:555; }","duration":"1.357859133s","start":"2026-07-24T10:09:06.64996Z","end":"2026-07-24T10:09:08.007819Z","steps":["trace[1932413745] 'read index received' (duration: 1.323078469s)","trace[1932413745] 'applied index is now lower than readState.Index' (duration: 34.779274ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:08.007933Z","caller":"traceutil/trace.go:171","msg":"trace[1186762422] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.699815835s","start":"2026-07-24T10:09:06.30809Z","end":"2026-07-24T10:09:08.007906Z","steps":["trace[1186762422] 'process raft request' (duration: 1.699405944s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:08.007946Z","caller":"traceutil/trace.go:171","msg":"trace[948182417] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.731734035s","start":"2026-07-24T10:09:06.276195Z","end":"2026-07-24T10:09:08.007929Z","steps":["trace[948182417] 'process raft request' (duration: 1.731077807s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.007973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.24102Z","time spent":"1.766778875s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:08.008029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358043979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T10:09:08.008034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.276179Z","time spent":"1.731806966s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:08.008045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.308055Z","time spent":"1.699920258s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.008071Z","caller":"traceutil/trace.go:171","msg":"trace[1110003429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"1.35813169s","start":"2026-07-24T10:09:06.64993Z","end":"2026-07-24T10:09:08.008061Z","steps":["trace[1110003429] 'agreement among raft nodes before linearized reading' (duration: 1.358032128s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.008106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.649912Z","time spent":"1.358186992s","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T10:09:08.008006Z","caller":"traceutil/trace.go:171","msg":"trace[1656532884] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.728745444s","start":"2026-07-24T10:09:06.27909Z","end":"2026-07-24T10:09:08.007836Z","steps":["trace[1656532884] 'process raft request' (duration: 1.728224571s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:08.008168Z","caller":"traceutil/trace.go:171","msg":"trace[955729063] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.719473898s","start":"2026-07-24T10:09:06.288683Z","end":"2026-07-24T10:09:08.008157Z","steps":["trace[955729063] 'process raft request' (duration: 1.718705197s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.008349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.288646Z","time spent":"1.719668653s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.00794Z","caller":"traceutil/trace.go:171","msg":"trace[1012264276] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.590117499s","start":"2026-07-24T10:09:06.417813Z","end":"2026-07-24T10:09:08.00793Z","steps":["trace[1012264276] 'process raft request' (duration: 1.589880972s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.008439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.417793Z","time spent":"1.590616562s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:08.008522Z","caller":"traceutil/trace.go:171","msg":"trace[1248566686] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.744670988s","start":"2026-07-24T10:09:06.263145Z","end":"2026-07-24T10:09:08.007816Z","steps":["trace[1248566686] 'process raft request' (duration: 1.744052192s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.008819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.279069Z","time spent":"1.729149315s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:08.008648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.263128Z","time spent":"1.74549012s","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:08.010086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183104577s","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-07-24T10:09:08.010136Z","caller":"traceutil/trace.go:171","msg":"trace[1489374585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"1.183195809s","start":"2026-07-24T10:09:06.826928Z","end":"2026-07-24T10:09:08.010124Z","steps":["trace[1489374585] 'agreement among raft nodes before linearized reading' (duration: 1.182983383s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.01018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.826905Z","time spent":"1.183267071s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T10:09:08.010348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.695713ms","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-07-24T10:09:08.010383Z","caller":"traceutil/trace.go:171","msg":"trace[704231631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"585.775475ms","start":"2026-07-24T10:09:07.424599Z","end":"2026-07-24T10:09:08.010374Z","steps":["trace[704231631] 'agreement among raft nodes before linearized reading' (duration: 585.716594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.010415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:07.42458Z","time spent":"585.829546ms","remote":"127.0.0.1:60080","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-07-24T10:09:08.010509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172295449s","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-07-24T10:09:08.010544Z","caller":"traceutil/trace.go:171","msg":"trace[1958176331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"1.1723576s","start":"2026-07-24T10:09:06.838177Z","end":"2026-07-24T10:09:08.010535Z","steps":["trace[1958176331] 'agreement among raft nodes before linearized reading' (duration: 1.172301199s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.01057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.838158Z","time spent":"1.172406902s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T10:09:08.01067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172305039s","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-07-24T10:09:08.010719Z","caller":"traceutil/trace.go:171","msg":"trace[1923013167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"1.172406103s","start":"2026-07-24T10:09:06.838301Z","end":"2026-07-24T10:09:08.010708Z","steps":["trace[1923013167] 'agreement among raft nodes before linearized reading' (duration: 1.172270158s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:08.010753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:06.838296Z","time spent":"1.172450124s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T10:09:08.662827Z","caller":"traceutil/trace.go:171","msg":"trace[1379466476] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"149.127315ms","start":"2026-07-24T10:09:08.513682Z","end":"2026-07-24T10:09:08.662809Z","steps":["trace[1379466476] 'process raft request' (duration: 114.055412ms)","trace[1379466476] 'compare' (duration: 34.994881ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:08.669022Z","caller":"traceutil/trace.go:171","msg":"trace[862156959] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"138.850802ms","start":"2026-07-24T10:09:08.530156Z","end":"2026-07-24T10:09:08.669007Z","steps":["trace[862156959] 'process raft request' (duration: 138.76298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:09.06037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.172677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:09:09.060608Z","caller":"traceutil/trace.go:171","msg":"trace[2136895537] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"223.549664ms","start":"2026-07-24T10:09:08.837042Z","end":"2026-07-24T10:09:09.060592Z","steps":["trace[2136895537] 'process raft request' (duration: 223.451382ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:09.060699Z","caller":"traceutil/trace.go:171","msg":"trace[879836741] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"233.18376ms","start":"2026-07-24T10:09:08.827495Z","end":"2026-07-24T10:09:09.060679Z","steps":["trace[879836741] 'process raft request' (duration: 127.465109ms)","trace[879836741] 'compare' (duration: 104.950981ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:09:09.711703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.977309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-07-24T10:09:09.711804Z","caller":"traceutil/trace.go:171","msg":"trace[1343283154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:610; }","duration":"217.108703ms","start":"2026-07-24T10:09:09.494661Z","end":"2026-07-24T10:09:09.71177Z","steps":["trace[1343283154] 'range keys from in-memory index tree' (duration: 216.803804ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:09.721773Z","caller":"traceutil/trace.go:171","msg":"trace[173211483] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"159.05358ms","start":"2026-07-24T10:09:09.562694Z","end":"2026-07-24T10:09:09.721748Z","steps":["trace[173211483] 'process raft request' (duration: 158.525535ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:09.737366Z","caller":"traceutil/trace.go:171","msg":"trace[1622120696] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"105.444784ms","start":"2026-07-24T10:09:09.6319Z","end":"2026-07-24T10:09:09.737344Z","steps":["trace[1622120696] 'process raft request' (duration: 105.27051ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:15.004917Z","caller":"traceutil/trace.go:171","msg":"trace[632651469] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"355.107672ms","start":"2026-07-24T10:09:14.649785Z","end":"2026-07-24T10:09:15.004892Z","steps":["trace[632651469] 'read index received' (duration: 354.094694ms)","trace[632651469] 'applied index is now lower than readState.Index' (duration: 1.012068ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:15.00506Z","caller":"traceutil/trace.go:171","msg":"trace[535300871] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"388.670373ms","start":"2026-07-24T10:09:14.616337Z","end":"2026-07-24T10:09:15.005007Z","steps":["trace[535300871] 'process raft request' (duration: 387.78601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:15.005173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.359328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:09:15.005253Z","caller":"traceutil/trace.go:171","msg":"trace[1621893936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"355.473641ms","start":"2026-07-24T10:09:14.649755Z","end":"2026-07-24T10:09:15.005229Z","steps":["trace[1621893936] 'agreement among raft nodes before linearized reading' (duration: 355.330307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:15.005264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.571909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dm6ft\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T10:09:15.00531Z","caller":"traceutil/trace.go:171","msg":"trace[1486844679] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dm6ft; range_end:; response_count:1; response_revision:645; }","duration":"251.62245ms","start":"2026-07-24T10:09:14.753676Z","end":"2026-07-24T10:09:15.005298Z","steps":["trace[1486844679] 'agreement among raft nodes before linearized reading' (duration: 251.443345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:15.005313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:14.649738Z","time spent":"355.563973ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:09:15.005388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:14.616304Z","time spent":"388.92377ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:09:15.827491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.246941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:09:15.827699Z","caller":"traceutil/trace.go:171","msg":"trace[2090722937] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"176.551704ms","start":"2026-07-24T10:09:15.651129Z","end":"2026-07-24T10:09:15.827681Z","steps":["trace[2090722937] 'read index received' (duration: 171.311355ms)","trace[2090722937] 'applied index is now lower than readState.Index' (duration: 5.238939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:09:15.827832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.701938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:09:15.827866Z","caller":"traceutil/trace.go:171","msg":"trace[1213495711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"176.77685ms","start":"2026-07-24T10:09:15.65108Z","end":"2026-07-24T10:09:15.827856Z","steps":["trace[1213495711] 'agreement among raft nodes before linearized reading' (duration: 176.658067ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:15.828025Z","caller":"traceutil/trace.go:171","msg":"trace[248547658] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"755.95691ms","start":"2026-07-24T10:09:15.072035Z","end":"2026-07-24T10:09:15.827992Z","steps":["trace[248547658] 'process raft request' (duration: 88.135173ms)","trace[248547658] 'compare' (duration: 667.046606ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:15.827991Z","caller":"traceutil/trace.go:171","msg":"trace[1450425914] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"202.992767ms","start":"2026-07-24T10:09:15.6249Z","end":"2026-07-24T10:09:15.827893Z","steps":["trace[1450425914] 'process raft request' (duration: 202.70976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:15.828167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:15.072019Z","time spent":"756.082744ms","remote":"127.0.0.1:60308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T10:09:17.151137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217707102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T10:09:17.406814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215641982s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T10:09:17.40753Z","caller":"traceutil/trace.go:171","msg":"trace[1710514565] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.216444004s","start":"2026-07-24T10:09:16.191062Z","end":"2026-07-24T10:09:17.407506Z","steps":["trace[1710514565] 'process raft request' (duration: 1.21630311s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.407667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.191045Z","time spent":"1.216549656s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:17.503066Z","caller":"traceutil/trace.go:171","msg":"trace[809995711] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.311906682s","start":"2026-07-24T10:09:16.191136Z","end":"2026-07-24T10:09:17.503043Z","steps":["trace[809995711] 'process raft request' (duration: 1.311684036s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.503242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.191125Z","time spent":"1.312004394s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:17.5034Z","caller":"traceutil/trace.go:171","msg":"trace[2003944773] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.312226111s","start":"2026-07-24T10:09:16.191165Z","end":"2026-07-24T10:09:17.503391Z","steps":["trace[2003944773] 'process raft request' (duration: 1.31183784s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.503459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.191144Z","time spent":"1.312292083s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:17.503806Z","caller":"traceutil/trace.go:171","msg":"trace[2064667530] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.312524808s","start":"2026-07-24T10:09:16.191201Z","end":"2026-07-24T10:09:17.503725Z","steps":["trace[2064667530] 'process raft request' (duration: 1.312055196s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.504047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.191192Z","time spent":"1.312715624s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:17.504259Z","caller":"traceutil/trace.go:171","msg":"trace[1713947729] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:674; }","duration":"853.140514ms","start":"2026-07-24T10:09:16.65108Z","end":"2026-07-24T10:09:17.504221Z","steps":["trace[1713947729] 'read index received' (duration: 756.673699ms)","trace[1713947729] 'applied index is now lower than readState.Index' (duration: 96.466105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:09:17.50437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.272228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:09:17.504396Z","caller":"traceutil/trace.go:171","msg":"trace[79733656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"853.34876ms","start":"2026-07-24T10:09:16.651039Z","end":"2026-07-24T10:09:17.504388Z","steps":["trace[79733656] 'agreement among raft nodes before linearized reading' (duration: 853.274148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.504426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.651019Z","time spent":"853.3994ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T10:09:17.504503Z","caller":"traceutil/trace.go:171","msg":"trace[557278581] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.238671834s","start":"2026-07-24T10:09:16.265813Z","end":"2026-07-24T10:09:17.504485Z","steps":["trace[557278581] 'process raft request' (duration: 1.237688518s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:17.504587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:16.265777Z","time spent":"1.238773927s","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:09:19.389432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.226123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:09:19.38952Z","caller":"traceutil/trace.go:171","msg":"trace[2135356552] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"391.664205ms","start":"2026-07-24T10:09:18.997841Z","end":"2026-07-24T10:09:19.389505Z","steps":["trace[2135356552] 'compare' (duration: 388.088879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:19.389558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:18.997822Z","time spent":"391.722676ms","remote":"127.0.0.1:60328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T10:09:19.435861Z","caller":"traceutil/trace.go:171","msg":"trace[290791679] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"436.775364ms","start":"2026-07-24T10:09:18.999058Z","end":"2026-07-24T10:09:19.435834Z","steps":["trace[290791679] 'process raft request' (duration: 436.62767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:19.436038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:18.999015Z","time spent":"436.926037ms","remote":"127.0.0.1:59864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:19.448639Z","caller":"traceutil/trace.go:171","msg":"trace[60207222] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"264.0478ms","start":"2026-07-24T10:09:19.184568Z","end":"2026-07-24T10:09:19.448616Z","steps":["trace[60207222] 'process raft request' (duration: 263.790284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:22.10418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.536614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-24T10:09:22.104336Z","caller":"traceutil/trace.go:171","msg":"trace[175747972] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"138.75874ms","start":"2026-07-24T10:09:21.965548Z","end":"2026-07-24T10:09:22.104307Z","steps":["trace[175747972] 'range keys from in-memory index tree' (duration: 138.211964ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:22.733454Z","caller":"traceutil/trace.go:171","msg":"trace[1095462942] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"167.470053ms","start":"2026-07-24T10:09:22.56596Z","end":"2026-07-24T10:09:22.73343Z","steps":["trace[1095462942] 'read index received' (duration: 167.083003ms)","trace[1095462942] 'applied index is now lower than readState.Index' (duration: 385.82µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:22.73353Z","caller":"traceutil/trace.go:171","msg":"trace[224218747] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"216.567108ms","start":"2026-07-24T10:09:22.516933Z","end":"2026-07-24T10:09:22.7335Z","steps":["trace[224218747] 'process raft request' (duration: 216.155358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:22.733787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.792882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-24T10:09:22.73387Z","caller":"traceutil/trace.go:171","msg":"trace[842707102] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"167.932305ms","start":"2026-07-24T10:09:22.565918Z","end":"2026-07-24T10:09:22.733851Z","steps":["trace[842707102] 'agreement among raft nodes before linearized reading' (duration: 167.592456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:23.291529Z","caller":"traceutil/trace.go:171","msg":"trace[1403291256] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"125.757753ms","start":"2026-07-24T10:09:23.165744Z","end":"2026-07-24T10:09:23.291502Z","steps":["trace[1403291256] 'read index received' (duration: 125.373453ms)","trace[1403291256] 'applied index is now lower than readState.Index' (duration: 383.1µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:23.291585Z","caller":"traceutil/trace.go:171","msg":"trace[1545159618] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"175.904176ms","start":"2026-07-24T10:09:23.115654Z","end":"2026-07-24T10:09:23.291558Z","steps":["trace[1545159618] 'process raft request' (duration: 175.605929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.291811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.040851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-07-24T10:09:23.291911Z","caller":"traceutil/trace.go:171","msg":"trace[1783771529] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"126.179845ms","start":"2026-07-24T10:09:23.165714Z","end":"2026-07-24T10:09:23.291894Z","steps":["trace[1783771529] 'agreement among raft nodes before linearized reading' (duration: 125.901677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.67977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.364317ms","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-07-24T10:09:23.679863Z","caller":"traceutil/trace.go:171","msg":"trace[725146594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"156.511142ms","start":"2026-07-24T10:09:23.523334Z","end":"2026-07-24T10:09:23.679845Z","steps":["trace[725146594] 'range keys from in-memory index tree' (duration: 156.160182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.679988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.70309ms","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-07-24T10:09:23.680081Z","caller":"traceutil/trace.go:171","msg":"trace[1492090468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"152.863734ms","start":"2026-07-24T10:09:23.527195Z","end":"2026-07-24T10:09:23.680058Z","steps":["trace[1492090468] 'range keys from in-memory index tree' (duration: 152.490305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.680177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.547208ms","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-07-24T10:09:23.680213Z","caller":"traceutil/trace.go:171","msg":"trace[1792556348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"210.622701ms","start":"2026-07-24T10:09:23.46958Z","end":"2026-07-24T10:09:23.680202Z","steps":["trace[1792556348] 'range keys from in-memory index tree' (duration: 210.349402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.680279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.863916ms","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-07-24T10:09:23.680311Z","caller":"traceutil/trace.go:171","msg":"trace[930721347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"152.922137ms","start":"2026-07-24T10:09:23.527378Z","end":"2026-07-24T10:09:23.6803Z","steps":["trace[930721347] 'range keys from in-memory index tree' (duration: 152.711692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:23.680218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.365516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-24T10:09:23.680989Z","caller":"traceutil/trace.go:171","msg":"trace[382225810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"278.208098ms","start":"2026-07-24T10:09:23.402756Z","end":"2026-07-24T10:09:23.680964Z","steps":["trace[382225810] 'range keys from in-memory index tree' (duration: 277.000845ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:29.933752Z","caller":"traceutil/trace.go:171","msg":"trace[161533981] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"125.617506ms","start":"2026-07-24T10:09:29.808108Z","end":"2026-07-24T10:09:29.933725Z","steps":["trace[161533981] 'process raft request' (duration: 125.561395ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:29.933954Z","caller":"traceutil/trace.go:171","msg":"trace[1562300960] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"125.848712ms","start":"2026-07-24T10:09:29.808073Z","end":"2026-07-24T10:09:29.933922Z","steps":["trace[1562300960] 'process raft request' (duration: 100.140808ms)","trace[1562300960] 'compare' (duration: 25.351425ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:33.335571Z","caller":"traceutil/trace.go:171","msg":"trace[1883987146] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"476.608755ms","start":"2026-07-24T10:09:32.858939Z","end":"2026-07-24T10:09:33.335548Z","steps":["trace[1883987146] 'read index received' (duration: 476.480122ms)","trace[1883987146] 'applied index is now lower than readState.Index' (duration: 127.213µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:09:33.335609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:32.837459Z","time spent":"498.141707ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-24T10:09:33.335753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.479665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T10:09:33.335796Z","caller":"traceutil/trace.go:171","msg":"trace[128624330] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:810; }","duration":"237.578938ms","start":"2026-07-24T10:09:33.098208Z","end":"2026-07-24T10:09:33.335787Z","steps":["trace[128624330] 'agreement among raft nodes before linearized reading' (duration: 237.494936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:33.335793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.1453ms","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-07-24T10:09:33.33579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.83838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cflbz\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-24T10:09:33.335826Z","caller":"traceutil/trace.go:171","msg":"trace[1511638120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"352.210361ms","start":"2026-07-24T10:09:32.983606Z","end":"2026-07-24T10:09:33.335816Z","steps":["trace[1511638120] 'agreement among raft nodes before linearized reading' (duration: 352.073327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:33.335868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:32.983594Z","time spent":"352.265493ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T10:09:33.335884Z","caller":"traceutil/trace.go:171","msg":"trace[1099059614] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cflbz; range_end:; response_count:1; response_revision:810; }","duration":"476.909522ms","start":"2026-07-24T10:09:32.858925Z","end":"2026-07-24T10:09:33.335834Z","steps":["trace[1099059614] 'agreement among raft nodes before linearized reading' (duration: 476.728567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:33.335914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:32.858907Z","time spent":"477.001845ms","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cflbz\" "} {"level":"warn","ts":"2026-07-24T10:09:33.990751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217707405,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T10:09:34.018306Z","caller":"traceutil/trace.go:171","msg":"trace[57951637] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"681.059899ms","start":"2026-07-24T10:09:33.337222Z","end":"2026-07-24T10:09:34.018282Z","steps":["trace[57951637] 'process raft request' (duration: 680.792223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.018513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:33.337202Z","time spent":"681.229613ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:34.022836Z","caller":"traceutil/trace.go:171","msg":"trace[799029714] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:841; }","duration":"532.275867ms","start":"2026-07-24T10:09:33.490521Z","end":"2026-07-24T10:09:34.022797Z","steps":["trace[799029714] 'read index received' (duration: 527.686215ms)","trace[799029714] 'applied index is now lower than readState.Index' (duration: 4.588862ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:34.023073Z","caller":"traceutil/trace.go:171","msg":"trace[2028846557] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"669.907492ms","start":"2026-07-24T10:09:33.353157Z","end":"2026-07-24T10:09:34.023064Z","steps":["trace[2028846557] 'process raft request' (duration: 669.46666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.023153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:33.353139Z","time spent":"669.954944ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:09:34.023352Z","caller":"traceutil/trace.go:171","msg":"trace[1964390797] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"662.831133ms","start":"2026-07-24T10:09:33.360512Z","end":"2026-07-24T10:09:34.023343Z","steps":["trace[1964390797] 'process raft request' (duration: 662.247408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.023389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:33.360495Z","time spent":"662.872665ms","remote":"127.0.0.1:60024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:09:34.02351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.985506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T10:09:34.023533Z","caller":"traceutil/trace.go:171","msg":"trace[72068604] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:813; }","duration":"533.048978ms","start":"2026-07-24T10:09:33.490477Z","end":"2026-07-24T10:09:34.023526Z","steps":["trace[72068604] 'agreement among raft nodes before linearized reading' (duration: 532.987996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.023554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:33.490457Z","time spent":"533.089569ms","remote":"127.0.0.1:60328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-24T10:09:34.02368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.858369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:09:34.023698Z","caller":"traceutil/trace.go:171","msg":"trace[264034608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"334.90159ms","start":"2026-07-24T10:09:33.688792Z","end":"2026-07-24T10:09:34.023693Z","steps":["trace[264034608] 'agreement among raft nodes before linearized reading' (duration: 334.865329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.023715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:09:33.688775Z","time spent":"334.93576ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:09:34.024278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.56259ms","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-07-24T10:09:34.024315Z","caller":"traceutil/trace.go:171","msg":"trace[482410796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"168.638172ms","start":"2026-07-24T10:09:33.85566Z","end":"2026-07-24T10:09:34.024298Z","steps":["trace[482410796] 'agreement among raft nodes before linearized reading' (duration: 168.55796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:09:34.024618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.427909ms","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-07-24T10:09:34.024689Z","caller":"traceutil/trace.go:171","msg":"trace[1314319604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"191.597043ms","start":"2026-07-24T10:09:33.833076Z","end":"2026-07-24T10:09:34.024673Z","steps":["trace[1314319604] 'agreement among raft nodes before linearized reading' (duration: 191.376777ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:41.55529Z","caller":"traceutil/trace.go:171","msg":"trace[83719675] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"177.006144ms","start":"2026-07-24T10:09:41.37825Z","end":"2026-07-24T10:09:41.555257Z","steps":["trace[83719675] 'process raft request' (duration: 133.228647ms)","trace[83719675] 'compare' (duration: 43.639744ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:09:41.562278Z","caller":"traceutil/trace.go:171","msg":"trace[247855538] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"180.848597ms","start":"2026-07-24T10:09:41.381411Z","end":"2026-07-24T10:09:41.562259Z","steps":["trace[247855538] 'process raft request' (duration: 180.676552ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:41.562402Z","caller":"traceutil/trace.go:171","msg":"trace[968762521] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"155.06135ms","start":"2026-07-24T10:09:41.407321Z","end":"2026-07-24T10:09:41.562383Z","steps":["trace[968762521] 'process raft request' (duration: 154.936527ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:41.56252Z","caller":"traceutil/trace.go:171","msg":"trace[185596304] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"179.436729ms","start":"2026-07-24T10:09:41.382888Z","end":"2026-07-24T10:09:41.562324Z","steps":["trace[185596304] 'process raft request' (duration: 179.318476ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:58.299188Z","caller":"traceutil/trace.go:171","msg":"trace[246962891] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"139.363372ms","start":"2026-07-24T10:09:58.159802Z","end":"2026-07-24T10:09:58.299165Z","steps":["trace[246962891] 'process raft request' (duration: 139.225269ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:58.328642Z","caller":"traceutil/trace.go:171","msg":"trace[1141919307] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"168.16162ms","start":"2026-07-24T10:09:58.160459Z","end":"2026-07-24T10:09:58.328621Z","steps":["trace[1141919307] 'process raft request' (duration: 167.973704ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:58.328877Z","caller":"traceutil/trace.go:171","msg":"trace[1512714964] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"168.123168ms","start":"2026-07-24T10:09:58.160742Z","end":"2026-07-24T10:09:58.328865Z","steps":["trace[1512714964] 'process raft request' (duration: 167.839381ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:58.328962Z","caller":"traceutil/trace.go:171","msg":"trace[651616387] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"161.957303ms","start":"2026-07-24T10:09:58.166985Z","end":"2026-07-24T10:09:58.328943Z","steps":["trace[651616387] 'process raft request' (duration: 161.818009ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:09:58.329022Z","caller":"traceutil/trace.go:171","msg":"trace[618607809] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"161.549503ms","start":"2026-07-24T10:09:58.167455Z","end":"2026-07-24T10:09:58.329004Z","steps":["trace[618607809] 'process raft request' (duration: 161.455071ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:04.51192Z","caller":"traceutil/trace.go:171","msg":"trace[764600527] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"126.246743ms","start":"2026-07-24T10:10:04.38565Z","end":"2026-07-24T10:10:04.511897Z","steps":["trace[764600527] 'process raft request' (duration: 126.202212ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:04.512056Z","caller":"traceutil/trace.go:171","msg":"trace[1644208419] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"127.546817ms","start":"2026-07-24T10:10:04.384486Z","end":"2026-07-24T10:10:04.512033Z","steps":["trace[1644208419] 'process raft request' (duration: 125.496942ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:04.512231Z","caller":"traceutil/trace.go:171","msg":"trace[1981390376] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"126.939971ms","start":"2026-07-24T10:10:04.385282Z","end":"2026-07-24T10:10:04.512222Z","steps":["trace[1981390376] 'process raft request' (duration: 126.526049ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:09.980609Z","caller":"traceutil/trace.go:171","msg":"trace[702240641] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"321.456192ms","start":"2026-07-24T10:10:09.659132Z","end":"2026-07-24T10:10:09.980589Z","steps":["trace[702240641] 'process raft request' (duration: 321.344559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:09.980732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:09.659111Z","time spent":"321.558724ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:10:10.638626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:10.077978Z","time spent":"560.640012ms","remote":"127.0.0.1:56806","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-24T10:10:10.63875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.882745ms","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-07-24T10:10:10.638777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24297ms","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-07-24T10:10:10.638777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.750298ms","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-07-24T10:10:10.638816Z","caller":"traceutil/trace.go:171","msg":"trace[322046197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"267.990718ms","start":"2026-07-24T10:10:10.370805Z","end":"2026-07-24T10:10:10.638795Z","steps":["trace[322046197] 'range keys from in-memory index tree' (duration: 267.723131ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:10.638844Z","caller":"traceutil/trace.go:171","msg":"trace[1961851079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"106.355333ms","start":"2026-07-24T10:10:10.532473Z","end":"2026-07-24T10:10:10.638828Z","steps":["trace[1961851079] 'range keys from in-memory index tree' (duration: 106.048825ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:10.638861Z","caller":"traceutil/trace.go:171","msg":"trace[1122233608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"170.862361ms","start":"2026-07-24T10:10:10.467979Z","end":"2026-07-24T10:10:10.638842Z","steps":["trace[1122233608] 'range keys from in-memory index tree' (duration: 170.536673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:10.639288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.556422ms","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-07-24T10:10:10.639327Z","caller":"traceutil/trace.go:171","msg":"trace[1018175982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"105.614433ms","start":"2026-07-24T10:10:10.533703Z","end":"2026-07-24T10:10:10.639318Z","steps":["trace[1018175982] 'range keys from in-memory index tree' (duration: 104.979296ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:10.810832Z","caller":"traceutil/trace.go:171","msg":"trace[1696211016] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"166.952767ms","start":"2026-07-24T10:10:10.643663Z","end":"2026-07-24T10:10:10.810616Z","steps":["trace[1696211016] 'process raft request' (duration: 166.797432ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:11.001823Z","caller":"traceutil/trace.go:171","msg":"trace[1866964506] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"358.061347ms","start":"2026-07-24T10:10:10.643733Z","end":"2026-07-24T10:10:11.001794Z","steps":["trace[1866964506] 'process raft request' (duration: 357.80428ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:11.001843Z","caller":"traceutil/trace.go:171","msg":"trace[53909898] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"357.978445ms","start":"2026-07-24T10:10:10.643834Z","end":"2026-07-24T10:10:11.001813Z","steps":["trace[53909898] 'process raft request' (duration: 357.880102ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:11.001876Z","caller":"traceutil/trace.go:171","msg":"trace[1717260284] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"357.927614ms","start":"2026-07-24T10:10:10.643917Z","end":"2026-07-24T10:10:11.001844Z","steps":["trace[1717260284] 'process raft request' (duration: 357.829361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:11.001985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:10.643718Z","time spent":"358.18348ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:10:11.002017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:10.643802Z","time spent":"358.121899ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:10:11.002027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:10.643893Z","time spent":"358.053096ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:10:11.00216Z","caller":"traceutil/trace.go:171","msg":"trace[1119779260] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"358.341754ms","start":"2026-07-24T10:10:10.643796Z","end":"2026-07-24T10:10:11.002138Z","steps":["trace[1119779260] 'process raft request' (duration: 357.882202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:11.002279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:10.643789Z","time spent":"358.426246ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:10:14.331576Z","caller":"traceutil/trace.go:171","msg":"trace[448316914] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"341.172517ms","start":"2026-07-24T10:10:13.990377Z","end":"2026-07-24T10:10:14.331549Z","steps":["trace[448316914] 'process raft request' (duration: 340.984733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:14.331723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:13.990358Z","time spent":"341.289151ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:10:33.065795Z","caller":"traceutil/trace.go:171","msg":"trace[633660685] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"121.411669ms","start":"2026-07-24T10:10:32.944359Z","end":"2026-07-24T10:10:33.065771Z","steps":["trace[633660685] 'read index received' (duration: 121.218953ms)","trace[633660685] 'applied index is now lower than readState.Index' (duration: 191.366µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:10:33.066094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.717109ms","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-07-24T10:10:33.066164Z","caller":"traceutil/trace.go:171","msg":"trace[1926623470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"121.843812ms","start":"2026-07-24T10:10:32.944302Z","end":"2026-07-24T10:10:33.066146Z","steps":["trace[1926623470] 'agreement among raft nodes before linearized reading' (duration: 121.622245ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:33.25252Z","caller":"traceutil/trace.go:171","msg":"trace[2115034502] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"146.663053ms","start":"2026-07-24T10:10:33.10583Z","end":"2026-07-24T10:10:33.252493Z","steps":["trace[2115034502] 'read index received' (duration: 51.711277ms)","trace[2115034502] 'applied index is now lower than readState.Index' (duration: 94.950456ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:10:33.252537Z","caller":"traceutil/trace.go:171","msg":"trace[62812636] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"185.387298ms","start":"2026-07-24T10:10:33.067103Z","end":"2026-07-24T10:10:33.252491Z","steps":["trace[62812636] 'process raft request' (duration: 90.452462ms)","trace[62812636] 'compare' (duration: 94.705929ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:10:33.252572Z","caller":"traceutil/trace.go:171","msg":"trace[2082741140] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"181.752712ms","start":"2026-07-24T10:10:33.070788Z","end":"2026-07-24T10:10:33.252541Z","steps":["trace[2082741140] 'process raft request' (duration: 181.623508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:33.252753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.908521ms","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-07-24T10:10:33.252814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.593369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T10:10:33.252863Z","caller":"traceutil/trace.go:171","msg":"trace[1695872786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"145.702473ms","start":"2026-07-24T10:10:33.107146Z","end":"2026-07-24T10:10:33.252848Z","steps":["trace[1695872786] 'agreement among raft nodes before linearized reading' (duration: 145.522077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:33.252872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.057813ms","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-07-24T10:10:33.252847Z","caller":"traceutil/trace.go:171","msg":"trace[1323626827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"147.032645ms","start":"2026-07-24T10:10:33.105795Z","end":"2026-07-24T10:10:33.252827Z","steps":["trace[1323626827] 'agreement among raft nodes before linearized reading' (duration: 146.802538ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:33.252986Z","caller":"traceutil/trace.go:171","msg":"trace[698554549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"145.174897ms","start":"2026-07-24T10:10:33.107774Z","end":"2026-07-24T10:10:33.252949Z","steps":["trace[698554549] 'agreement among raft nodes before linearized reading' (duration: 144.929598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:33.25314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.750024ms","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-07-24T10:10:33.253202Z","caller":"traceutil/trace.go:171","msg":"trace[466993630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"145.825146ms","start":"2026-07-24T10:10:33.107365Z","end":"2026-07-24T10:10:33.25319Z","steps":["trace[466993630] 'agreement among raft nodes before linearized reading' (duration: 145.499356ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:10:35.403251Z","caller":"traceutil/trace.go:171","msg":"trace[1458473588] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"109.21292ms","start":"2026-07-24T10:10:35.294016Z","end":"2026-07-24T10:10:35.403229Z","steps":["trace[1458473588] 'process raft request' (duration: 34.312654ms)","trace[1458473588] 'compare' (duration: 74.220945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:10:51.150616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217708366,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T10:10:51.237813Z","caller":"traceutil/trace.go:171","msg":"trace[1699646647] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"587.582484ms","start":"2026-07-24T10:10:50.650206Z","end":"2026-07-24T10:10:51.237788Z","steps":["trace[1699646647] 'read index received' (duration: 587.407957ms)","trace[1699646647] 'applied index is now lower than readState.Index' (duration: 173.607µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:10:51.237877Z","caller":"traceutil/trace.go:171","msg":"trace[1301446656] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"726.836419ms","start":"2026-07-24T10:10:50.511003Z","end":"2026-07-24T10:10:51.237839Z","steps":["trace[1301446656] 'process raft request' (duration: 726.626952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:51.237937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.716087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:10:51.238022Z","caller":"traceutil/trace.go:171","msg":"trace[780590049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"587.853802ms","start":"2026-07-24T10:10:50.650156Z","end":"2026-07-24T10:10:51.23801Z","steps":["trace[780590049] 'agreement among raft nodes before linearized reading' (duration: 587.725618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:51.238075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:50.650137Z","time spent":"587.919054ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:10:51.238062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:50.510985Z","time spent":"726.975474ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:10:51.237954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.832314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:10:51.23819Z","caller":"traceutil/trace.go:171","msg":"trace[571878708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1211; }","duration":"440.091842ms","start":"2026-07-24T10:10:50.798069Z","end":"2026-07-24T10:10:51.238161Z","steps":["trace[571878708] 'agreement among raft nodes before linearized reading' (duration: 439.855395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:10:51.238304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:10:50.79805Z","time spent":"440.193736ms","remote":"127.0.0.1:44748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T10:11:07.544639Z","caller":"traceutil/trace.go:171","msg":"trace[189399810] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"151.034875ms","start":"2026-07-24T10:11:07.393582Z","end":"2026-07-24T10:11:07.544617Z","steps":["trace[189399810] 'process raft request' (duration: 150.801489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:08.197089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.571408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:11:08.197166Z","caller":"traceutil/trace.go:171","msg":"trace[1266382060] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"122.259526ms","start":"2026-07-24T10:11:08.074896Z","end":"2026-07-24T10:11:08.197156Z","steps":["trace[1266382060] 'read index received' (duration: 33.161µs)","trace[1266382060] 'applied index is now lower than readState.Index' (duration: 122.225495ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:11:08.197219Z","caller":"traceutil/trace.go:171","msg":"trace[261333270] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"153.823428ms","start":"2026-07-24T10:11:08.043387Z","end":"2026-07-24T10:11:08.19721Z","steps":["trace[261333270] 'compare' (duration: 143.347023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:08.197851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.951474ms","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-07-24T10:11:08.197899Z","caller":"traceutil/trace.go:171","msg":"trace[123120083] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1294; }","duration":"123.032886ms","start":"2026-07-24T10:11:08.074851Z","end":"2026-07-24T10:11:08.197884Z","steps":["trace[123120083] 'agreement among raft nodes before linearized reading' (duration: 122.332188ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:11:08.372047Z","caller":"traceutil/trace.go:171","msg":"trace[1548997964] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"113.771292ms","start":"2026-07-24T10:11:08.258256Z","end":"2026-07-24T10:11:08.372027Z","steps":["trace[1548997964] 'process raft request' (duration: 113.725011ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:11:08.372227Z","caller":"traceutil/trace.go:171","msg":"trace[103273568] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"169.519642ms","start":"2026-07-24T10:11:08.202682Z","end":"2026-07-24T10:11:08.372201Z","steps":["trace[103273568] 'process raft request' (duration: 140.041644ms)","trace[103273568] 'compare' (duration: 29.137369ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:11:08.629395Z","caller":"traceutil/trace.go:171","msg":"trace[769520961] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"251.172426ms","start":"2026-07-24T10:11:08.378202Z","end":"2026-07-24T10:11:08.629374Z","steps":["trace[769520961] 'process raft request' (duration: 251.036063ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:11:08.641828Z","caller":"traceutil/trace.go:171","msg":"trace[497010049] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"260.356778ms","start":"2026-07-24T10:11:08.381453Z","end":"2026-07-24T10:11:08.641809Z","steps":["trace[497010049] 'process raft request' (duration: 260.244625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.151358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217708718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T10:11:21.652184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217708718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T10:11:21.887455Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27135678s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T10:11:21.887818Z","caller":"traceutil/trace.go:171","msg":"trace[2111277338] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"1.271855303s","start":"2026-07-24T10:11:20.615936Z","end":"2026-07-24T10:11:21.887791Z","steps":["trace[2111277338] 'process raft request' (duration: 1.27172721s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.887944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:11:20.615913Z","time spent":"1.271956837s","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:11:21.88956Z","caller":"traceutil/trace.go:171","msg":"trace[414797447] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1433; }","duration":"1.238782702s","start":"2026-07-24T10:11:20.650758Z","end":"2026-07-24T10:11:21.88954Z","steps":["trace[414797447] 'read index received' (duration: 1.236878091s)","trace[414797447] 'applied index is now lower than readState.Index' (duration: 1.903801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:11:21.889664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238896735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:11:21.889682Z","caller":"traceutil/trace.go:171","msg":"trace[1146677519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"1.238958676s","start":"2026-07-24T10:11:20.650718Z","end":"2026-07-24T10:11:21.889677Z","steps":["trace[1146677519] 'agreement among raft nodes before linearized reading' (duration: 1.238909985s)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:11:21.889671Z","caller":"traceutil/trace.go:171","msg":"trace[1377122246] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"282.633917ms","start":"2026-07-24T10:11:21.607005Z","end":"2026-07-24T10:11:21.889638Z","steps":["trace[1377122246] 'process raft request' (duration: 282.442032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.889805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207426093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T10:11:21.889847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.367756ms","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-07-24T10:11:21.889877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.282811ms","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-07-24T10:11:21.889893Z","caller":"traceutil/trace.go:171","msg":"trace[1549264387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1380; }","duration":"200.454608ms","start":"2026-07-24T10:11:21.689426Z","end":"2026-07-24T10:11:21.889881Z","steps":["trace[1549264387] 'agreement among raft nodes before linearized reading' (duration: 200.301344ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:11:21.889887Z","caller":"traceutil/trace.go:171","msg":"trace[1389613074] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1380; }","duration":"1.207538737s","start":"2026-07-24T10:11:20.68233Z","end":"2026-07-24T10:11:21.889869Z","steps":["trace[1389613074] 'agreement among raft nodes before linearized reading' (duration: 1.207400253s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.889976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:11:20.682324Z","time spent":"1.20764015s","remote":"127.0.0.1:60010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-24T10:11:21.889997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.055605ms","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-07-24T10:11:21.890012Z","caller":"traceutil/trace.go:171","msg":"trace[1987162407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"201.110626ms","start":"2026-07-24T10:11:21.688898Z","end":"2026-07-24T10:11:21.890009Z","steps":["trace[1987162407] 'agreement among raft nodes before linearized reading' (duration: 201.058734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.890058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.872711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T10:11:21.890109Z","caller":"traceutil/trace.go:171","msg":"trace[1862599646] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1380; }","duration":"559.972273ms","start":"2026-07-24T10:11:21.330124Z","end":"2026-07-24T10:11:21.890096Z","steps":["trace[1862599646] 'agreement among raft nodes before linearized reading' (duration: 559.85497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.890142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:11:21.330106Z","time spent":"560.027984ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-24T10:11:21.890142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.635135ms","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-07-24T10:11:21.889929Z","caller":"traceutil/trace.go:171","msg":"trace[1763964231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"223.331422ms","start":"2026-07-24T10:11:21.666564Z","end":"2026-07-24T10:11:21.889895Z","steps":["trace[1763964231] 'agreement among raft nodes before linearized reading' (duration: 223.22964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:11:21.889704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:11:20.650701Z","time spent":"1.238998868s","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T10:11:21.890178Z","caller":"traceutil/trace.go:171","msg":"trace[1415618574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1380; }","duration":"125.710067ms","start":"2026-07-24T10:11:21.764459Z","end":"2026-07-24T10:11:21.890169Z","steps":["trace[1415618574] 'agreement among raft nodes before linearized reading' (duration: 125.615685ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.717996Z","caller":"traceutil/trace.go:171","msg":"trace[1798725406] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"116.611015ms","start":"2026-07-24T10:13:34.601359Z","end":"2026-07-24T10:13:34.71797Z","steps":["trace[1798725406] 'process raft request' (duration: 116.446121ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.774068Z","caller":"traceutil/trace.go:171","msg":"trace[303912128] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2074; }","duration":"124.555835ms","start":"2026-07-24T10:13:34.649496Z","end":"2026-07-24T10:13:34.774052Z","steps":["trace[303912128] 'read index received' (duration: 68.458106ms)","trace[303912128] 'applied index is now lower than readState.Index' (duration: 56.096919ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:13:34.774088Z","caller":"traceutil/trace.go:171","msg":"trace[765196241] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"172.396976ms","start":"2026-07-24T10:13:34.601662Z","end":"2026-07-24T10:13:34.774059Z","steps":["trace[765196241] 'process raft request' (duration: 172.219592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:13:34.774182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.676518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:13:34.774201Z","caller":"traceutil/trace.go:171","msg":"trace[2116016953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1997; }","duration":"124.74307ms","start":"2026-07-24T10:13:34.649452Z","end":"2026-07-24T10:13:34.774195Z","steps":["trace[2116016953] 'agreement among raft nodes before linearized reading' (duration: 124.655348ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.774351Z","caller":"traceutil/trace.go:171","msg":"trace[155372568] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"172.604453ms","start":"2026-07-24T10:13:34.601741Z","end":"2026-07-24T10:13:34.774345Z","steps":["trace[155372568] 'process raft request' (duration: 172.231163ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.77448Z","caller":"traceutil/trace.go:171","msg":"trace[1996586362] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"171.462173ms","start":"2026-07-24T10:13:34.60298Z","end":"2026-07-24T10:13:34.774442Z","steps":["trace[1996586362] 'process raft request' (duration: 171.045992ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.774583Z","caller":"traceutil/trace.go:171","msg":"trace[583095967] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"172.719396ms","start":"2026-07-24T10:13:34.601858Z","end":"2026-07-24T10:13:34.774578Z","steps":["trace[583095967] 'process raft request' (duration: 172.134561ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:34.77464Z","caller":"traceutil/trace.go:171","msg":"trace[1593552838] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"171.714099ms","start":"2026-07-24T10:13:34.602916Z","end":"2026-07-24T10:13:34.77463Z","steps":["trace[1593552838] 'process raft request' (duration: 171.097342ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:13:56.263203Z","caller":"traceutil/trace.go:171","msg":"trace[2069787645] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"120.785954ms","start":"2026-07-24T10:13:56.142398Z","end":"2026-07-24T10:13:56.263184Z","steps":["trace[2069787645] 'process raft request' (duration: 120.667561ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:26.75539Z","caller":"traceutil/trace.go:171","msg":"trace[656529477] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2319; }","duration":"107.074962ms","start":"2026-07-24T10:14:26.648281Z","end":"2026-07-24T10:14:26.755356Z","steps":["trace[656529477] 'read index received' (duration: 106.859376ms)","trace[656529477] 'applied index is now lower than readState.Index' (duration: 214.976µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:14:26.755455Z","caller":"traceutil/trace.go:171","msg":"trace[1038903495] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"148.154293ms","start":"2026-07-24T10:14:26.607271Z","end":"2026-07-24T10:14:26.755425Z","steps":["trace[1038903495] 'process raft request' (duration: 147.925998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:26.755559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.279757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:14:26.755689Z","caller":"traceutil/trace.go:171","msg":"trace[1408629039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2228; }","duration":"107.469822ms","start":"2026-07-24T10:14:26.648207Z","end":"2026-07-24T10:14:26.755677Z","steps":["trace[1408629039] 'agreement among raft nodes before linearized reading' (duration: 107.283346ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:27.476683Z","caller":"traceutil/trace.go:171","msg":"trace[108723635] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"155.348503ms","start":"2026-07-24T10:14:27.321284Z","end":"2026-07-24T10:14:27.476632Z","steps":["trace[108723635] 'process raft request' (duration: 155.198669ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:27.476701Z","caller":"traceutil/trace.go:171","msg":"trace[33517228] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"155.397065ms","start":"2026-07-24T10:14:27.321252Z","end":"2026-07-24T10:14:27.476649Z","steps":["trace[33517228] 'process raft request' (duration: 89.150859ms)","trace[33517228] 'compare' (duration: 65.726202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:14:28.432657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.293014ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T10:14:28.909359Z","caller":"traceutil/trace.go:171","msg":"trace[1092586787] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"119.248681ms","start":"2026-07-24T10:14:28.790082Z","end":"2026-07-24T10:14:28.909331Z","steps":["trace[1092586787] 'read index received' (duration: 118.954524ms)","trace[1092586787] 'applied index is now lower than readState.Index' (duration: 292.937µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:14:28.909465Z","caller":"traceutil/trace.go:171","msg":"trace[138160805] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"149.089349ms","start":"2026-07-24T10:14:28.760345Z","end":"2026-07-24T10:14:28.909435Z","steps":["trace[138160805] 'process raft request' (duration: 148.779141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:28.909641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.406855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:81433"} {"level":"warn","ts":"2026-07-24T10:14:28.909633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.514658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-24T10:14:28.909679Z","caller":"traceutil/trace.go:171","msg":"trace[2086756868] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2239; }","duration":"119.486508ms","start":"2026-07-24T10:14:28.790181Z","end":"2026-07-24T10:14:28.909667Z","steps":["trace[2086756868] 'agreement among raft nodes before linearized reading' (duration: 119.291743ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:28.909726Z","caller":"traceutil/trace.go:171","msg":"trace[1160339446] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2239; }","duration":"119.665692ms","start":"2026-07-24T10:14:28.790035Z","end":"2026-07-24T10:14:28.909701Z","steps":["trace[1160339446] 'agreement among raft nodes before linearized reading' (duration: 119.445776ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:41.453147Z","caller":"traceutil/trace.go:171","msg":"trace[391184300] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2390; }","duration":"118.366049ms","start":"2026-07-24T10:14:41.334756Z","end":"2026-07-24T10:14:41.453122Z","steps":["trace[391184300] 'read index received' (duration: 70.951069ms)","trace[391184300] 'applied index is now lower than readState.Index' (duration: 47.41388ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:14:41.453291Z","caller":"traceutil/trace.go:171","msg":"trace[419667872] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"126.767781ms","start":"2026-07-24T10:14:41.326493Z","end":"2026-07-24T10:14:41.45326Z","steps":["trace[419667872] 'process raft request' (duration: 79.231419ms)","trace[419667872] 'compare' (duration: 47.270535ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:14:41.453385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.605526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T10:14:41.453421Z","caller":"traceutil/trace.go:171","msg":"trace[1504927361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2296; }","duration":"118.687267ms","start":"2026-07-24T10:14:41.334724Z","end":"2026-07-24T10:14:41.453411Z","steps":["trace[1504927361] 'agreement among raft nodes before linearized reading' (duration: 118.539593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:41.453501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.58386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T10:14:41.453599Z","caller":"traceutil/trace.go:171","msg":"trace[1909163377] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2296; }","duration":"100.698994ms","start":"2026-07-24T10:14:41.352877Z","end":"2026-07-24T10:14:41.453576Z","steps":["trace[1909163377] 'agreement among raft nodes before linearized reading' (duration: 100.486838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:41.453501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T10:14:41.453774Z","caller":"traceutil/trace.go:171","msg":"trace[1331125793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2296; }","duration":"101.75248ms","start":"2026-07-24T10:14:41.352011Z","end":"2026-07-24T10:14:41.453764Z","steps":["trace[1331125793] 'agreement among raft nodes before linearized reading' (duration: 101.32141ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:42.725621Z","caller":"traceutil/trace.go:171","msg":"trace[1725557561] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"167.126354ms","start":"2026-07-24T10:14:42.558471Z","end":"2026-07-24T10:14:42.725597Z","steps":["trace[1725557561] 'process raft request' (duration: 167.004631ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:42.733678Z","caller":"traceutil/trace.go:171","msg":"trace[1271730063] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"175.026282ms","start":"2026-07-24T10:14:42.558618Z","end":"2026-07-24T10:14:42.733644Z","steps":["trace[1271730063] 'process raft request' (duration: 174.797647ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:42.733704Z","caller":"traceutil/trace.go:171","msg":"trace[1224889860] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"132.913292ms","start":"2026-07-24T10:14:42.600765Z","end":"2026-07-24T10:14:42.733678Z","steps":["trace[1224889860] 'process raft request' (duration: 132.779789ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:43.623061Z","caller":"traceutil/trace.go:171","msg":"trace[740943126] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"133.534329ms","start":"2026-07-24T10:14:43.489497Z","end":"2026-07-24T10:14:43.623032Z","steps":["trace[740943126] 'process raft request' (duration: 132.378318ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:43.623288Z","caller":"traceutil/trace.go:171","msg":"trace[81203271] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"133.689653ms","start":"2026-07-24T10:14:43.489574Z","end":"2026-07-24T10:14:43.623263Z","steps":["trace[81203271] 'process raft request' (duration: 133.405596ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:52.97165Z","caller":"traceutil/trace.go:171","msg":"trace[1562670675] linearizableReadLoop","detail":"{readStateIndex:2443; appliedIndex:2442; }","duration":"225.826092ms","start":"2026-07-24T10:14:52.7458Z","end":"2026-07-24T10:14:52.971626Z","steps":["trace[1562670675] 'read index received' (duration: 225.725069ms)","trace[1562670675] 'applied index is now lower than readState.Index' (duration: 100.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:14:52.971779Z","caller":"traceutil/trace.go:171","msg":"trace[1860980116] transaction","detail":"{read_only:false; response_revision:2346; number_of_response:1; }","duration":"230.3471ms","start":"2026-07-24T10:14:52.741414Z","end":"2026-07-24T10:14:52.971761Z","steps":["trace[1860980116] 'process raft request' (duration: 230.088724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:52.972117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.309004ms","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-07-24T10:14:52.972198Z","caller":"traceutil/trace.go:171","msg":"trace[1622102035] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2346; }","duration":"226.439787ms","start":"2026-07-24T10:14:52.745737Z","end":"2026-07-24T10:14:52.972176Z","steps":["trace[1622102035] 'agreement among raft nodes before linearized reading' (duration: 226.254143ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:53.060112Z","caller":"traceutil/trace.go:171","msg":"trace[2119544487] transaction","detail":"{read_only:false; response_revision:2347; number_of_response:1; }","duration":"274.267694ms","start":"2026-07-24T10:14:52.78581Z","end":"2026-07-24T10:14:53.060078Z","steps":["trace[2119544487] 'process raft request' (duration: 272.137778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:53.060154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.036533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.176\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T10:14:53.06022Z","caller":"traceutil/trace.go:171","msg":"trace[2061950278] range","detail":"{range_begin:/registry/masterleases/199.19.213.176; range_end:; response_count:1; response_revision:2347; }","duration":"208.110125ms","start":"2026-07-24T10:14:52.852091Z","end":"2026-07-24T10:14:53.060201Z","steps":["trace[2061950278] 'agreement among raft nodes before linearized reading' (duration: 207.92722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:14:53.060518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.758884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T10:14:53.060551Z","caller":"traceutil/trace.go:171","msg":"trace[145984140] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2348; }","duration":"156.830136ms","start":"2026-07-24T10:14:52.903713Z","end":"2026-07-24T10:14:53.060543Z","steps":["trace[145984140] 'agreement among raft nodes before linearized reading' (duration: 156.759144ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:14:53.656679Z","caller":"traceutil/trace.go:171","msg":"trace[1750563911] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"102.926919ms","start":"2026-07-24T10:14:53.553739Z","end":"2026-07-24T10:14:53.656666Z","steps":["trace[1750563911] 'process raft request' (duration: 102.667831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:32.016973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.173197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T10:15:32.016973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.144369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-24T10:15:32.017107Z","caller":"traceutil/trace.go:171","msg":"trace[1014050090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2543; }","duration":"162.335541ms","start":"2026-07-24T10:15:31.854744Z","end":"2026-07-24T10:15:32.017079Z","steps":["trace[1014050090] 'range keys from in-memory index tree' (duration: 161.955331ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:32.017135Z","caller":"traceutil/trace.go:171","msg":"trace[349969266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2543; }","duration":"107.320714ms","start":"2026-07-24T10:15:31.909789Z","end":"2026-07-24T10:15:32.017109Z","steps":["trace[349969266] 'range keys from in-memory index tree' (duration: 107.013935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:32.017387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.925117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T10:15:32.0176Z","caller":"traceutil/trace.go:171","msg":"trace[1753000412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2543; }","duration":"133.188804ms","start":"2026-07-24T10:15:31.884398Z","end":"2026-07-24T10:15:32.017587Z","steps":["trace[1753000412] 'range keys from in-memory index tree' (duration: 132.791684ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:32.142348Z","caller":"traceutil/trace.go:171","msg":"trace[283293398] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"121.108256ms","start":"2026-07-24T10:15:32.021199Z","end":"2026-07-24T10:15:32.142307Z","steps":["trace[283293398] 'process raft request' (duration: 120.968392ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:32.186836Z","caller":"traceutil/trace.go:171","msg":"trace[1195519224] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"165.3693ms","start":"2026-07-24T10:15:32.021447Z","end":"2026-07-24T10:15:32.186816Z","steps":["trace[1195519224] 'process raft request' (duration: 165.230647ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:32.187053Z","caller":"traceutil/trace.go:171","msg":"trace[1857226138] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"165.444462ms","start":"2026-07-24T10:15:32.0216Z","end":"2026-07-24T10:15:32.187045Z","steps":["trace[1857226138] 'process raft request' (duration: 165.182646ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:32.187113Z","caller":"traceutil/trace.go:171","msg":"trace[852163184] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"164.437327ms","start":"2026-07-24T10:15:32.022668Z","end":"2026-07-24T10:15:32.187105Z","steps":["trace[852163184] 'process raft request' (duration: 164.315832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:35.752204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.847276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:35.752343Z","caller":"traceutil/trace.go:171","msg":"trace[1860723947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2565; }","duration":"103.050451ms","start":"2026-07-24T10:15:35.649272Z","end":"2026-07-24T10:15:35.752322Z","steps":["trace[1860723947] 'range keys from in-memory index tree' (duration: 102.757223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:38.151039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228775227217711653,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T10:15:38.295015Z","caller":"traceutil/trace.go:171","msg":"trace[41084133] linearizableReadLoop","detail":"{readStateIndex:2679; appliedIndex:2678; }","duration":"644.009443ms","start":"2026-07-24T10:15:37.650983Z","end":"2026-07-24T10:15:38.294993Z","steps":["trace[41084133] 'read index received' (duration: 643.86906ms)","trace[41084133] 'applied index is now lower than readState.Index' (duration: 139.313µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.295147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.151967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:38.295123Z","caller":"traceutil/trace.go:171","msg":"trace[541512771] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"840.971424ms","start":"2026-07-24T10:15:37.454112Z","end":"2026-07-24T10:15:38.295083Z","steps":["trace[541512771] 'process raft request' (duration: 840.710758ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.295181Z","caller":"traceutil/trace.go:171","msg":"trace[1921049275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2572; }","duration":"644.231678ms","start":"2026-07-24T10:15:37.650939Z","end":"2026-07-24T10:15:38.29517Z","steps":["trace[1921049275] 'agreement among raft nodes before linearized reading' (duration: 644.159127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:38.295218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:37.650924Z","time spent":"644.28702ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:15:38.295371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:37.454097Z","time spent":"841.100288ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T10:15:38.507971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.705686ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T10:15:38.508318Z","caller":"traceutil/trace.go:171","msg":"trace[1647610468] linearizableReadLoop","detail":"{readStateIndex:2680; appliedIndex:2679; }","duration":"213.21464ms","start":"2026-07-24T10:15:38.295077Z","end":"2026-07-24T10:15:38.508292Z","steps":["trace[1647610468] 'read index received' (duration: 11.197215ms)","trace[1647610468] 'applied index is now lower than readState.Index' (duration: 202.014185ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:15:38.50904Z","caller":"traceutil/trace.go:171","msg":"trace[620368957] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"125.952564ms","start":"2026-07-24T10:15:38.383049Z","end":"2026-07-24T10:15:38.509001Z","steps":["trace[620368957] 'process raft request' (duration: 125.029659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:38.733889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.048224ms","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:534"} {"level":"info","ts":"2026-07-24T10:15:38.733941Z","caller":"traceutil/trace.go:171","msg":"trace[1773429699] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2573; }","duration":"590.154167ms","start":"2026-07-24T10:15:38.143773Z","end":"2026-07-24T10:15:38.733927Z","steps":["trace[1773429699] 'agreement among raft nodes before linearized reading' (duration: 364.910801ms)","trace[1773429699] 'range keys from in-memory index tree' (duration: 225.128213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.733962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.143744Z","time spent":"590.214077ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.893993ms","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-07-24T10:15:38.734191Z","caller":"traceutil/trace.go:171","msg":"trace[1506396721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2573; }","duration":"569.979235ms","start":"2026-07-24T10:15:38.164206Z","end":"2026-07-24T10:15:38.734185Z","steps":["trace[1506396721] 'agreement among raft nodes before linearized reading' (duration: 344.465622ms)","trace[1506396721] 'range keys from in-memory index tree' (duration: 225.467091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.115277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-24T10:15:38.734234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.594038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T10:15:38.734249Z","caller":"traceutil/trace.go:171","msg":"trace[648137476] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2573; }","duration":"522.64697ms","start":"2026-07-24T10:15:38.211597Z","end":"2026-07-24T10:15:38.734244Z","steps":["trace[648137476] 'agreement among raft nodes before linearized reading' (duration: 296.958493ms)","trace[648137476] 'range keys from in-memory index tree' (duration: 225.634316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.211577Z","time spent":"522.68186ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-24T10:15:38.734253Z","caller":"traceutil/trace.go:171","msg":"trace[348740197] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2573; }","duration":"657.23993ms","start":"2026-07-24T10:15:38.076985Z","end":"2026-07-24T10:15:38.734225Z","steps":["trace[348740197] 'agreement among raft nodes before linearized reading' (duration: 431.706547ms)","trace[348740197] 'range keys from in-memory index tree' (duration: 225.338908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.076966Z","time spent":"657.327724ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.727791ms","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-07-24T10:15:38.734363Z","caller":"traceutil/trace.go:171","msg":"trace[626213112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2573; }","duration":"522.782403ms","start":"2026-07-24T10:15:38.211576Z","end":"2026-07-24T10:15:38.734359Z","steps":["trace[626213112] 'agreement among raft nodes before linearized reading' (duration: 297.009033ms)","trace[626213112] 'range keys from in-memory index tree' (duration: 225.732658ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.211552Z","time spent":"522.820885ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T10:15:38.73443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.011499ms","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-07-24T10:15:38.734441Z","caller":"traceutil/trace.go:171","msg":"trace[640629726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2573; }","duration":"526.05659ms","start":"2026-07-24T10:15:38.20838Z","end":"2026-07-24T10:15:38.734437Z","steps":["trace[640629726] 'agreement among raft nodes before linearized reading' (duration: 300.28032ms)","trace[640629726] 'range keys from in-memory index tree' (duration: 225.73961ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.208359Z","time spent":"526.090502ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.614996ms","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-07-24T10:15:38.734515Z","caller":"traceutil/trace.go:171","msg":"trace[281025325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2573; }","duration":"970.639867ms","start":"2026-07-24T10:15:37.763871Z","end":"2026-07-24T10:15:38.734511Z","steps":["trace[281025325] 'agreement among raft nodes before linearized reading' (duration: 744.829466ms)","trace[281025325] 'range keys from in-memory index tree' (duration: 225.77668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:37.763864Z","time spent":"970.664818ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.092018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T10:15:38.734631Z","caller":"traceutil/trace.go:171","msg":"trace[1742430623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2573; }","duration":"971.122039ms","start":"2026-07-24T10:15:37.763498Z","end":"2026-07-24T10:15:38.73462Z","steps":["trace[1742430623] 'agreement among raft nodes before linearized reading' (duration: 745.214005ms)","trace[1742430623] 'range keys from in-memory index tree' (duration: 225.827401ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.164186Z","time spent":"570.014786ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:37.763443Z","time spent":"971.210251ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T10:15:38.734699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.181038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:38.734717Z","caller":"traceutil/trace.go:171","msg":"trace[927675198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2573; }","duration":"438.221229ms","start":"2026-07-24T10:15:38.296489Z","end":"2026-07-24T10:15:38.73471Z","steps":["trace[927675198] 'agreement among raft nodes before linearized reading' (duration: 212.477641ms)","trace[927675198] 'range keys from in-memory index tree' (duration: 225.715818ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:38.734731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:38.29645Z","time spent":"438.2772ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T10:15:38.968579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.422592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:15:38.968799Z","caller":"traceutil/trace.go:171","msg":"trace[191456088] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"229.619281ms","start":"2026-07-24T10:15:38.739163Z","end":"2026-07-24T10:15:38.968783Z","steps":["trace[191456088] 'process raft request' (duration: 229.533999ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.968829Z","caller":"traceutil/trace.go:171","msg":"trace[1581897183] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"229.937489ms","start":"2026-07-24T10:15:38.73887Z","end":"2026-07-24T10:15:38.968808Z","steps":["trace[1581897183] 'process raft request' (duration: 102.191418ms)","trace[1581897183] 'compare' (duration: 127.286689ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:15:38.969336Z","caller":"traceutil/trace.go:171","msg":"trace[1952313627] transaction","detail":"{read_only:false; response_revision:2577; number_of_response:1; }","duration":"230.115384ms","start":"2026-07-24T10:15:38.739214Z","end":"2026-07-24T10:15:38.96933Z","steps":["trace[1952313627] 'process raft request' (duration: 229.534609ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.969342Z","caller":"traceutil/trace.go:171","msg":"trace[1436548756] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"230.023721ms","start":"2026-07-24T10:15:38.739295Z","end":"2026-07-24T10:15:38.969319Z","steps":["trace[1436548756] 'process raft request' (duration: 229.808366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.969448Z","caller":"traceutil/trace.go:171","msg":"trace[62274381] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"230.068372ms","start":"2026-07-24T10:15:38.739375Z","end":"2026-07-24T10:15:38.969443Z","steps":["trace[62274381] 'process raft request' (duration: 229.910518ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.969467Z","caller":"traceutil/trace.go:171","msg":"trace[1873960681] linearizableReadLoop","detail":"{readStateIndex:2688; appliedIndex:2682; }","duration":"227.716221ms","start":"2026-07-24T10:15:38.741738Z","end":"2026-07-24T10:15:38.969455Z","steps":["trace[1873960681] 'read index received' (duration: 1.292944ms)","trace[1873960681] 'applied index is now lower than readState.Index' (duration: 226.422337ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:15:38.969496Z","caller":"traceutil/trace.go:171","msg":"trace[990861980] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"230.069882ms","start":"2026-07-24T10:15:38.739422Z","end":"2026-07-24T10:15:38.969492Z","steps":["trace[990861980] 'process raft request' (duration: 229.95986ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:38.96956Z","caller":"traceutil/trace.go:171","msg":"trace[329951249] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"226.809137ms","start":"2026-07-24T10:15:38.742742Z","end":"2026-07-24T10:15:38.969551Z","steps":["trace[329951249] 'process raft request' (duration: 226.680144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:38.969684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.940217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:38.969717Z","caller":"traceutil/trace.go:171","msg":"trace[206152015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2581; }","duration":"227.996389ms","start":"2026-07-24T10:15:38.741712Z","end":"2026-07-24T10:15:38.969708Z","steps":["trace[206152015] 'agreement among raft nodes before linearized reading' (duration: 227.943068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:38.969805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.43465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-24T10:15:38.969879Z","caller":"traceutil/trace.go:171","msg":"trace[1901559296] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2581; }","duration":"209.547373ms","start":"2026-07-24T10:15:38.760315Z","end":"2026-07-24T10:15:38.969862Z","steps":["trace[1901559296] 'agreement among raft nodes before linearized reading' (duration: 209.330757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:39.88908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.364723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:39.889152Z","caller":"traceutil/trace.go:171","msg":"trace[158847797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2582; }","duration":"240.482537ms","start":"2026-07-24T10:15:39.648655Z","end":"2026-07-24T10:15:39.889138Z","steps":["trace[158847797] 'range keys from in-memory index tree' (duration: 240.300332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:39.889304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.590395ms","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-07-24T10:15:39.889336Z","caller":"traceutil/trace.go:171","msg":"trace[1997988836] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2582; }","duration":"596.653747ms","start":"2026-07-24T10:15:39.292676Z","end":"2026-07-24T10:15:39.889329Z","steps":["trace[1997988836] 'range keys from in-memory index tree' (duration: 596.406461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:39.889321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.631618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-07-24T10:15:39.890549Z","caller":"traceutil/trace.go:171","msg":"trace[48325690] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2582; }","duration":"238.721731ms","start":"2026-07-24T10:15:39.65064Z","end":"2026-07-24T10:15:39.889362Z","steps":["trace[48325690] 'range keys from in-memory index tree' (duration: 238.418932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:39.889293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.616353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:39.891726Z","caller":"traceutil/trace.go:171","msg":"trace[1265717372] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2582; }","duration":"589.094039ms","start":"2026-07-24T10:15:39.302608Z","end":"2026-07-24T10:15:39.891702Z","steps":["trace[1265717372] 'count revisions from in-memory index tree' (duration: 586.51288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:39.891808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:39.302581Z","time spent":"589.204731ms","remote":"127.0.0.1:60094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T10:15:39.890202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:15:39.292658Z","time spent":"596.698588ms","remote":"127.0.0.1:60080","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":"info","ts":"2026-07-24T10:15:40.150538Z","caller":"traceutil/trace.go:171","msg":"trace[381886420] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"251.906557ms","start":"2026-07-24T10:15:39.898598Z","end":"2026-07-24T10:15:40.150505Z","steps":["trace[381886420] 'process raft request' (duration: 251.650391ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:46.423204Z","caller":"traceutil/trace.go:171","msg":"trace[299741198] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"102.471775ms","start":"2026-07-24T10:15:46.320697Z","end":"2026-07-24T10:15:46.423169Z","steps":["trace[299741198] 'process raft request' (duration: 102.194718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:54.762105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.880777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:15:54.762211Z","caller":"traceutil/trace.go:171","msg":"trace[1083025358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2662; }","duration":"110.037111ms","start":"2026-07-24T10:15:54.652139Z","end":"2026-07-24T10:15:54.762176Z","steps":["trace[1083025358] 'range keys from in-memory index tree' (duration: 109.756904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:54.762481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.045156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-07-24T10:15:54.762669Z","caller":"traceutil/trace.go:171","msg":"trace[866269978] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:2662; }","duration":"117.274022ms","start":"2026-07-24T10:15:54.645377Z","end":"2026-07-24T10:15:54.762651Z","steps":["trace[866269978] 'range keys from in-memory index tree' (duration: 116.644235ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:55.352137Z","caller":"traceutil/trace.go:171","msg":"trace[64687447] linearizableReadLoop","detail":"{readStateIndex:2781; appliedIndex:2780; }","duration":"114.102939ms","start":"2026-07-24T10:15:55.238002Z","end":"2026-07-24T10:15:55.352105Z","steps":["trace[64687447] 'read index received' (duration: 72.558574ms)","trace[64687447] 'applied index is now lower than readState.Index' (duration: 41.543024ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:15:55.352158Z","caller":"traceutil/trace.go:171","msg":"trace[352691640] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"167.817555ms","start":"2026-07-24T10:15:55.184299Z","end":"2026-07-24T10:15:55.352116Z","steps":["trace[352691640] 'process raft request' (duration: 126.251589ms)","trace[352691640] 'compare' (duration: 41.422452ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:55.352226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.214021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-07-24T10:15:55.352267Z","caller":"traceutil/trace.go:171","msg":"trace[1428538454] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:2669; }","duration":"114.266712ms","start":"2026-07-24T10:15:55.237992Z","end":"2026-07-24T10:15:55.352259Z","steps":["trace[1428538454] 'agreement among raft nodes before linearized reading' (duration: 114.18015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:55.352614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.443898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-07-24T10:15:55.352675Z","caller":"traceutil/trace.go:171","msg":"trace[1936692386] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:2669; }","duration":"103.5552ms","start":"2026-07-24T10:15:55.249099Z","end":"2026-07-24T10:15:55.352654Z","steps":["trace[1936692386] 'agreement among raft nodes before linearized reading' (duration: 103.235841ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:58.462606Z","caller":"traceutil/trace.go:171","msg":"trace[64065182] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2788; }","duration":"133.218343ms","start":"2026-07-24T10:15:58.329364Z","end":"2026-07-24T10:15:58.462583Z","steps":["trace[64065182] 'read index received' (duration: 82.398092ms)","trace[64065182] 'applied index is now lower than readState.Index' (duration: 50.819131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T10:15:58.462775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.76398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-07-24T10:15:58.462803Z","caller":"traceutil/trace.go:171","msg":"trace[2088267869] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:2676; }","duration":"124.825261ms","start":"2026-07-24T10:15:58.337968Z","end":"2026-07-24T10:15:58.462793Z","steps":["trace[2088267869] 'agreement among raft nodes before linearized reading' (duration: 124.737069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:15:58.462888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.487059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-07-24T10:15:58.462991Z","caller":"traceutil/trace.go:171","msg":"trace[106979] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:2676; }","duration":"133.617242ms","start":"2026-07-24T10:15:58.329353Z","end":"2026-07-24T10:15:58.46297Z","steps":["trace[106979] 'agreement among raft nodes before linearized reading' (duration: 133.326585ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:59.443163Z","caller":"traceutil/trace.go:171","msg":"trace[289985607] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"109.940429ms","start":"2026-07-24T10:15:59.333198Z","end":"2026-07-24T10:15:59.443138Z","steps":["trace[289985607] 'process raft request' (duration: 109.790885ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:59.446091Z","caller":"traceutil/trace.go:171","msg":"trace[785457909] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"112.152148ms","start":"2026-07-24T10:15:59.333927Z","end":"2026-07-24T10:15:59.446079Z","steps":["trace[785457909] 'process raft request' (duration: 112.032935ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:15:59.446138Z","caller":"traceutil/trace.go:171","msg":"trace[1792963466] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"111.552032ms","start":"2026-07-24T10:15:59.334565Z","end":"2026-07-24T10:15:59.446117Z","steps":["trace[1792963466] 'process raft request' (duration: 111.46845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:00.708659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.538341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-07-24T10:16:00.708841Z","caller":"traceutil/trace.go:171","msg":"trace[1292466118] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:2689; }","duration":"122.782497ms","start":"2026-07-24T10:16:00.58603Z","end":"2026-07-24T10:16:00.708813Z","steps":["trace[1292466118] 'range keys from in-memory index tree' (duration: 122.275714ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:01.57788Z","caller":"traceutil/trace.go:171","msg":"trace[1849755980] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"289.123203ms","start":"2026-07-24T10:16:01.288733Z","end":"2026-07-24T10:16:01.577857Z","steps":["trace[1849755980] 'read index received' (duration: 288.938997ms)","trace[1849755980] 'applied index is now lower than readState.Index' (duration: 183.046µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:16:01.577929Z","caller":"traceutil/trace.go:171","msg":"trace[1724284058] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"328.495052ms","start":"2026-07-24T10:16:01.249401Z","end":"2026-07-24T10:16:01.577896Z","steps":["trace[1724284058] 'process raft request' (duration: 328.295816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.578064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.307537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-07-24T10:16:01.578114Z","caller":"traceutil/trace.go:171","msg":"trace[2027698146] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:2691; }","duration":"289.371909ms","start":"2026-07-24T10:16:01.288729Z","end":"2026-07-24T10:16:01.578101Z","steps":["trace[2027698146] 'agreement among raft nodes before linearized reading' (duration: 289.266306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.578146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.205296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:638"} {"level":"info","ts":"2026-07-24T10:16:01.578217Z","caller":"traceutil/trace.go:171","msg":"trace[173664272] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:2691; }","duration":"286.317839ms","start":"2026-07-24T10:16:01.291883Z","end":"2026-07-24T10:16:01.578201Z","steps":["trace[173664272] 'agreement among raft nodes before linearized reading' (duration: 286.167965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.578229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.121368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-24T10:16:01.578253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.029275ms","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-07-24T10:16:01.578286Z","caller":"traceutil/trace.go:171","msg":"trace[1653890861] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2691; }","duration":"128.22265ms","start":"2026-07-24T10:16:01.450049Z","end":"2026-07-24T10:16:01.578271Z","steps":["trace[1653890861] 'agreement among raft nodes before linearized reading' (duration: 128.032685ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:01.578324Z","caller":"traceutil/trace.go:171","msg":"trace[259134308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2691; }","duration":"128.113438ms","start":"2026-07-24T10:16:01.450191Z","end":"2026-07-24T10:16:01.578305Z","steps":["trace[259134308] 'agreement among raft nodes before linearized reading' (duration: 127.949684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.578538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.884347ms","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-07-24T10:16:01.578717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T10:16:01.24938Z","time spent":"328.620885ms","remote":"127.0.0.1:60080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T10:16:01.579448Z","caller":"traceutil/trace.go:171","msg":"trace[650480631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2691; }","duration":"245.01748ms","start":"2026-07-24T10:16:01.33359Z","end":"2026-07-24T10:16:01.578608Z","steps":["trace[650480631] 'agreement among raft nodes before linearized reading' (duration: 244.63332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.579643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.642211ms","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-07-24T10:16:01.579692Z","caller":"traceutil/trace.go:171","msg":"trace[985403890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2691; }","duration":"131.733043ms","start":"2026-07-24T10:16:01.447945Z","end":"2026-07-24T10:16:01.579678Z","steps":["trace[985403890] 'agreement among raft nodes before linearized reading' (duration: 131.540878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.800197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.427272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T10:16:01.800364Z","caller":"traceutil/trace.go:171","msg":"trace[1290315757] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"218.563333ms","start":"2026-07-24T10:16:01.58178Z","end":"2026-07-24T10:16:01.800344Z","steps":["trace[1290315757] 'process raft request' (duration: 77.898635ms)","trace[1290315757] 'compare' (duration: 140.230097ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:16:01.801021Z","caller":"traceutil/trace.go:171","msg":"trace[1865268369] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2804; }","duration":"217.867925ms","start":"2026-07-24T10:16:01.583138Z","end":"2026-07-24T10:16:01.801006Z","steps":["trace[1865268369] 'read index received' (duration: 76.548899ms)","trace[1865268369] 'applied index is now lower than readState.Index' (duration: 141.318215ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T10:16:01.801057Z","caller":"traceutil/trace.go:171","msg":"trace[1945207830] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"217.941386ms","start":"2026-07-24T10:16:01.583093Z","end":"2026-07-24T10:16:01.801034Z","steps":["trace[1945207830] 'process raft request' (duration: 217.806013ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:01.801082Z","caller":"traceutil/trace.go:171","msg":"trace[2014636791] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"217.596517ms","start":"2026-07-24T10:16:01.583469Z","end":"2026-07-24T10:16:01.801065Z","steps":["trace[2014636791] 'process raft request' (duration: 217.515365ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T10:16:01.801082Z","caller":"traceutil/trace.go:171","msg":"trace[764046633] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"217.860325ms","start":"2026-07-24T10:16:01.583189Z","end":"2026-07-24T10:16:01.80105Z","steps":["trace[764046633] 'process raft request' (duration: 217.775092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.801149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.996609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-07-24T10:16:01.801172Z","caller":"traceutil/trace.go:171","msg":"trace[1429491613] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:2695; }","duration":"218.0571ms","start":"2026-07-24T10:16:01.583108Z","end":"2026-07-24T10:16:01.801165Z","steps":["trace[1429491613] 'agreement among raft nodes before linearized reading' (duration: 217.990809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T10:16:01.801342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.572016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T10:16:01.80138Z","caller":"traceutil/trace.go:171","msg":"trace[1535757818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2695; }","duration":"151.658599ms","start":"2026-07-24T10:16:01.64971Z","end":"2026-07-24T10:16:01.801369Z","steps":["trace[1535757818] 'agreement among raft nodes before linearized reading' (duration: 151.575367ms)"],"step_count":1}