{"level":"warn","ts":"2026-02-01T18:47:03.244206Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:47:03.244389Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-02-01T18:47:03.244498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:47:03.244521Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-01T18:47:03.244571Z","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-02-01T18:47:03.245287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-02-01T18:47:03.245447Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-02-01T18:47:03.250049Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.287236ms"} {"level":"info","ts":"2026-02-01T18:47:03.260605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-02-01T18:47:03.260968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:47:03.261277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:47:03.261308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:47:03.261447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:47:03.261674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-02-01T18:47:03.271575Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:47:03.282982Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:47:03.286686Z","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-02-01T18:47:03.291296Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:47:03.294914Z","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-02-01T18:47:03.295219Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:47:03.295248Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:47:03.29549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-01T18:47:03.295512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-01T18:47:03.297284Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:47:03.297439Z","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-02-01T18:47:03.297468Z","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-02-01T18:47:03.297535Z","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-02-01T18:47:03.342218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-02-01T18:47:03.342389Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-01T18:47:04.063143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:47:04.063179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:47:04.063197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-02-01T18:47:04.063208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:47:04.063213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-01T18:47:04.063221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:47:04.063227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-01T18:47:04.066931Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:04.069322Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:47:04.070149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:47:04.070445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:47:04.070595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:47:04.070608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:47:04.070645Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:04.070717Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:04.070737Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:47:04.07423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-02-01T18:47:04.078586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:47:07.185814Z","caller":"traceutil/trace.go:171","msg":"trace[1964511785] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:77; }","duration":"139.231316ms","start":"2026-02-01T18:47:07.046566Z","end":"2026-02-01T18:47:07.185797Z","steps":["trace[1964511785] 'read index received' (duration: 86.259276ms)","trace[1964511785] 'applied index is now lower than readState.Index' (duration: 52.97131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:07.186076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.497155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:47:07.186111Z","caller":"traceutil/trace.go:171","msg":"trace[1073005096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"139.578453ms","start":"2026-02-01T18:47:07.046521Z","end":"2026-02-01T18:47:07.186099Z","steps":["trace[1073005096] 'agreement among raft nodes before linearized reading' (duration: 139.422597ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:07.186308Z","caller":"traceutil/trace.go:171","msg":"trace[1699197438] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"188.952764ms","start":"2026-02-01T18:47:06.997284Z","end":"2026-02-01T18:47:07.186237Z","steps":["trace[1699197438] 'process raft request' (duration: 135.516145ms)","trace[1699197438] 'compare' (duration: 52.870265ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:07.18636Z","caller":"traceutil/trace.go:171","msg":"trace[174041383] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"186.915851ms","start":"2026-02-01T18:47:06.999437Z","end":"2026-02-01T18:47:07.186353Z","steps":["trace[174041383] 'process raft request' (duration: 186.321202ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:07.420135Z","caller":"traceutil/trace.go:171","msg":"trace[568823791] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"133.11576ms","start":"2026-02-01T18:47:07.286995Z","end":"2026-02-01T18:47:07.42011Z","steps":["trace[568823791] 'process raft request' (duration: 48.038594ms)","trace[568823791] 'compare' (duration: 84.980081ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:07.652906Z","caller":"traceutil/trace.go:171","msg":"trace[1977506587] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"129.524829ms","start":"2026-02-01T18:47:07.523361Z","end":"2026-02-01T18:47:07.652886Z","steps":["trace[1977506587] 'process raft request' (duration: 45.273296ms)","trace[1977506587] 'compare' (duration: 84.167995ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:08.029355Z","caller":"traceutil/trace.go:171","msg":"trace[1194554095] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"181.349014ms","start":"2026-02-01T18:47:07.847981Z","end":"2026-02-01T18:47:08.02933Z","steps":["trace[1194554095] 'process raft request' (duration: 150.216748ms)","trace[1194554095] 'compare' (duration: 31.032071ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:10.783793Z","caller":"traceutil/trace.go:171","msg":"trace[674255663] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"133.010346ms","start":"2026-02-01T18:47:10.650756Z","end":"2026-02-01T18:47:10.783767Z","steps":["trace[674255663] 'process raft request' (duration: 76.644936ms)","trace[674255663] 'compare' (duration: 56.112516ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:10.78418Z","caller":"traceutil/trace.go:171","msg":"trace[1581229399] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:284; }","duration":"130.926187ms","start":"2026-02-01T18:47:10.65279Z","end":"2026-02-01T18:47:10.783716Z","steps":["trace[1581229399] 'read index received' (duration: 74.621349ms)","trace[1581229399] 'applied index is now lower than readState.Index' (duration: 56.303948ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:10.784331Z","caller":"traceutil/trace.go:171","msg":"trace[2057427245] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"131.585572ms","start":"2026-02-01T18:47:10.652735Z","end":"2026-02-01T18:47:10.78432Z","steps":["trace[2057427245] 'process raft request' (duration: 130.940675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:10.784934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.69016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-01T18:47:10.785153Z","caller":"traceutil/trace.go:171","msg":"trace[15840883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:279; }","duration":"128.925086ms","start":"2026-02-01T18:47:10.656214Z","end":"2026-02-01T18:47:10.785139Z","steps":["trace[15840883] 'agreement among raft nodes before linearized reading' (duration: 128.638318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:10.785504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.707119ms","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-02-01T18:47:10.785995Z","caller":"traceutil/trace.go:171","msg":"trace[2065556919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"133.209457ms","start":"2026-02-01T18:47:10.652767Z","end":"2026-02-01T18:47:10.785977Z","steps":["trace[2065556919] 'agreement among raft nodes before linearized reading' (duration: 132.696311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.440988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143218039s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:12.441063Z","caller":"traceutil/trace.go:171","msg":"trace[1676012864] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"1.234455954s","start":"2026-02-01T18:47:11.206585Z","end":"2026-02-01T18:47:12.441041Z","steps":["trace[1676012864] 'read index received' (duration: 90.895963ms)","trace[1676012864] 'applied index is now lower than readState.Index' (duration: 1.143559061s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:12.441225Z","caller":"traceutil/trace.go:171","msg":"trace[1124216726] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"1.268125234s","start":"2026-02-01T18:47:11.173093Z","end":"2026-02-01T18:47:12.441218Z","steps":["trace[1124216726] 'process raft request' (duration: 124.380148ms)","trace[1124216726] 'compare' (duration: 1.143135751s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:12.441253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.173079Z","time spent":"1.268161599s","remote":"127.0.0.1:34278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:47:12.441395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234802436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:12.441429Z","caller":"traceutil/trace.go:171","msg":"trace[657928335] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:289; }","duration":"1.234835572s","start":"2026-02-01T18:47:11.206581Z","end":"2026-02-01T18:47:12.441417Z","steps":["trace[657928335] 'agreement among raft nodes before linearized reading' (duration: 1.234759843s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.441464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.206568Z","time spent":"1.234886225s","remote":"127.0.0.1:34112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":26,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-01T18:47:12.447415Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150008442s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:47:12.447852Z","caller":"traceutil/trace.go:171","msg":"trace[566222348] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"1.19475407s","start":"2026-02-01T18:47:11.253084Z","end":"2026-02-01T18:47:12.447838Z","steps":["trace[566222348] 'process raft request' (duration: 1.19468334s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.448003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.253067Z","time spent":"1.194844297s","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:12.451915Z","caller":"traceutil/trace.go:171","msg":"trace[1586251236] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"1.090943251s","start":"2026-02-01T18:47:11.360956Z","end":"2026-02-01T18:47:12.451899Z","steps":["trace[1586251236] 'process raft request' (duration: 1.090646842s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.452164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.360935Z","time spent":"1.091039637s","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:12.45243Z","caller":"traceutil/trace.go:171","msg":"trace[1891577264] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"1.076575372s","start":"2026-02-01T18:47:11.375849Z","end":"2026-02-01T18:47:12.452424Z","steps":["trace[1891577264] 'process raft request' (duration: 1.075827047s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.452511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.37583Z","time spent":"1.076618676s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:12.452721Z","caller":"traceutil/trace.go:171","msg":"trace[1552331308] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"1.07455063s","start":"2026-02-01T18:47:11.378164Z","end":"2026-02-01T18:47:12.452715Z","steps":["trace[1552331308] 'process raft request' (duration: 1.073564907s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.45275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.378154Z","time spent":"1.074584036s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:12.45292Z","caller":"traceutil/trace.go:171","msg":"trace[451545682] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"1.074675013s","start":"2026-02-01T18:47:11.378218Z","end":"2026-02-01T18:47:12.452893Z","steps":["trace[451545682] 'process raft request' (duration: 1.073558519s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:12.453021Z","caller":"traceutil/trace.go:171","msg":"trace[114436297] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.074671533s","start":"2026-02-01T18:47:11.378342Z","end":"2026-02-01T18:47:12.453013Z","steps":["trace[114436297] 'process raft request' (duration: 1.073483949s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.454275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.378211Z","time spent":"1.076034853s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:47:12.454294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.378338Z","time spent":"1.075891514s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:47:12.456831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.4916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-01T18:47:12.452993Z","caller":"traceutil/trace.go:171","msg":"trace[1465573646] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.074723186s","start":"2026-02-01T18:47:11.378261Z","end":"2026-02-01T18:47:12.452984Z","steps":["trace[1465573646] 'process raft request' (duration: 1.07354989s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.457872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.185576ms","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-02-01T18:47:12.45814Z","caller":"traceutil/trace.go:171","msg":"trace[1788720931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:296; }","duration":"582.474025ms","start":"2026-02-01T18:47:11.875642Z","end":"2026-02-01T18:47:12.458117Z","steps":["trace[1788720931] 'agreement among raft nodes before linearized reading' (duration: 582.197064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.458301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.875621Z","time spent":"582.667428ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:47:12.461658Z","caller":"traceutil/trace.go:171","msg":"trace[1627488822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:296; }","duration":"172.348472ms","start":"2026-02-01T18:47:12.289294Z","end":"2026-02-01T18:47:12.461643Z","steps":["trace[1627488822] 'agreement among raft nodes before linearized reading' (duration: 167.391023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.46198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.378257Z","time spent":"1.083666679s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:47:12.457907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.013245ms","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-02-01T18:47:12.462597Z","caller":"traceutil/trace.go:171","msg":"trace[1779296206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:296; }","duration":"739.665116ms","start":"2026-02-01T18:47:11.722889Z","end":"2026-02-01T18:47:12.462554Z","steps":["trace[1779296206] 'agreement among raft nodes before linearized reading' (duration: 734.999387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.46278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.722825Z","time spent":"739.927619ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:12.457928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199112997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:12.464579Z","caller":"traceutil/trace.go:171","msg":"trace[1858497662] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:296; }","duration":"1.205759019s","start":"2026-02-01T18:47:11.258801Z","end":"2026-02-01T18:47:12.46456Z","steps":["trace[1858497662] 'agreement among raft nodes before linearized reading' (duration: 1.199114096s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.464727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.258796Z","time spent":"1.205915598s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-01T18:47:12.457969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205138351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-01T18:47:12.46537Z","caller":"traceutil/trace.go:171","msg":"trace[873115381] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:296; }","duration":"1.212566765s","start":"2026-02-01T18:47:11.252791Z","end":"2026-02-01T18:47:12.465358Z","steps":["trace[873115381] 'agreement among raft nodes before linearized reading' (duration: 1.20514547s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:12.46551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:11.252773Z","time spent":"1.212726822s","remote":"127.0.0.1:34316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":685,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-01T18:47:14.639394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.558968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:14.639459Z","caller":"traceutil/trace.go:171","msg":"trace[1198381283] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"167.160097ms","start":"2026-02-01T18:47:14.472289Z","end":"2026-02-01T18:47:14.639449Z","steps":["trace[1198381283] 'read index received' (duration: 48.453489ms)","trace[1198381283] 'applied index is now lower than readState.Index' (duration: 118.705988ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:14.639736Z","caller":"traceutil/trace.go:171","msg":"trace[1597724790] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"180.937087ms","start":"2026-02-01T18:47:14.45879Z","end":"2026-02-01T18:47:14.639727Z","steps":["trace[1597724790] 'process raft request' (duration: 62.004369ms)","trace[1597724790] 'compare' (duration: 118.47211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.640576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.278567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:14.640598Z","caller":"traceutil/trace.go:171","msg":"trace[340471588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:345; }","duration":"168.316532ms","start":"2026-02-01T18:47:14.472276Z","end":"2026-02-01T18:47:14.640593Z","steps":["trace[340471588] 'agreement among raft nodes before linearized reading' (duration: 168.242112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.640794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.649765ms","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-02-01T18:47:14.640829Z","caller":"traceutil/trace.go:171","msg":"trace[1495887436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"148.681111ms","start":"2026-02-01T18:47:14.49213Z","end":"2026-02-01T18:47:14.640811Z","steps":["trace[1495887436] 'agreement among raft nodes before linearized reading' (duration: 148.638777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.641169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.451893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-01T18:47:14.64119Z","caller":"traceutil/trace.go:171","msg":"trace[1820552194] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"134.516364ms","start":"2026-02-01T18:47:14.506668Z","end":"2026-02-01T18:47:14.641185Z","steps":["trace[1820552194] 'agreement among raft nodes before linearized reading' (duration: 134.481518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.641407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.00501ms","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-02-01T18:47:14.641427Z","caller":"traceutil/trace.go:171","msg":"trace[1719349551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:345; }","duration":"137.034696ms","start":"2026-02-01T18:47:14.504387Z","end":"2026-02-01T18:47:14.641422Z","steps":["trace[1719349551] 'agreement among raft nodes before linearized reading' (duration: 137.00063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.641594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.737555ms","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-02-01T18:47:14.641617Z","caller":"traceutil/trace.go:171","msg":"trace[810881218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:345; }","duration":"143.77219ms","start":"2026-02-01T18:47:14.49784Z","end":"2026-02-01T18:47:14.641612Z","steps":["trace[810881218] 'agreement among raft nodes before linearized reading' (duration: 143.733446ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:14.76007Z","caller":"traceutil/trace.go:171","msg":"trace[292091021] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"106.259121ms","start":"2026-02-01T18:47:14.653792Z","end":"2026-02-01T18:47:14.760051Z","steps":["trace[292091021] 'process raft request' (duration: 82.654563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:14.825231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.860184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-01T18:47:14.825341Z","caller":"traceutil/trace.go:171","msg":"trace[1029670308] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:352; }","duration":"168.118939ms","start":"2026-02-01T18:47:14.657181Z","end":"2026-02-01T18:47:14.8253Z","steps":["trace[1029670308] 'agreement among raft nodes before linearized reading' (duration: 102.497247ms)","trace[1029670308] 'range keys from in-memory index tree' (duration: 65.314404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.825787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.768512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-7pm5p\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-01T18:47:14.825816Z","caller":"traceutil/trace.go:171","msg":"trace[953011953] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-7pm5p; range_end:; response_count:1; response_revision:353; }","duration":"145.832164ms","start":"2026-02-01T18:47:14.679977Z","end":"2026-02-01T18:47:14.825809Z","steps":["trace[953011953] 'agreement among raft nodes before linearized reading' (duration: 145.738547ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:14.826015Z","caller":"traceutil/trace.go:171","msg":"trace[84078991] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"145.883137ms","start":"2026-02-01T18:47:14.680122Z","end":"2026-02-01T18:47:14.826006Z","steps":["trace[84078991] 'process raft request' (duration: 83.747266ms)","trace[84078991] 'compare' (duration: 60.50253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:14.826334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.468036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-01T18:47:14.826399Z","caller":"traceutil/trace.go:171","msg":"trace[1688422323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"119.530278ms","start":"2026-02-01T18:47:14.706844Z","end":"2026-02-01T18:47:14.826375Z","steps":["trace[1688422323] 'agreement among raft nodes before linearized reading' (duration: 119.466437ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.103817Z","caller":"traceutil/trace.go:171","msg":"trace[1390279628] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:363; }","duration":"250.769049ms","start":"2026-02-01T18:47:14.853026Z","end":"2026-02-01T18:47:15.103795Z","steps":["trace[1390279628] 'read index received' (duration: 45.465171ms)","trace[1390279628] 'applied index is now lower than readState.Index' (duration: 205.302938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:15.104341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.298299ms","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-02-01T18:47:15.104501Z","caller":"traceutil/trace.go:171","msg":"trace[667111725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:357; }","duration":"251.479815ms","start":"2026-02-01T18:47:14.853008Z","end":"2026-02-01T18:47:15.104488Z","steps":["trace[667111725] 'agreement among raft nodes before linearized reading' (duration: 251.281351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.105779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.831183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-01T18:47:15.104533Z","caller":"traceutil/trace.go:171","msg":"trace[616500228] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"264.732555ms","start":"2026-02-01T18:47:14.839734Z","end":"2026-02-01T18:47:15.104467Z","steps":["trace[616500228] 'process raft request' (duration: 210.682025ms)","trace[616500228] 'compare' (duration: 52.930687ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:15.104342Z","caller":"traceutil/trace.go:171","msg":"trace[1610755695] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"263.292437ms","start":"2026-02-01T18:47:14.840887Z","end":"2026-02-01T18:47:15.104179Z","steps":["trace[1610755695] 'process raft request' (duration: 262.865943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.104723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.963669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:47:15.106871Z","caller":"traceutil/trace.go:171","msg":"trace[894569212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:357; }","duration":"250.135402ms","start":"2026-02-01T18:47:14.85672Z","end":"2026-02-01T18:47:15.106855Z","steps":["trace[894569212] 'agreement among raft nodes before linearized reading' (duration: 247.914995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.105736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.629534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-01T18:47:15.107723Z","caller":"traceutil/trace.go:171","msg":"trace[1670330934] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:357; }","duration":"149.641379ms","start":"2026-02-01T18:47:14.958065Z","end":"2026-02-01T18:47:15.107706Z","steps":["trace[1670330934] 'agreement among raft nodes before linearized reading' (duration: 147.643602ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.105733Z","caller":"traceutil/trace.go:171","msg":"trace[263408886] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"265.759769ms","start":"2026-02-01T18:47:14.839956Z","end":"2026-02-01T18:47:15.105716Z","steps":["trace[263408886] 'process raft request' (duration: 263.716209ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.105821Z","caller":"traceutil/trace.go:171","msg":"trace[1177709933] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:357; }","duration":"198.893085ms","start":"2026-02-01T18:47:14.906917Z","end":"2026-02-01T18:47:15.10581Z","steps":["trace[1177709933] 'agreement among raft nodes before linearized reading' (duration: 198.843631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.414817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.97695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:15.415508Z","caller":"traceutil/trace.go:171","msg":"trace[1559146595] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"291.707669ms","start":"2026-02-01T18:47:15.123773Z","end":"2026-02-01T18:47:15.41548Z","steps":["trace[1559146595] 'process raft request' (duration: 158.804741ms)","trace[1559146595] 'compare' (duration: 131.743891ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:15.416139Z","caller":"traceutil/trace.go:171","msg":"trace[1360726633] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"237.782323ms","start":"2026-02-01T18:47:15.17834Z","end":"2026-02-01T18:47:15.416123Z","steps":["trace[1360726633] 'process raft request' (duration: 237.73312ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.416814Z","caller":"traceutil/trace.go:171","msg":"trace[2124221019] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"292.960573ms","start":"2026-02-01T18:47:15.123821Z","end":"2026-02-01T18:47:15.416782Z","steps":["trace[2124221019] 'process raft request' (duration: 291.095039ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.417266Z","caller":"traceutil/trace.go:171","msg":"trace[1722600593] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"293.405434ms","start":"2026-02-01T18:47:15.123845Z","end":"2026-02-01T18:47:15.41725Z","steps":["trace[1722600593] 'process raft request' (duration: 291.512924ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.417639Z","caller":"traceutil/trace.go:171","msg":"trace[686257085] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"292.30872ms","start":"2026-02-01T18:47:15.125315Z","end":"2026-02-01T18:47:15.417624Z","steps":["trace[686257085] 'process raft request' (duration: 290.126338ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.418222Z","caller":"traceutil/trace.go:171","msg":"trace[1619512961] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"286.319179ms","start":"2026-02-01T18:47:15.131886Z","end":"2026-02-01T18:47:15.418205Z","steps":["trace[1619512961] 'process raft request' (duration: 283.610777ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.419567Z","caller":"traceutil/trace.go:171","msg":"trace[1505028309] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"287.589891ms","start":"2026-02-01T18:47:15.131925Z","end":"2026-02-01T18:47:15.419514Z","steps":["trace[1505028309] 'process raft request' (duration: 283.648712ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.41998Z","caller":"traceutil/trace.go:171","msg":"trace[339660866] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"288.020595ms","start":"2026-02-01T18:47:15.131943Z","end":"2026-02-01T18:47:15.419964Z","steps":["trace[339660866] 'process raft request' (duration: 283.706754ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.420945Z","caller":"traceutil/trace.go:171","msg":"trace[200208123] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"288.915487ms","start":"2026-02-01T18:47:15.132012Z","end":"2026-02-01T18:47:15.420928Z","steps":["trace[200208123] 'process raft request' (duration: 283.694446ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.421784Z","caller":"traceutil/trace.go:171","msg":"trace[1251463812] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"285.598645ms","start":"2026-02-01T18:47:15.136169Z","end":"2026-02-01T18:47:15.421768Z","steps":["trace[1251463812] 'process raft request' (duration: 279.751487ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.422501Z","caller":"traceutil/trace.go:171","msg":"trace[1347610933] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"281.602822ms","start":"2026-02-01T18:47:15.14089Z","end":"2026-02-01T18:47:15.422493Z","steps":["trace[1347610933] 'process raft request' (duration: 275.089541ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:15.422698Z","caller":"traceutil/trace.go:171","msg":"trace[1014089010] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:368; }","duration":"244.432916ms","start":"2026-02-01T18:47:15.178257Z","end":"2026-02-01T18:47:15.42269Z","steps":["trace[1014089010] 'read index received' (duration: 104.329098ms)","trace[1014089010] 'applied index is now lower than readState.Index' (duration: 140.102928ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:15.422852Z","caller":"traceutil/trace.go:171","msg":"trace[475522825] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"244.515585ms","start":"2026-02-01T18:47:15.178327Z","end":"2026-02-01T18:47:15.422842Z","steps":["trace[475522825] 'process raft request' (duration: 237.705723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.423138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.871177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:47:15.423169Z","caller":"traceutil/trace.go:171","msg":"trace[2106716054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"244.936768ms","start":"2026-02-01T18:47:15.178225Z","end":"2026-02-01T18:47:15.423162Z","steps":["trace[2106716054] 'agreement among raft nodes before linearized reading' (duration: 244.876346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:15.423487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.775922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-01T18:47:15.423513Z","caller":"traceutil/trace.go:171","msg":"trace[506088597] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:370; }","duration":"102.829465ms","start":"2026-02-01T18:47:15.320678Z","end":"2026-02-01T18:47:15.423507Z","steps":["trace[506088597] 'agreement among raft nodes before linearized reading' (duration: 102.781851ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:16.825283Z","caller":"traceutil/trace.go:171","msg":"trace[1657319057] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"140.731966ms","start":"2026-02-01T18:47:16.684526Z","end":"2026-02-01T18:47:16.825258Z","steps":["trace[1657319057] 'process raft request' (duration: 140.577406ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:16.96841Z","caller":"traceutil/trace.go:171","msg":"trace[1798044708] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"133.617926ms","start":"2026-02-01T18:47:16.834777Z","end":"2026-02-01T18:47:16.968395Z","steps":["trace[1798044708] 'process raft request' (duration: 133.577341ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:16.968779Z","caller":"traceutil/trace.go:171","msg":"trace[559808240] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"135.706035ms","start":"2026-02-01T18:47:16.833063Z","end":"2026-02-01T18:47:16.968769Z","steps":["trace[559808240] 'process raft request' (duration: 58.293629ms)","trace[559808240] 'compare' (duration: 76.908741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:17.691805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.38797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:17.69189Z","caller":"traceutil/trace.go:171","msg":"trace[363358125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"218.519663ms","start":"2026-02-01T18:47:17.473355Z","end":"2026-02-01T18:47:17.691874Z","steps":["trace[363358125] 'range keys from in-memory index tree' (duration: 218.322399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:17.69216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.897955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-01T18:47:17.692189Z","caller":"traceutil/trace.go:171","msg":"trace[1485124235] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:397; }","duration":"367.961587ms","start":"2026-02-01T18:47:17.324219Z","end":"2026-02-01T18:47:17.69218Z","steps":["trace[1485124235] 'range keys from in-memory index tree' (duration: 367.817836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:17.692231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:17.324201Z","time spent":"368.02207ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4281,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-01T18:47:17.692363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.614547ms","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-02-01T18:47:17.692392Z","caller":"traceutil/trace.go:171","msg":"trace[1287332426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:397; }","duration":"258.672949ms","start":"2026-02-01T18:47:17.433711Z","end":"2026-02-01T18:47:17.692384Z","steps":["trace[1287332426] 'range keys from in-memory index tree' (duration: 258.553274ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:18.204299Z","caller":"traceutil/trace.go:171","msg":"trace[615389111] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"505.356923ms","start":"2026-02-01T18:47:17.698926Z","end":"2026-02-01T18:47:18.204283Z","steps":["trace[615389111] 'process raft request' (duration: 505.256826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:18.204426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:17.698913Z","time spent":"505.432193ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:18.636444Z","caller":"traceutil/trace.go:171","msg":"trace[512173852] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"160.14273ms","start":"2026-02-01T18:47:18.476284Z","end":"2026-02-01T18:47:18.636427Z","steps":["trace[512173852] 'read index received' (duration: 67.462933ms)","trace[512173852] 'applied index is now lower than readState.Index' (duration: 92.679177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:18.636528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.228309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:18.636545Z","caller":"traceutil/trace.go:171","msg":"trace[1691280002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"160.29462ms","start":"2026-02-01T18:47:18.476245Z","end":"2026-02-01T18:47:18.63654Z","steps":["trace[1691280002] 'agreement among raft nodes before linearized reading' (duration: 160.247316ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:18.636678Z","caller":"traceutil/trace.go:171","msg":"trace[1089179222] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"932.720594ms","start":"2026-02-01T18:47:17.703929Z","end":"2026-02-01T18:47:18.63665Z","steps":["trace[1089179222] 'process raft request' (duration: 839.870688ms)","trace[1089179222] 'compare' (duration: 92.552173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:18.636765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:17.703924Z","time spent":"932.794034ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:18.9702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.291214ms","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-02-01T18:47:18.970421Z","caller":"traceutil/trace.go:171","msg":"trace[877363224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"111.541373ms","start":"2026-02-01T18:47:18.858867Z","end":"2026-02-01T18:47:18.970408Z","steps":["trace[877363224] 'range keys from in-memory index tree' (duration: 111.216954ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.078391Z","caller":"traceutil/trace.go:171","msg":"trace[32304588] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"124.748128ms","start":"2026-02-01T18:47:18.953629Z","end":"2026-02-01T18:47:19.078377Z","steps":["trace[32304588] 'process raft request' (duration: 124.664328ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.164101Z","caller":"traceutil/trace.go:171","msg":"trace[290019336] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"189.851144ms","start":"2026-02-01T18:47:18.974228Z","end":"2026-02-01T18:47:19.164079Z","steps":["trace[290019336] 'read index received' (duration: 104.268974ms)","trace[290019336] 'applied index is now lower than readState.Index' (duration: 85.58125ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:19.164528Z","caller":"traceutil/trace.go:171","msg":"trace[1663572148] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"190.008385ms","start":"2026-02-01T18:47:18.974502Z","end":"2026-02-01T18:47:19.164511Z","steps":["trace[1663572148] 'process raft request' (duration: 189.449473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:19.164702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.462828ms","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-02-01T18:47:19.164724Z","caller":"traceutil/trace.go:171","msg":"trace[2051472555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"190.521471ms","start":"2026-02-01T18:47:18.974197Z","end":"2026-02-01T18:47:19.164718Z","steps":["trace[2051472555] 'agreement among raft nodes before linearized reading' (duration: 190.468868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:19.164812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.975019ms","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-02-01T18:47:19.164828Z","caller":"traceutil/trace.go:171","msg":"trace[906339420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"183.993377ms","start":"2026-02-01T18:47:18.98083Z","end":"2026-02-01T18:47:19.164823Z","steps":["trace[906339420] 'agreement among raft nodes before linearized reading' (duration: 183.96508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:19.348007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.423803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:19.348192Z","caller":"traceutil/trace.go:171","msg":"trace[2106978957] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"180.79132ms","start":"2026-02-01T18:47:19.16738Z","end":"2026-02-01T18:47:19.348171Z","steps":["trace[2106978957] 'process raft request' (duration: 15.129797ms)","trace[2106978957] 'compare' (duration: 165.297638ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:19.348699Z","caller":"traceutil/trace.go:171","msg":"trace[762800961] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"181.104532ms","start":"2026-02-01T18:47:19.167584Z","end":"2026-02-01T18:47:19.348689Z","steps":["trace[762800961] 'process raft request' (duration: 180.54592ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.349357Z","caller":"traceutil/trace.go:171","msg":"trace[1013579496] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"178.011181ms","start":"2026-02-01T18:47:19.171334Z","end":"2026-02-01T18:47:19.349346Z","steps":["trace[1013579496] 'process raft request' (duration: 177.316646ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.349482Z","caller":"traceutil/trace.go:171","msg":"trace[127259670] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"172.159868ms","start":"2026-02-01T18:47:19.177297Z","end":"2026-02-01T18:47:19.349457Z","steps":["trace[127259670] 'process raft request' (duration: 171.741579ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.585773Z","caller":"traceutil/trace.go:171","msg":"trace[1145464839] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"226.258393ms","start":"2026-02-01T18:47:19.359493Z","end":"2026-02-01T18:47:19.585751Z","steps":["trace[1145464839] 'read index received' (duration: 226.088194ms)","trace[1145464839] 'applied index is now lower than readState.Index' (duration: 169.359µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:19.586018Z","caller":"traceutil/trace.go:171","msg":"trace[1974497281] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"226.786708ms","start":"2026-02-01T18:47:19.359182Z","end":"2026-02-01T18:47:19.585969Z","steps":["trace[1974497281] 'process raft request' (duration: 226.44588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:19.586065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.463521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:19.586121Z","caller":"traceutil/trace.go:171","msg":"trace[1833616429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"107.5483ms","start":"2026-02-01T18:47:19.478559Z","end":"2026-02-01T18:47:19.586107Z","steps":["trace[1833616429] 'agreement among raft nodes before linearized reading' (duration: 107.46587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:19.586255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.762771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T18:47:19.586274Z","caller":"traceutil/trace.go:171","msg":"trace[2096066121] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:408; }","duration":"226.829823ms","start":"2026-02-01T18:47:19.359439Z","end":"2026-02-01T18:47:19.586269Z","steps":["trace[2096066121] 'agreement among raft nodes before linearized reading' (duration: 226.791257ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:19.815286Z","caller":"traceutil/trace.go:171","msg":"trace[773097689] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"221.233928ms","start":"2026-02-01T18:47:19.594032Z","end":"2026-02-01T18:47:19.815266Z","steps":["trace[773097689] 'process raft request' (duration: 122.258838ms)","trace[773097689] 'compare' (duration: 98.89767ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:21.236731Z","caller":"traceutil/trace.go:171","msg":"trace[687211179] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"382.294956ms","start":"2026-02-01T18:47:20.854412Z","end":"2026-02-01T18:47:21.236707Z","steps":["trace[687211179] 'process raft request' (duration: 381.910011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.236875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:20.8544Z","time spent":"382.390775ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:21.703441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.86434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:21.703554Z","caller":"traceutil/trace.go:171","msg":"trace[558979696] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"461.117743ms","start":"2026-02-01T18:47:21.242395Z","end":"2026-02-01T18:47:21.703512Z","steps":["trace[558979696] 'read index received' (duration: 264.096041ms)","trace[558979696] 'applied index is now lower than readState.Index' (duration: 197.020602ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:21.703722Z","caller":"traceutil/trace.go:171","msg":"trace[978906673] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"461.495899ms","start":"2026-02-01T18:47:21.242212Z","end":"2026-02-01T18:47:21.703708Z","steps":["trace[978906673] 'process raft request' (duration: 264.290008ms)","trace[978906673] 'compare' (duration: 196.69913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:21.703778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:21.242198Z","time spent":"461.550602ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:21.703952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.553212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-01T18:47:21.70398Z","caller":"traceutil/trace.go:171","msg":"trace[1450119499] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:412; }","duration":"461.594957ms","start":"2026-02-01T18:47:21.242376Z","end":"2026-02-01T18:47:21.703971Z","steps":["trace[1450119499] 'agreement among raft nodes before linearized reading' (duration: 461.540213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.704006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:21.242371Z","time spent":"461.629052ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6919,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-01T18:47:21.704143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.738573ms","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-02-01T18:47:21.70418Z","caller":"traceutil/trace.go:171","msg":"trace[359896402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"346.76236ms","start":"2026-02-01T18:47:21.357398Z","end":"2026-02-01T18:47:21.70416Z","steps":["trace[359896402] 'agreement among raft nodes before linearized reading' (duration: 346.719805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.704206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:21.357341Z","time spent":"346.859679ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:21.704425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.813422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:21.704452Z","caller":"traceutil/trace.go:171","msg":"trace[1735451920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"228.871785ms","start":"2026-02-01T18:47:21.475572Z","end":"2026-02-01T18:47:21.704444Z","steps":["trace[1735451920] 'agreement among raft nodes before linearized reading' (duration: 228.83088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.704571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.09992ms","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-02-01T18:47:21.704598Z","caller":"traceutil/trace.go:171","msg":"trace[512615273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"347.140506ms","start":"2026-02-01T18:47:21.357448Z","end":"2026-02-01T18:47:21.704589Z","steps":["trace[512615273] 'agreement among raft nodes before linearized reading' (duration: 347.10077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:21.704621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:21.357443Z","time spent":"347.173082ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:21.92259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.606888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:21.922926Z","caller":"traceutil/trace.go:171","msg":"trace[1909455033] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"210.238773ms","start":"2026-02-01T18:47:21.712432Z","end":"2026-02-01T18:47:21.922671Z","steps":["trace[1909455033] 'process raft request' (duration: 97.473453ms)","trace[1909455033] 'compare' (duration: 112.446107ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:21.929741Z","caller":"traceutil/trace.go:171","msg":"trace[1803671599] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"216.546279ms","start":"2026-02-01T18:47:21.713172Z","end":"2026-02-01T18:47:21.929718Z","steps":["trace[1803671599] 'process raft request' (duration: 216.468929ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:21.930427Z","caller":"traceutil/trace.go:171","msg":"trace[227738306] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"217.779983ms","start":"2026-02-01T18:47:21.712629Z","end":"2026-02-01T18:47:21.930409Z","steps":["trace[227738306] 'process raft request' (duration: 216.826965ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:22.234387Z","caller":"traceutil/trace.go:171","msg":"trace[1573135662] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"206.812223ms","start":"2026-02-01T18:47:22.027564Z","end":"2026-02-01T18:47:22.234376Z","steps":["trace[1573135662] 'process raft request' (duration: 206.553573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:22.492013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.148753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:22.4921Z","caller":"traceutil/trace.go:171","msg":"trace[1587274419] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"253.83915ms","start":"2026-02-01T18:47:22.238237Z","end":"2026-02-01T18:47:22.492076Z","steps":["trace[1587274419] 'process raft request' (duration: 105.568111ms)","trace[1587274419] 'compare' (duration: 148.049175ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:22.819668Z","caller":"traceutil/trace.go:171","msg":"trace[2051594488] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"146.711569ms","start":"2026-02-01T18:47:22.672935Z","end":"2026-02-01T18:47:22.819646Z","steps":["trace[2051594488] 'process raft request' (duration: 90.240675ms)","trace[2051594488] 'compare' (duration: 56.378464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:24.43219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214008979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:24.932937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214008979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:25.433642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214008979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:25.934259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214008979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:25.976377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.263563447s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:47:25.976759Z","caller":"traceutil/trace.go:171","msg":"trace[1309605149] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"2.264034195s","start":"2026-02-01T18:47:23.712703Z","end":"2026-02-01T18:47:25.976737Z","steps":["trace[1309605149] 'process raft request' (duration: 2.263836267s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:25.976909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:23.712687Z","time spent":"2.264118206s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:26.247697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.514897ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-01T18:47:26.247764Z","caller":"traceutil/trace.go:171","msg":"trace[89027069] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"2.31606394s","start":"2026-02-01T18:47:23.931689Z","end":"2026-02-01T18:47:26.247753Z","steps":["trace[89027069] 'read index received' (duration: 2.045034989s)","trace[89027069] 'applied index is now lower than readState.Index' (duration: 271.027831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:26.247852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.316165808s","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-02-01T18:47:26.247873Z","caller":"traceutil/trace.go:171","msg":"trace[81355715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"2.316225002s","start":"2026-02-01T18:47:23.931641Z","end":"2026-02-01T18:47:26.247866Z","steps":["trace[81355715] 'agreement among raft nodes before linearized reading' (duration: 2.316167988s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:26.247897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:23.931622Z","time spent":"2.316269837s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:26.248079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.309184292s","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-02-01T18:47:26.248101Z","caller":"traceutil/trace.go:171","msg":"trace[1646038404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"2.30920592s","start":"2026-02-01T18:47:23.938889Z","end":"2026-02-01T18:47:26.248095Z","steps":["trace[1646038404] 'agreement among raft nodes before linearized reading' (duration: 2.309171004s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:26.248117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:23.938832Z","time spent":"2.309282302s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:26.248229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752077357s","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-02-01T18:47:26.24825Z","caller":"traceutil/trace.go:171","msg":"trace[33782927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"1.75214083s","start":"2026-02-01T18:47:24.496104Z","end":"2026-02-01T18:47:26.248245Z","steps":["trace[33782927] 'agreement among raft nodes before linearized reading' (duration: 1.752106833s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:26.248272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:24.496083Z","time spent":"1.752183876s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:47:26.248352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772359697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:26.248371Z","caller":"traceutil/trace.go:171","msg":"trace[1431007706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.772417791s","start":"2026-02-01T18:47:24.475948Z","end":"2026-02-01T18:47:26.248366Z","steps":["trace[1431007706] 'agreement among raft nodes before linearized reading' (duration: 1.772389134s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:26.248389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:24.475927Z","time spent":"1.772458426s","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:47:26.403192Z","caller":"traceutil/trace.go:171","msg":"trace[2003215483] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"146.343802ms","start":"2026-02-01T18:47:26.256827Z","end":"2026-02-01T18:47:26.403171Z","steps":["trace[2003215483] 'process raft request' (duration: 146.252531ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:26.403592Z","caller":"traceutil/trace.go:171","msg":"trace[1029511567] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"147.306498ms","start":"2026-02-01T18:47:26.256272Z","end":"2026-02-01T18:47:26.403578Z","steps":["trace[1029511567] 'process raft request' (duration: 103.165043ms)","trace[1029511567] 'compare' (duration: 43.428561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:28.741412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.789355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:28.741484Z","caller":"traceutil/trace.go:171","msg":"trace[1637115568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"264.916332ms","start":"2026-02-01T18:47:28.476551Z","end":"2026-02-01T18:47:28.741468Z","steps":["trace[1637115568] 'range keys from in-memory index tree' (duration: 264.706004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:28.741741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.39499ms","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-02-01T18:47:28.741778Z","caller":"traceutil/trace.go:171","msg":"trace[704966528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"334.455204ms","start":"2026-02-01T18:47:28.407313Z","end":"2026-02-01T18:47:28.741768Z","steps":["trace[704966528] 'range keys from in-memory index tree' (duration: 334.328866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:28.741808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:28.407299Z","time spent":"334.501929ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:28.741964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.096211ms","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-02-01T18:47:28.741998Z","caller":"traceutil/trace.go:171","msg":"trace[136115158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"334.137356ms","start":"2026-02-01T18:47:28.407852Z","end":"2026-02-01T18:47:28.74199Z","steps":["trace[136115158] 'range keys from in-memory index tree' (duration: 334.067674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:28.742029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:28.407848Z","time spent":"334.174693ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:31.311198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.213328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:47:31.311309Z","caller":"traceutil/trace.go:171","msg":"trace[19507698] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"433.997284ms","start":"2026-02-01T18:47:30.877293Z","end":"2026-02-01T18:47:31.31129Z","steps":["trace[19507698] 'compare' (duration: 428.012418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:31.31139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:30.877287Z","time spent":"434.060038ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:31.316516Z","caller":"traceutil/trace.go:171","msg":"trace[1846584121] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"434.044199ms","start":"2026-02-01T18:47:30.882459Z","end":"2026-02-01T18:47:31.316503Z","steps":["trace[1846584121] 'process raft request' (duration: 434.013862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:31.31659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:30.882455Z","time spent":"434.103134ms","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:31.316792Z","caller":"traceutil/trace.go:171","msg":"trace[638855001] transaction","detail":"{read_only:false; number_of_response:1; response_revision:449; }","duration":"435.166298ms","start":"2026-02-01T18:47:30.881613Z","end":"2026-02-01T18:47:31.31678Z","steps":["trace[638855001] 'process raft request' (duration: 434.762027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:31.316857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:30.881605Z","time spent":"435.219544ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3820,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:31.316981Z","caller":"traceutil/trace.go:171","msg":"trace[807996601] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"434.565849ms","start":"2026-02-01T18:47:30.882404Z","end":"2026-02-01T18:47:31.31697Z","steps":["trace[807996601] 'process raft request' (duration: 434.022361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:31.317032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:30.882398Z","time spent":"434.611044ms","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:31.331987Z","caller":"traceutil/trace.go:171","msg":"trace[2141592891] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"443.633857ms","start":"2026-02-01T18:47:30.888332Z","end":"2026-02-01T18:47:31.331966Z","steps":["trace[2141592891] 'process raft request' (duration: 434.350141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:31.332233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:30.888326Z","time spent":"443.715538ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:47:36.302633Z","caller":"traceutil/trace.go:171","msg":"trace[273639734] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"241.767506ms","start":"2026-02-01T18:47:36.06085Z","end":"2026-02-01T18:47:36.302617Z","steps":["trace[273639734] 'process raft request' (duration: 241.639377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:44.844614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:45.345568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:47:45.680933Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381113337s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:47:45.681175Z","caller":"traceutil/trace.go:171","msg":"trace[1673221323] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.381419974s","start":"2026-02-01T18:47:44.299741Z","end":"2026-02-01T18:47:45.681161Z","steps":["trace[1673221323] 'process raft request' (duration: 1.38133369s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:45.681254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.299728Z","time spent":"1.381478919s","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4256,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:46.475401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000207445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-01T18:47:46.47547Z","caller":"traceutil/trace.go:171","msg":"trace[1416573014] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000322747s","start":"2026-02-01T18:47:44.475131Z","end":"2026-02-01T18:47:46.475453Z","steps":["trace[1416573014] 'agreement among raft nodes before linearized reading' (duration: 2.000229474s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:46.475503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.475099Z","time spent":"2.000397151s","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:47:48.369883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.688849328s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:47:48.476844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000738062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-01T18:47:48.476934Z","caller":"traceutil/trace.go:171","msg":"trace[1338091711] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000856704s","start":"2026-02-01T18:47:46.476064Z","end":"2026-02-01T18:47:48.476921Z","steps":["trace[1338091711] 'agreement among raft nodes before linearized reading' (duration: 2.000751991s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:48.476993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:46.476053Z","time spent":"2.000933098s","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/01 18:47:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-01T18:47:49.258139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.057893ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-01T18:47:49.258487Z","caller":"traceutil/trace.go:171","msg":"trace[750517] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"779.443051ms","start":"2026-02-01T18:47:48.479027Z","end":"2026-02-01T18:47:49.25847Z","steps":["trace[750517] 'process raft request' (duration: 779.3097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:49.258577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:48.479009Z","time spent":"779.522246ms","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:47:49.258752Z","caller":"traceutil/trace.go:171","msg":"trace[836638201] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"4.914844634s","start":"2026-02-01T18:47:44.343658Z","end":"2026-02-01T18:47:49.258503Z","steps":["trace[836638201] 'read index received' (duration: 1.337829907s)","trace[836638201] 'applied index is now lower than readState.Index' (duration: 3.576954131s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:49.258964Z","caller":"traceutil/trace.go:171","msg":"trace[537780205] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"2.134997901s","start":"2026-02-01T18:47:47.123954Z","end":"2026-02-01T18:47:49.258952Z","steps":["trace[537780205] 'process raft request' (duration: 2.13427716s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:49.259486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:47.123938Z","time spent":"2.135420611s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:49.370597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.480347522s","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-02-01T18:47:49.370662Z","caller":"traceutil/trace.go:171","msg":"trace[1374097799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"4.480464683s","start":"2026-02-01T18:47:44.890181Z","end":"2026-02-01T18:47:49.370646Z","steps":["trace[1374097799] 'agreement among raft nodes before linearized reading' (duration: 4.369735147s)","trace[1374097799] 'range keys from in-memory index tree' (duration: 110.616654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.370695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.890161Z","time spent":"4.48052603s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:47:49.370931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.381212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T18:47:49.372301Z","caller":"traceutil/trace.go:171","msg":"trace[248435994] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:531; }","duration":"422.917978ms","start":"2026-02-01T18:47:48.949361Z","end":"2026-02-01T18:47:49.372279Z","steps":["trace[248435994] 'agreement among raft nodes before linearized reading' (duration: 310.530014ms)","trace[248435994] 'range keys from in-memory index tree' (duration: 110.710007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.371089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.945836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:47:49.371114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.530894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:47:49.371161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.462129425s","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-02-01T18:47:49.371159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.02749915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"rangeKeys: context cancelled: context deadline exceeded"} {"level":"warn","ts":"2026-02-01T18:47:49.371203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.009469535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"rangeKeys: context cancelled: context deadline exceeded"} {"level":"warn","ts":"2026-02-01T18:47:49.372629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:48.949343Z","time spent":"423.18789ms","remote":"127.0.0.1:33940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-02-01T18:47:49.373449Z","caller":"traceutil/trace.go:171","msg":"trace[417890651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.029810503s","start":"2026-02-01T18:47:44.343629Z","end":"2026-02-01T18:47:49.373439Z","steps":["trace[417890651] 'agreement among raft nodes before linearized reading' (duration: 4.916139207s)","trace[417890651] 'range keys from in-memory index tree' (duration: 111.358823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.37353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.343616Z","time spent":"5.029905797s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/01 18:47:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-01T18:47:49.373708Z","caller":"traceutil/trace.go:171","msg":"trace[1563394025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.011971386s","start":"2026-02-01T18:47:44.361728Z","end":"2026-02-01T18:47:49.373699Z","steps":["trace[1563394025] 'agreement among raft nodes before linearized reading' (duration: 4.898155919s)","trace[1563394025] 'range keys from in-memory index tree' (duration: 111.306347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.373818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.361665Z","time spent":"5.012070758s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/01 18:47:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-01T18:47:49.372698Z","caller":"traceutil/trace.go:171","msg":"trace[661752710] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:531; }","duration":"426.580625ms","start":"2026-02-01T18:47:48.946106Z","end":"2026-02-01T18:47:49.372686Z","steps":["trace[661752710] 'agreement among raft nodes before linearized reading' (duration: 313.791849ms)","trace[661752710] 'range keys from in-memory index tree' (duration: 111.167807ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:49.373176Z","caller":"traceutil/trace.go:171","msg":"trace[1096853148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"891.588695ms","start":"2026-02-01T18:47:48.481578Z","end":"2026-02-01T18:47:49.373167Z","steps":["trace[1096853148] 'agreement among raft nodes before linearized reading' (duration: 778.32606ms)","trace[1096853148] 'range keys from in-memory index tree' (duration: 111.198974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.374418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:48.946089Z","time spent":"427.780784ms","remote":"127.0.0.1:33994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-01T18:47:49.374613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:48.481549Z","time spent":"893.047306ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:47:49.373276Z","caller":"traceutil/trace.go:171","msg":"trace[1127081723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"4.46426937s","start":"2026-02-01T18:47:44.908999Z","end":"2026-02-01T18:47:49.373268Z","steps":["trace[1127081723] 'agreement among raft nodes before linearized reading' (duration: 4.350912112s)","trace[1127081723] 'range keys from in-memory index tree' (duration: 111.212783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.375679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:44.908984Z","time spent":"4.46663748s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-01T18:47:49.579068Z","caller":"traceutil/trace.go:171","msg":"trace[413641735] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"202.171457ms","start":"2026-02-01T18:47:49.376876Z","end":"2026-02-01T18:47:49.579047Z","steps":["trace[413641735] 'read index received' (duration: 202.050125ms)","trace[413641735] 'applied index is now lower than readState.Index' (duration: 120.172µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:49.579405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.507414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:49.579627Z","caller":"traceutil/trace.go:171","msg":"trace[1029879904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"202.792295ms","start":"2026-02-01T18:47:49.376811Z","end":"2026-02-01T18:47:49.579603Z","steps":["trace[1029879904] 'agreement among raft nodes before linearized reading' (duration: 202.538362ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:49.598345Z","caller":"traceutil/trace.go:171","msg":"trace[520516799] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"217.731664ms","start":"2026-02-01T18:47:49.380599Z","end":"2026-02-01T18:47:49.598331Z","steps":["trace[520516799] 'process raft request' (duration: 217.684807ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:49.598584Z","caller":"traceutil/trace.go:171","msg":"trace[1637656202] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"220.339088ms","start":"2026-02-01T18:47:49.378239Z","end":"2026-02-01T18:47:49.598578Z","steps":["trace[1637656202] 'process raft request' (duration: 219.842251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:49.598881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.238245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:49.599372Z","caller":"traceutil/trace.go:171","msg":"trace[129912982] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:533; }","duration":"117.75776ms","start":"2026-02-01T18:47:49.481605Z","end":"2026-02-01T18:47:49.599363Z","steps":["trace[129912982] 'agreement among raft nodes before linearized reading' (duration: 117.228136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:53.59467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.143648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:53.594721Z","caller":"traceutil/trace.go:171","msg":"trace[1880907816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"118.232632ms","start":"2026-02-01T18:47:53.476477Z","end":"2026-02-01T18:47:53.594709Z","steps":["trace[1880907816] 'range keys from in-memory index tree' (duration: 118.093531ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:53.888272Z","caller":"traceutil/trace.go:171","msg":"trace[93320808] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"276.866204ms","start":"2026-02-01T18:47:53.61139Z","end":"2026-02-01T18:47:53.888256Z","steps":["trace[93320808] 'process raft request' (duration: 276.738632ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:53.888586Z","caller":"traceutil/trace.go:171","msg":"trace[2032681509] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:584; }","duration":"276.516186ms","start":"2026-02-01T18:47:53.612064Z","end":"2026-02-01T18:47:53.888581Z","steps":["trace[2032681509] 'read index received' (duration: 276.513746ms)","trace[2032681509] 'applied index is now lower than readState.Index' (duration: 2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:53.888661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.584142ms","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-02-01T18:47:53.888676Z","caller":"traceutil/trace.go:171","msg":"trace[977483044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"276.617811ms","start":"2026-02-01T18:47:53.612053Z","end":"2026-02-01T18:47:53.888671Z","steps":["trace[977483044] 'agreement among raft nodes before linearized reading' (duration: 276.545755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:57.91417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009288,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:47:58.026787Z","caller":"traceutil/trace.go:171","msg":"trace[238729132] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"613.325993ms","start":"2026-02-01T18:47:57.413429Z","end":"2026-02-01T18:47:58.026755Z","steps":["trace[238729132] 'read index received' (duration: 613.177382ms)","trace[238729132] 'applied index is now lower than readState.Index' (duration: 148.011µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:58.026969Z","caller":"traceutil/trace.go:171","msg":"trace[1856033746] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"635.909188ms","start":"2026-02-01T18:47:57.391052Z","end":"2026-02-01T18:47:58.026961Z","steps":["trace[1856033746] 'process raft request' (duration: 635.602425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.027047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:57.391041Z","time spent":"635.947115ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:47:58.027167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.737841ms","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-02-01T18:47:58.027187Z","caller":"traceutil/trace.go:171","msg":"trace[1872482165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"613.75712ms","start":"2026-02-01T18:47:57.413424Z","end":"2026-02-01T18:47:58.027181Z","steps":["trace[1872482165] 'agreement among raft nodes before linearized reading' (duration: 613.719932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.027205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:57.413378Z","time spent":"613.823266ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:47:58.02734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.069164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8fjb\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T18:47:58.027358Z","caller":"traceutil/trace.go:171","msg":"trace[1544998656] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8fjb; range_end:; response_count:1; response_revision:573; }","duration":"608.111971ms","start":"2026-02-01T18:47:57.41924Z","end":"2026-02-01T18:47:58.027352Z","steps":["trace[1544998656] 'agreement among raft nodes before linearized reading' (duration: 608.033976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.027374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:57.419232Z","time spent":"608.138459ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16677,"request content":"key:\"/registry/pods/kube-system/cilium-l8fjb\" "} {"level":"warn","ts":"2026-02-01T18:47:58.027723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.066721ms","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-02-01T18:47:58.027751Z","caller":"traceutil/trace.go:171","msg":"trace[850747053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"129.112288ms","start":"2026-02-01T18:47:57.898631Z","end":"2026-02-01T18:47:58.027744Z","steps":["trace[850747053] 'agreement among raft nodes before linearized reading' (duration: 128.926549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.02792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.06747ms","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-02-01T18:47:58.02794Z","caller":"traceutil/trace.go:171","msg":"trace[1664262924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"132.139466ms","start":"2026-02-01T18:47:57.895795Z","end":"2026-02-01T18:47:58.027935Z","steps":["trace[1664262924] 'agreement among raft nodes before linearized reading' (duration: 132.100108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.028076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.193083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:58.028096Z","caller":"traceutil/trace.go:171","msg":"trace[1446438161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"551.24574ms","start":"2026-02-01T18:47:57.476844Z","end":"2026-02-01T18:47:58.028089Z","steps":["trace[1446438161] 'agreement among raft nodes before linearized reading' (duration: 551.214172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:58.028113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:57.476827Z","time spent":"551.282258ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:47:58.163463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.024461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:58.163561Z","caller":"traceutil/trace.go:171","msg":"trace[1379446961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"133.144014ms","start":"2026-02-01T18:47:58.030395Z","end":"2026-02-01T18:47:58.163539Z","steps":["trace[1379446961] 'range keys from in-memory index tree' (duration: 132.987533ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:58.337797Z","caller":"traceutil/trace.go:171","msg":"trace[560497434] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"299.558856ms","start":"2026-02-01T18:47:58.038222Z","end":"2026-02-01T18:47:58.337781Z","steps":["trace[560497434] 'process raft request' (duration: 299.459761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:59.450717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:48:00.160072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:48:00.660802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:48:01.161061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920878678214009292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:48:01.305078Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.966923636s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:48:01.305358Z","caller":"traceutil/trace.go:171","msg":"trace[2145407604] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"3.264620717s","start":"2026-02-01T18:47:58.040725Z","end":"2026-02-01T18:48:01.305346Z","steps":["trace[2145407604] 'process raft request' (duration: 3.264572769s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.305424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:58.040719Z","time spent":"3.264661814s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:01.305588Z","caller":"traceutil/trace.go:171","msg":"trace[1048875094] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"3.266957977s","start":"2026-02-01T18:47:58.038624Z","end":"2026-02-01T18:48:01.305582Z","steps":["trace[1048875094] 'process raft request' (duration: 3.266589867s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.305614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:58.038617Z","time spent":"3.266982685s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:01.306254Z","caller":"traceutil/trace.go:171","msg":"trace[691022398] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"3.267520089s","start":"2026-02-01T18:47:58.038727Z","end":"2026-02-01T18:48:01.306247Z","steps":["trace[691022398] 'process raft request' (duration: 3.266553559s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.306296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:58.038722Z","time spent":"3.267546447s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:01.338919Z","caller":"traceutil/trace.go:171","msg":"trace[1706602502] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"2.388963687s","start":"2026-02-01T18:47:58.949941Z","end":"2026-02-01T18:48:01.338904Z","steps":["trace[1706602502] 'read index received' (duration: 2.356576854s)","trace[1706602502] 'applied index is now lower than readState.Index' (duration: 32.386193ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:01.338985Z","caller":"traceutil/trace.go:171","msg":"trace[512033520] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"2.059138646s","start":"2026-02-01T18:47:59.279841Z","end":"2026-02-01T18:48:01.33898Z","steps":["trace[512033520] 'process raft request' (duration: 2.058976245s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.33904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:59.279825Z","time spent":"2.059172754s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:48:01.339103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.866012125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:01.339132Z","caller":"traceutil/trace.go:171","msg":"trace[57465248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"1.866062823s","start":"2026-02-01T18:47:59.473061Z","end":"2026-02-01T18:48:01.339124Z","steps":["trace[57465248] 'agreement among raft nodes before linearized reading' (duration: 1.866016385s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.339152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:59.473049Z","time spent":"1.866099351s","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:48:01.339224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.38928813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T18:48:01.339237Z","caller":"traceutil/trace.go:171","msg":"trace[142226966] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:578; }","duration":"2.389328728s","start":"2026-02-01T18:47:58.949905Z","end":"2026-02-01T18:48:01.339233Z","steps":["trace[142226966] 'agreement among raft nodes before linearized reading' (duration: 2.389272121s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.339249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:47:58.94989Z","time spent":"2.389356926s","remote":"127.0.0.1:33940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-02-01T18:48:01.339276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178084605s","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-02-01T18:48:01.339294Z","caller":"traceutil/trace.go:171","msg":"trace[1915642342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"1.178122563s","start":"2026-02-01T18:48:00.161166Z","end":"2026-02-01T18:48:01.339288Z","steps":["trace[1915642342] 'agreement among raft nodes before linearized reading' (duration: 1.178064626s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:01.339328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:00.161151Z","time spent":"1.178157601s","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:48:01.574151Z","caller":"traceutil/trace.go:171","msg":"trace[150731900] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"139.425451ms","start":"2026-02-01T18:48:01.434714Z","end":"2026-02-01T18:48:01.57414Z","steps":["trace[150731900] 'process raft request' (duration: 123.166878ms)","trace[150731900] 'compare' (duration: 16.099732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:01.575233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.277377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8fjb\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-01T18:48:01.575327Z","caller":"traceutil/trace.go:171","msg":"trace[1670701902] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8fjb; range_end:; response_count:1; response_revision:581; }","duration":"140.39964ms","start":"2026-02-01T18:48:01.434914Z","end":"2026-02-01T18:48:01.575313Z","steps":["trace[1670701902] 'agreement among raft nodes before linearized reading' (duration: 140.181632ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:01.574107Z","caller":"traceutil/trace.go:171","msg":"trace[1569019550] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"139.152165ms","start":"2026-02-01T18:48:01.434935Z","end":"2026-02-01T18:48:01.574087Z","steps":["trace[1569019550] 'read index received' (duration: 122.902711ms)","trace[1569019550] 'applied index is now lower than readState.Index' (duration: 16.248624ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:01.728455Z","caller":"traceutil/trace.go:171","msg":"trace[1806628180] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"143.522418ms","start":"2026-02-01T18:48:01.584917Z","end":"2026-02-01T18:48:01.728439Z","steps":["trace[1806628180] 'process raft request' (duration: 143.425774ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:03.565526Z","caller":"traceutil/trace.go:171","msg":"trace[1976725409] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"252.295002ms","start":"2026-02-01T18:48:03.313209Z","end":"2026-02-01T18:48:03.565504Z","steps":["trace[1976725409] 'process raft request' (duration: 252.12543ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:03.566952Z","caller":"traceutil/trace.go:171","msg":"trace[1562323701] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"252.811455ms","start":"2026-02-01T18:48:03.314125Z","end":"2026-02-01T18:48:03.566937Z","steps":["trace[1562323701] 'process raft request' (duration: 252.597086ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:03.567141Z","caller":"traceutil/trace.go:171","msg":"trace[1354830648] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"173.281242ms","start":"2026-02-01T18:48:03.393846Z","end":"2026-02-01T18:48:03.567127Z","steps":["trace[1354830648] 'read index received' (duration: 171.650193ms)","trace[1354830648] 'applied index is now lower than readState.Index' (duration: 1.630289ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:03.567193Z","caller":"traceutil/trace.go:171","msg":"trace[2147157772] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"251.211305ms","start":"2026-02-01T18:48:03.315971Z","end":"2026-02-01T18:48:03.567183Z","steps":["trace[2147157772] 'process raft request' (duration: 250.939818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:03.567574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.678852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-01T18:48:03.567625Z","caller":"traceutil/trace.go:171","msg":"trace[535364210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"173.817675ms","start":"2026-02-01T18:48:03.393784Z","end":"2026-02-01T18:48:03.567602Z","steps":["trace[535364210] 'agreement among raft nodes before linearized reading' (duration: 173.71283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:04.907115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.098681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:48:04.907181Z","caller":"traceutil/trace.go:171","msg":"trace[1586040983] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"423.654092ms","start":"2026-02-01T18:48:04.483517Z","end":"2026-02-01T18:48:04.907171Z","steps":["trace[1586040983] 'read index received' (duration: 49.638µs)","trace[1586040983] 'applied index is now lower than readState.Index' (duration: 423.603874ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:04.907218Z","caller":"traceutil/trace.go:171","msg":"trace[847691999] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"471.364013ms","start":"2026-02-01T18:48:04.435849Z","end":"2026-02-01T18:48:04.907213Z","steps":["trace[847691999] 'process raft request' (duration: 32.126069ms)","trace[847691999] 'compare' (duration: 439.026964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:04.907241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:04.435818Z","time spent":"471.41149ms","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:48:04.907405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.88506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8fjb\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-01T18:48:04.907418Z","caller":"traceutil/trace.go:171","msg":"trace[1986273131] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8fjb; range_end:; response_count:1; response_revision:594; }","duration":"423.924788ms","start":"2026-02-01T18:48:04.483489Z","end":"2026-02-01T18:48:04.907414Z","steps":["trace[1986273131] 'agreement among raft nodes before linearized reading' (duration: 423.843372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:04.907432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:04.483477Z","time spent":"423.952198ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17192,"request content":"key:\"/registry/pods/kube-system/cilium-l8fjb\" "} {"level":"warn","ts":"2026-02-01T18:48:05.255133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.415233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-01T18:48:05.255258Z","caller":"traceutil/trace.go:171","msg":"trace[1553193239] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"348.048767ms","start":"2026-02-01T18:48:04.907194Z","end":"2026-02-01T18:48:05.255243Z","steps":["trace[1553193239] 'read index received' (duration: 32.635924ms)","trace[1553193239] 'applied index is now lower than readState.Index' (duration: 315.411323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:05.255349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.706239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:05.255375Z","caller":"traceutil/trace.go:171","msg":"trace[67850315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"771.744347ms","start":"2026-02-01T18:48:04.483622Z","end":"2026-02-01T18:48:05.255366Z","steps":["trace[67850315] 'agreement among raft nodes before linearized reading' (duration: 771.663821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:05.255429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:04.483619Z","time spent":"771.802394ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:48:05.25566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.048699ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:05.255778Z","caller":"traceutil/trace.go:171","msg":"trace[2020259489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:594; }","duration":"100.176393ms","start":"2026-02-01T18:48:05.155583Z","end":"2026-02-01T18:48:05.255759Z","steps":["trace[2020259489] 'range keys from in-memory index tree' (duration: 100.03277ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:05.470277Z","caller":"traceutil/trace.go:171","msg":"trace[1731365467] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"212.886052ms","start":"2026-02-01T18:48:05.257378Z","end":"2026-02-01T18:48:05.470264Z","steps":["trace[1731365467] 'read index received' (duration: 212.796366ms)","trace[1731365467] 'applied index is now lower than readState.Index' (duration: 89.036µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:05.47035Z","caller":"traceutil/trace.go:171","msg":"trace[109979580] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"549.092977ms","start":"2026-02-01T18:48:04.921251Z","end":"2026-02-01T18:48:05.470344Z","steps":["trace[109979580] 'process raft request' (duration: 548.923385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:05.470405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:04.921232Z","time spent":"549.131095ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17318,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:48:05.470492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.114711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:05.470507Z","caller":"traceutil/trace.go:171","msg":"trace[119680788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"213.15704ms","start":"2026-02-01T18:48:05.257345Z","end":"2026-02-01T18:48:05.470502Z","steps":["trace[119680788] 'agreement among raft nodes before linearized reading' (duration: 213.131691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.027199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.263895ms","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-02-01T18:48:06.027252Z","caller":"traceutil/trace.go:171","msg":"trace[1660856479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"457.346612ms","start":"2026-02-01T18:48:05.569893Z","end":"2026-02-01T18:48:06.02724Z","steps":["trace[1660856479] 'range keys from in-memory index tree' (duration: 457.206548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.027275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:05.569879Z","time spent":"457.39055ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:48:06.027379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.580373ms","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-02-01T18:48:06.027393Z","caller":"traceutil/trace.go:171","msg":"trace[1242882671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"454.599903ms","start":"2026-02-01T18:48:05.572787Z","end":"2026-02-01T18:48:06.027387Z","steps":["trace[1242882671] 'range keys from in-memory index tree' (duration: 454.559595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.027404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:05.572785Z","time spent":"454.616602ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:48:06.027463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.705097ms","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-02-01T18:48:06.027474Z","caller":"traceutil/trace.go:171","msg":"trace[357981398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"454.726617ms","start":"2026-02-01T18:48:05.572744Z","end":"2026-02-01T18:48:06.027471Z","steps":["trace[357981398] 'range keys from in-memory index tree' (duration: 454.638821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.027485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:05.57274Z","time spent":"454.742936ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:48:06.027598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.2333ms","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-02-01T18:48:06.027616Z","caller":"traceutil/trace.go:171","msg":"trace[1946519366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"446.261418ms","start":"2026-02-01T18:48:05.581351Z","end":"2026-02-01T18:48:06.027613Z","steps":["trace[1946519366] 'range keys from in-memory index tree' (duration: 446.169932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.027632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:05.581347Z","time spent":"446.282478ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:48:06.027704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.333873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8fjb\" ","response":"range_response_count:1 size:17333"} {"level":"info","ts":"2026-02-01T18:48:06.027721Z","caller":"traceutil/trace.go:171","msg":"trace[592507176] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8fjb; range_end:; response_count:1; response_revision:595; }","duration":"219.373182ms","start":"2026-02-01T18:48:05.808344Z","end":"2026-02-01T18:48:06.027717Z","steps":["trace[592507176] 'range keys from in-memory index tree' (duration: 219.285855ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:06.035938Z","caller":"traceutil/trace.go:171","msg":"trace[1863954433] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"226.73754ms","start":"2026-02-01T18:48:05.809181Z","end":"2026-02-01T18:48:06.035919Z","steps":["trace[1863954433] 'process raft request' (duration: 226.632295ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:06.429279Z","caller":"traceutil/trace.go:171","msg":"trace[1494710663] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"368.72292ms","start":"2026-02-01T18:48:06.06052Z","end":"2026-02-01T18:48:06.429243Z","steps":["trace[1494710663] 'read index received' (duration: 46.15107ms)","trace[1494710663] 'applied index is now lower than readState.Index' (duration: 322.57098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:06.429394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.861753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:06.429423Z","caller":"traceutil/trace.go:171","msg":"trace[847353717] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:601; }","duration":"368.92731ms","start":"2026-02-01T18:48:06.060487Z","end":"2026-02-01T18:48:06.429414Z","steps":["trace[847353717] 'agreement among raft nodes before linearized reading' (duration: 368.846923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.429455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:06.060475Z","time spent":"368.970768ms","remote":"127.0.0.1:34238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":26,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:48:06.429677Z","caller":"traceutil/trace.go:171","msg":"trace[45643873] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"391.715323ms","start":"2026-02-01T18:48:06.037948Z","end":"2026-02-01T18:48:06.429664Z","steps":["trace[45643873] 'process raft request' (duration: 365.509889ms)","trace[45643873] 'compare' (duration: 25.21169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:06.429749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:06.037941Z","time spent":"391.761041ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:06.429935Z","caller":"traceutil/trace.go:171","msg":"trace[370114] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"388.791759ms","start":"2026-02-01T18:48:06.041134Z","end":"2026-02-01T18:48:06.429926Z","steps":["trace[370114] 'process raft request' (duration: 388.046573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:06.429982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:06.041127Z","time spent":"388.829506ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17400,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:48:07.592276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.806545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T18:48:07.592335Z","caller":"traceutil/trace.go:171","msg":"trace[187991776] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:606; }","duration":"540.892281ms","start":"2026-02-01T18:48:07.051428Z","end":"2026-02-01T18:48:07.59232Z","steps":["trace[187991776] 'range keys from in-memory index tree' (duration: 540.736108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:07.592364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:07.051415Z","time spent":"540.942959ms","remote":"127.0.0.1:34016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:48:07.592558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.643191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:07.59258Z","caller":"traceutil/trace.go:171","msg":"trace[508267564] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:606; }","duration":"304.66675ms","start":"2026-02-01T18:48:07.287905Z","end":"2026-02-01T18:48:07.592572Z","steps":["trace[508267564] 'count revisions from in-memory index tree' (duration: 304.577065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:07.592599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:07.28786Z","time spent":"304.734047ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:48:07.592788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.957173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:07.592807Z","caller":"traceutil/trace.go:171","msg":"trace[1832630696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"117.01098ms","start":"2026-02-01T18:48:07.47579Z","end":"2026-02-01T18:48:07.592801Z","steps":["trace[1832630696] 'range keys from in-memory index tree' (duration: 116.879296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:10.736267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.78563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:48:10.736355Z","caller":"traceutil/trace.go:171","msg":"trace[1984276298] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"152.724424ms","start":"2026-02-01T18:48:10.583616Z","end":"2026-02-01T18:48:10.73634Z","steps":["trace[1984276298] 'read index received' (duration: 50.716023ms)","trace[1984276298] 'applied index is now lower than readState.Index' (duration: 102.007431ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:10.736589Z","caller":"traceutil/trace.go:171","msg":"trace[1907054613] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"234.979156ms","start":"2026-02-01T18:48:10.501597Z","end":"2026-02-01T18:48:10.736576Z","steps":["trace[1907054613] 'process raft request' (duration: 132.818822ms)","trace[1907054613] 'compare' (duration: 101.698234ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:10.736754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.136516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T18:48:10.736781Z","caller":"traceutil/trace.go:171","msg":"trace[1494595217] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:663; }","duration":"153.188384ms","start":"2026-02-01T18:48:10.583584Z","end":"2026-02-01T18:48:10.736773Z","steps":["trace[1494595217] 'agreement among raft nodes before linearized reading' (duration: 153.129466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:10.736966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.27603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-01T18:48:10.736993Z","caller":"traceutil/trace.go:171","msg":"trace[1069649846] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"111.328898ms","start":"2026-02-01T18:48:10.625655Z","end":"2026-02-01T18:48:10.736984Z","steps":["trace[1069649846] 'agreement among raft nodes before linearized reading' (duration: 111.237742ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:11.123094Z","caller":"traceutil/trace.go:171","msg":"trace[742569599] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"148.779233ms","start":"2026-02-01T18:48:10.974279Z","end":"2026-02-01T18:48:11.123058Z","steps":["trace[742569599] 'process raft request' (duration: 84.190879ms)","trace[742569599] 'compare' (duration: 64.380552ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:11.123418Z","caller":"traceutil/trace.go:171","msg":"trace[1255565313] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"149.045941ms","start":"2026-02-01T18:48:10.974357Z","end":"2026-02-01T18:48:11.123403Z","steps":["trace[1255565313] 'process raft request' (duration: 148.618059ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:14.952517Z","caller":"traceutil/trace.go:171","msg":"trace[1378063488] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"117.480237ms","start":"2026-02-01T18:48:14.835009Z","end":"2026-02-01T18:48:14.952489Z","steps":["trace[1378063488] 'process raft request' (duration: 89.516294ms)","trace[1378063488] 'compare' (duration: 27.743262ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:14.952628Z","caller":"traceutil/trace.go:171","msg":"trace[822355437] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"108.276148ms","start":"2026-02-01T18:48:14.844332Z","end":"2026-02-01T18:48:14.952608Z","steps":["trace[822355437] 'process raft request' (duration: 108.016338ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.039358Z","caller":"traceutil/trace.go:171","msg":"trace[474795080] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"101.759063ms","start":"2026-02-01T18:48:16.937578Z","end":"2026-02-01T18:48:17.039337Z","steps":["trace[474795080] 'process raft request' (duration: 101.634337ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.056907Z","caller":"traceutil/trace.go:171","msg":"trace[1988591198] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"112.613666ms","start":"2026-02-01T18:48:16.944279Z","end":"2026-02-01T18:48:17.056892Z","steps":["trace[1988591198] 'process raft request' (duration: 112.569127ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.05723Z","caller":"traceutil/trace.go:171","msg":"trace[801290624] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"118.638867ms","start":"2026-02-01T18:48:16.938581Z","end":"2026-02-01T18:48:17.05722Z","steps":["trace[801290624] 'process raft request' (duration: 118.187453ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.176223Z","caller":"traceutil/trace.go:171","msg":"trace[175929242] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"105.514471ms","start":"2026-02-01T18:48:17.070687Z","end":"2026-02-01T18:48:17.176201Z","steps":["trace[175929242] 'process raft request' (duration: 105.338858ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.176537Z","caller":"traceutil/trace.go:171","msg":"trace[2133058981] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"105.525851ms","start":"2026-02-01T18:48:17.070995Z","end":"2026-02-01T18:48:17.17652Z","steps":["trace[2133058981] 'process raft request' (duration: 105.159834ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.471254Z","caller":"traceutil/trace.go:171","msg":"trace[1908385318] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"157.091948ms","start":"2026-02-01T18:48:17.314127Z","end":"2026-02-01T18:48:17.471219Z","steps":["trace[1908385318] 'read index received' (duration: 86.529672ms)","trace[1908385318] 'applied index is now lower than readState.Index' (duration: 70.561386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:17.471591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.261126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:48:17.471774Z","caller":"traceutil/trace.go:171","msg":"trace[1370479287] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:750; }","duration":"177.379731ms","start":"2026-02-01T18:48:17.294246Z","end":"2026-02-01T18:48:17.471626Z","steps":["trace[1370479287] 'agreement among raft nodes before linearized reading' (duration: 177.064372ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.477129Z","caller":"traceutil/trace.go:171","msg":"trace[1202602271] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"173.710861ms","start":"2026-02-01T18:48:17.298619Z","end":"2026-02-01T18:48:17.47233Z","steps":["trace[1202602271] 'process raft request' (duration: 102.107771ms)","trace[1202602271] 'compare' (duration: 70.360344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:17.477451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.916887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3881"} {"level":"info","ts":"2026-02-01T18:48:17.477545Z","caller":"traceutil/trace.go:171","msg":"trace[411191044] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:750; }","duration":"179.034483ms","start":"2026-02-01T18:48:17.298503Z","end":"2026-02-01T18:48:17.477537Z","steps":["trace[411191044] 'agreement among raft nodes before linearized reading' (duration: 178.856129ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.512293Z","caller":"traceutil/trace.go:171","msg":"trace[2075523145] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"148.54403ms","start":"2026-02-01T18:48:17.363738Z","end":"2026-02-01T18:48:17.512282Z","steps":["trace[2075523145] 'process raft request' (duration: 148.518871ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.512696Z","caller":"traceutil/trace.go:171","msg":"trace[1681559275] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"197.168462ms","start":"2026-02-01T18:48:17.315509Z","end":"2026-02-01T18:48:17.512678Z","steps":["trace[1681559275] 'process raft request' (duration: 196.67353ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.645866Z","caller":"traceutil/trace.go:171","msg":"trace[474038788] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"123.94234ms","start":"2026-02-01T18:48:17.521868Z","end":"2026-02-01T18:48:17.64581Z","steps":["trace[474038788] 'process raft request' (duration: 51.129504ms)","trace[474038788] 'compare' (duration: 72.499587ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:17.651Z","caller":"traceutil/trace.go:171","msg":"trace[1044012936] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"128.986421ms","start":"2026-02-01T18:48:17.521996Z","end":"2026-02-01T18:48:17.650983Z","steps":["trace[1044012936] 'process raft request' (duration: 123.741187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:17.65371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.090556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:48:17.653885Z","caller":"traceutil/trace.go:171","msg":"trace[355252779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"135.182032ms","start":"2026-02-01T18:48:17.518579Z","end":"2026-02-01T18:48:17.653761Z","steps":["trace[355252779] 'agreement among raft nodes before linearized reading' (duration: 135.081706ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:17.653617Z","caller":"traceutil/trace.go:171","msg":"trace[1695738978] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"107.357566ms","start":"2026-02-01T18:48:17.541887Z","end":"2026-02-01T18:48:17.649244Z","steps":["trace[1695738978] 'read index received' (duration: 31.089881ms)","trace[1695738978] 'applied index is now lower than readState.Index' (duration: 76.266455ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:17.813134Z","caller":"traceutil/trace.go:171","msg":"trace[784772142] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"154.664663ms","start":"2026-02-01T18:48:17.658446Z","end":"2026-02-01T18:48:17.813111Z","steps":["trace[784772142] 'process raft request' (duration: 126.552016ms)","trace[784772142] 'compare' (duration: 27.916674ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:18.022831Z","caller":"traceutil/trace.go:171","msg":"trace[1968129545] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"126.791966ms","start":"2026-02-01T18:48:17.896026Z","end":"2026-02-01T18:48:18.022818Z","steps":["trace[1968129545] 'read index received' (duration: 35.044271ms)","trace[1968129545] 'applied index is now lower than readState.Index' (duration: 91.747195ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:18.023029Z","caller":"traceutil/trace.go:171","msg":"trace[1562575324] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"199.926312ms","start":"2026-02-01T18:48:17.823094Z","end":"2026-02-01T18:48:18.02302Z","steps":["trace[1562575324] 'process raft request' (duration: 108.012853ms)","trace[1562575324] 'compare' (duration: 91.60817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:18.023216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.175753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-vqxwc\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-02-01T18:48:18.023245Z","caller":"traceutil/trace.go:171","msg":"trace[1585379687] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-vqxwc; range_end:; response_count:1; response_revision:757; }","duration":"127.237772ms","start":"2026-02-01T18:48:17.896Z","end":"2026-02-01T18:48:18.023238Z","steps":["trace[1585379687] 'agreement among raft nodes before linearized reading' (duration: 127.143635ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.252373Z","caller":"traceutil/trace.go:171","msg":"trace[792670188] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"212.987507ms","start":"2026-02-01T18:48:18.039369Z","end":"2026-02-01T18:48:18.252357Z","steps":["trace[792670188] 'process raft request' (duration: 212.877751ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.252695Z","caller":"traceutil/trace.go:171","msg":"trace[1236846715] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:786; }","duration":"150.4406ms","start":"2026-02-01T18:48:18.102249Z","end":"2026-02-01T18:48:18.252689Z","steps":["trace[1236846715] 'read index received' (duration: 150.43826ms)","trace[1236846715] 'applied index is now lower than readState.Index' (duration: 1.97µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:18.252774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.514038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-vqxwc\" ","response":"range_response_count:1 size:5002"} {"level":"info","ts":"2026-02-01T18:48:18.252789Z","caller":"traceutil/trace.go:171","msg":"trace[2886052] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-vqxwc; range_end:; response_count:1; response_revision:759; }","duration":"150.564646ms","start":"2026-02-01T18:48:18.10222Z","end":"2026-02-01T18:48:18.252784Z","steps":["trace[2886052] 'agreement among raft nodes before linearized reading' (duration: 150.489559ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.283918Z","caller":"traceutil/trace.go:171","msg":"trace[1886906444] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"180.133089ms","start":"2026-02-01T18:48:18.103767Z","end":"2026-02-01T18:48:18.2839Z","steps":["trace[1886906444] 'process raft request' (duration: 180.027303ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.458242Z","caller":"traceutil/trace.go:171","msg":"trace[1031396211] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"113.55417ms","start":"2026-02-01T18:48:18.344671Z","end":"2026-02-01T18:48:18.458225Z","steps":["trace[1031396211] 'read index received' (duration: 55.45746ms)","trace[1031396211] 'applied index is now lower than readState.Index' (duration: 58.09458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:18.458532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.85661ms","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-02-01T18:48:18.458563Z","caller":"traceutil/trace.go:171","msg":"trace[801766057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"113.897249ms","start":"2026-02-01T18:48:18.344658Z","end":"2026-02-01T18:48:18.458555Z","steps":["trace[801766057] 'agreement among raft nodes before linearized reading' (duration: 113.754324ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.458696Z","caller":"traceutil/trace.go:171","msg":"trace[447205650] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"200.654802ms","start":"2026-02-01T18:48:18.258033Z","end":"2026-02-01T18:48:18.458688Z","steps":["trace[447205650] 'process raft request' (duration: 142.158676ms)","trace[447205650] 'compare' (duration: 57.80604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:18.459016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.498911ms","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-02-01T18:48:18.459042Z","caller":"traceutil/trace.go:171","msg":"trace[76541168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"102.555539ms","start":"2026-02-01T18:48:18.35648Z","end":"2026-02-01T18:48:18.459036Z","steps":["trace[76541168] 'agreement among raft nodes before linearized reading' (duration: 102.491302ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.686888Z","caller":"traceutil/trace.go:171","msg":"trace[1337661815] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:788; }","duration":"141.819307ms","start":"2026-02-01T18:48:18.54504Z","end":"2026-02-01T18:48:18.686859Z","steps":["trace[1337661815] 'read index received' (duration: 12.20002ms)","trace[1337661815] 'applied index is now lower than readState.Index' (duration: 129.617868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:18.687119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.059119ms","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-02-01T18:48:18.687161Z","caller":"traceutil/trace.go:171","msg":"trace[245622111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"142.139376ms","start":"2026-02-01T18:48:18.54501Z","end":"2026-02-01T18:48:18.68715Z","steps":["trace[245622111] 'agreement among raft nodes before linearized reading' (duration: 141.950753ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.687435Z","caller":"traceutil/trace.go:171","msg":"trace[1394838745] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"220.604015ms","start":"2026-02-01T18:48:18.466816Z","end":"2026-02-01T18:48:18.687419Z","steps":["trace[1394838745] 'process raft request' (duration: 219.925729ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.687674Z","caller":"traceutil/trace.go:171","msg":"trace[1446993722] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"220.849047ms","start":"2026-02-01T18:48:18.466785Z","end":"2026-02-01T18:48:18.687634Z","steps":["trace[1446993722] 'process raft request' (duration: 219.821302ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:18.689175Z","caller":"traceutil/trace.go:171","msg":"trace[882300756] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"220.086122ms","start":"2026-02-01T18:48:18.469073Z","end":"2026-02-01T18:48:18.689159Z","steps":["trace[882300756] 'process raft request' (duration: 217.712904ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:19.042954Z","caller":"traceutil/trace.go:171","msg":"trace[1948829094] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"143.808773ms","start":"2026-02-01T18:48:18.899127Z","end":"2026-02-01T18:48:19.042936Z","steps":["trace[1948829094] 'process raft request' (duration: 143.715067ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:19.043342Z","caller":"traceutil/trace.go:171","msg":"trace[1529121652] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:794; }","duration":"143.997158ms","start":"2026-02-01T18:48:18.899337Z","end":"2026-02-01T18:48:19.043335Z","steps":["trace[1529121652] 'read index received' (duration: 143.993508ms)","trace[1529121652] 'applied index is now lower than readState.Index' (duration: 2.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:19.044141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.789032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-vqxwc\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-01T18:48:19.044171Z","caller":"traceutil/trace.go:171","msg":"trace[458364897] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-vqxwc; range_end:; response_count:1; response_revision:767; }","duration":"144.842829ms","start":"2026-02-01T18:48:18.89932Z","end":"2026-02-01T18:48:19.044163Z","steps":["trace[458364897] 'agreement among raft nodes before linearized reading' (duration: 144.088155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:48:21.0439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.12008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:48:21.043992Z","caller":"traceutil/trace.go:171","msg":"trace[69711860] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"297.888229ms","start":"2026-02-01T18:48:20.746088Z","end":"2026-02-01T18:48:21.043977Z","steps":["trace[69711860] 'read index received' (duration: 49.645899ms)","trace[69711860] 'applied index is now lower than readState.Index' (duration: 248.24126ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:48:21.044072Z","caller":"traceutil/trace.go:171","msg":"trace[978513385] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"342.059068ms","start":"2026-02-01T18:48:20.701994Z","end":"2026-02-01T18:48:21.044053Z","steps":["trace[978513385] 'process raft request' (duration: 235.675996ms)","trace[978513385] 'compare' (duration: 105.986664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:48:21.044136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.042254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-01T18:48:21.044159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:48:20.701989Z","time spent":"342.122126ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:48:21.044164Z","caller":"traceutil/trace.go:171","msg":"trace[889125191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"298.102522ms","start":"2026-02-01T18:48:20.746052Z","end":"2026-02-01T18:48:21.044155Z","steps":["trace[889125191] 'agreement among raft nodes before linearized reading' (duration: 297.965627ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:24.29028Z","caller":"traceutil/trace.go:171","msg":"trace[1956848649] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"109.102569ms","start":"2026-02-01T18:48:24.181159Z","end":"2026-02-01T18:48:24.290262Z","steps":["trace[1956848649] 'process raft request' (duration: 108.988483ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:44.922935Z","caller":"traceutil/trace.go:171","msg":"trace[426387420] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"100.557409ms","start":"2026-02-01T18:48:44.822362Z","end":"2026-02-01T18:48:44.92292Z","steps":["trace[426387420] 'process raft request' (duration: 100.46918ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:48:55.300838Z","caller":"traceutil/trace.go:171","msg":"trace[912826351] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"125.426003ms","start":"2026-02-01T18:48:55.175394Z","end":"2026-02-01T18:48:55.30082Z","steps":["trace[912826351] 'process raft request' (duration: 125.318044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:27.802531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.932935ms","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-02-01T18:49:27.802596Z","caller":"traceutil/trace.go:171","msg":"trace[595133344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"139.064675ms","start":"2026-02-01T18:49:27.66352Z","end":"2026-02-01T18:49:27.802585Z","steps":["trace[595133344] 'range keys from in-memory index tree' (duration: 138.808545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:28.418492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.735661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:28.418566Z","caller":"traceutil/trace.go:171","msg":"trace[76242893] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1008; }","duration":"119.852342ms","start":"2026-02-01T18:49:28.298697Z","end":"2026-02-01T18:49:28.418549Z","steps":["trace[76242893] 'range keys from in-memory index tree' (duration: 119.665571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:28.419337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.50623ms","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-02-01T18:49:28.419518Z","caller":"traceutil/trace.go:171","msg":"trace[1984818868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"474.58099ms","start":"2026-02-01T18:49:27.944791Z","end":"2026-02-01T18:49:28.419372Z","steps":["trace[1984818868] 'range keys from in-memory index tree' (duration: 474.38958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:28.419588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:49:27.944777Z","time spent":"474.798271ms","remote":"127.0.0.1:34154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:49:28.587426Z","caller":"traceutil/trace.go:171","msg":"trace[377749662] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"162.5067ms","start":"2026-02-01T18:49:28.424897Z","end":"2026-02-01T18:49:28.587404Z","steps":["trace[377749662] 'process raft request' (duration: 162.336389ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:28.587719Z","caller":"traceutil/trace.go:171","msg":"trace[286350315] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1052; }","duration":"114.898942ms","start":"2026-02-01T18:49:28.472808Z","end":"2026-02-01T18:49:28.587707Z","steps":["trace[286350315] 'read index received' (duration: 114.894902ms)","trace[286350315] 'applied index is now lower than readState.Index' (duration: 3.32µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:28.587805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.971052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:28.587834Z","caller":"traceutil/trace.go:171","msg":"trace[1495290249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"115.020232ms","start":"2026-02-01T18:49:28.472804Z","end":"2026-02-01T18:49:28.587824Z","steps":["trace[1495290249] 'agreement among raft nodes before linearized reading' (duration: 114.938252ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:29.071692Z","caller":"traceutil/trace.go:171","msg":"trace[1823569028] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"114.490331ms","start":"2026-02-01T18:49:28.957178Z","end":"2026-02-01T18:49:29.071669Z","steps":["trace[1823569028] 'read index received' (duration: 114.29664ms)","trace[1823569028] 'applied index is now lower than readState.Index' (duration: 192.451µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:49:29.07222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.012143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T18:49:29.072289Z","caller":"traceutil/trace.go:171","msg":"trace[2127811588] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1010; }","duration":"115.121303ms","start":"2026-02-01T18:49:28.957147Z","end":"2026-02-01T18:49:29.072268Z","steps":["trace[2127811588] 'agreement among raft nodes before linearized reading' (duration: 114.611351ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:49:29.07272Z","caller":"traceutil/trace.go:171","msg":"trace[1531856457] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"143.517115ms","start":"2026-02-01T18:49:28.929183Z","end":"2026-02-01T18:49:29.0727Z","steps":["trace[1531856457] 'process raft request' (duration: 142.31319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:29.252838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.836127ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"warn","ts":"2026-02-01T18:49:29.535974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.511303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:49:29.537023Z","caller":"traceutil/trace.go:171","msg":"trace[1598266108] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"232.598915ms","start":"2026-02-01T18:49:29.304401Z","end":"2026-02-01T18:49:29.536999Z","steps":["trace[1598266108] 'process raft request' (duration: 81.986357ms)","trace[1598266108] 'compare' (duration: 149.409203ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:29.799675Z","caller":"traceutil/trace.go:171","msg":"trace[1973922199] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"162.81114ms","start":"2026-02-01T18:49:29.636841Z","end":"2026-02-01T18:49:29.799652Z","steps":["trace[1973922199] 'read index received' (duration: 162.671629ms)","trace[1973922199] 'applied index is now lower than readState.Index' (duration: 138.401µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:49:29.799924Z","caller":"traceutil/trace.go:171","msg":"trace[1206538660] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"257.481291ms","start":"2026-02-01T18:49:29.542421Z","end":"2026-02-01T18:49:29.799902Z","steps":["trace[1206538660] 'process raft request' (duration: 257.038829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:29.800008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.158851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:49:29.800653Z","caller":"traceutil/trace.go:171","msg":"trace[714707668] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1013; }","duration":"163.862594ms","start":"2026-02-01T18:49:29.636782Z","end":"2026-02-01T18:49:29.800645Z","steps":["trace[714707668] 'agreement among raft nodes before linearized reading' (duration: 163.185431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:29.800939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.828685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:49:29.800965Z","caller":"traceutil/trace.go:171","msg":"trace[1031032343] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1013; }","duration":"128.877765ms","start":"2026-02-01T18:49:29.672082Z","end":"2026-02-01T18:49:29.800959Z","steps":["trace[1031032343] 'agreement among raft nodes before linearized reading' (duration: 128.836005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:49:29.991196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.155747ms","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-02-01T18:49:29.991504Z","caller":"traceutil/trace.go:171","msg":"trace[505769677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"122.517929ms","start":"2026-02-01T18:49:29.868967Z","end":"2026-02-01T18:49:29.991484Z","steps":["trace[505769677] 'range keys from in-memory index tree' (duration: 121.976686ms)"],"step_count":1}