{"level":"warn","ts":"2026-02-19T00:37:52.013126Z","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-19T00:37:52.013992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67: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.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-19T00:37:52.016629Z","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-19T00:37:52.016647Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-19T00:37:52.016713Z","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-19T00:37:52.017503Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-02-19T00:37:52.017665Z","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.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-19T00:37:52.024255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.147308ms"} {"level":"info","ts":"2026-02-19T00:37:52.034666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-02-19T00:37:52.034736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T00:37:52.034771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-02-19T00:37:52.034813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T00:37:52.034827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-02-19T00:37:52.034897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-02-19T00:37:52.048479Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T00:37:52.053171Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T00:37:52.066548Z","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-19T00:37:52.08364Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T00:37:52.084364Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T00:37:52.08457Z","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-19T00:37:52.084612Z","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-19T00:37:52.084632Z","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-19T00:37:52.089048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-02-19T00:37:52.095079Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-19T00:37:52.095457Z","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-19T00:37:52.095563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-19T00:37:52.095608Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-19T00:37:52.100624Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T00:37:52.100692Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T00:37:52.535758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T00:37:52.535796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T00:37:52.53582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-02-19T00:37:52.535832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-02-19T00:37:52.535837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-19T00:37:52.535845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-02-19T00:37:52.535852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-19T00:37:52.538008Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T00:37:52.53828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T00:37:52.538539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T00:37:52.538948Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T00:37:52.539061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T00:37:52.53907Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T00:37:52.545628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T00:37:52.56313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-02-19T00:37:52.564133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T00:37:52.566567Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T00:37:52.566645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T00:37:54.589025Z","caller":"traceutil/trace.go:171","msg":"trace[2029693240] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:71; }","duration":"189.738529ms","start":"2026-02-19T00:37:54.399264Z","end":"2026-02-19T00:37:54.589002Z","steps":["trace[2029693240] 'read index received' (duration: 121.961ms)","trace[2029693240] 'applied index is now lower than readState.Index' (duration: 67.776429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:54.589387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.076646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/service-accounts\" ","response":"range_response_count:1 size:1100"} {"level":"info","ts":"2026-02-19T00:37:54.5895Z","caller":"traceutil/trace.go:171","msg":"trace[898894896] range","detail":"{range_begin:/registry/flowschemas/service-accounts; range_end:; response_count:1; response_revision:68; }","duration":"190.245505ms","start":"2026-02-19T00:37:54.39924Z","end":"2026-02-19T00:37:54.589486Z","steps":["trace[898894896] 'agreement among raft nodes before linearized reading' (duration: 189.960847ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:54.589688Z","caller":"traceutil/trace.go:171","msg":"trace[157383931] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"277.359692ms","start":"2026-02-19T00:37:54.312297Z","end":"2026-02-19T00:37:54.589656Z","steps":["trace[157383931] 'process raft request' (duration: 208.965408ms)","trace[157383931] 'compare' (duration: 67.510762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:54.911624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.100278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:54.911757Z","caller":"traceutil/trace.go:171","msg":"trace[775719886] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"253.510344ms","start":"2026-02-19T00:37:54.658233Z","end":"2026-02-19T00:37:54.911743Z","steps":["trace[775719886] 'process raft request' (duration: 42.774091ms)","trace[775719886] 'compare' (duration: 209.985739ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:54.912559Z","caller":"traceutil/trace.go:171","msg":"trace[1169752707] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"240.217691ms","start":"2026-02-19T00:37:54.67233Z","end":"2026-02-19T00:37:54.912548Z","steps":["trace[1169752707] 'process raft request' (duration: 240.012493ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:55.269175Z","caller":"traceutil/trace.go:171","msg":"trace[1795971535] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"182.772815ms","start":"2026-02-19T00:37:55.086357Z","end":"2026-02-19T00:37:55.269129Z","steps":["trace[1795971535] 'read index received' (duration: 87.362189ms)","trace[1795971535] 'applied index is now lower than readState.Index' (duration: 95.409496ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:55.269492Z","caller":"traceutil/trace.go:171","msg":"trace[865096720] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"352.083727ms","start":"2026-02-19T00:37:54.917394Z","end":"2026-02-19T00:37:55.269478Z","steps":["trace[865096720] 'process raft request' (duration: 256.391394ms)","trace[865096720] 'compare' (duration: 95.249797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:55.269833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.427328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:55.269905Z","caller":"traceutil/trace.go:171","msg":"trace[1813994452] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:74; }","duration":"182.527597ms","start":"2026-02-19T00:37:55.087365Z","end":"2026-02-19T00:37:55.269892Z","steps":["trace[1813994452] 'agreement among raft nodes before linearized reading' (duration: 182.424798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:55.270092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.733355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:55.270129Z","caller":"traceutil/trace.go:171","msg":"trace[1519749745] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:74; }","duration":"183.809895ms","start":"2026-02-19T00:37:55.086313Z","end":"2026-02-19T00:37:55.270123Z","steps":["trace[1519749745] 'agreement among raft nodes before linearized reading' (duration: 183.760835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:55.270554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:54.917387Z","time spent":"352.267025ms","remote":"127.0.0.1:40894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:37:55.407779Z","caller":"traceutil/trace.go:171","msg":"trace[1548576376] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"133.054186ms","start":"2026-02-19T00:37:55.274707Z","end":"2026-02-19T00:37:55.407761Z","steps":["trace[1548576376] 'process raft request' (duration: 118.429238ms)","trace[1548576376] 'compare' (duration: 14.252201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:55.408664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.194035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:55.408802Z","caller":"traceutil/trace.go:171","msg":"trace[461250643] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:76; }","duration":"134.362123ms","start":"2026-02-19T00:37:55.274421Z","end":"2026-02-19T00:37:55.408784Z","steps":["trace[461250643] 'agreement among raft nodes before linearized reading' (duration: 134.181555ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:55.99983Z","caller":"traceutil/trace.go:171","msg":"trace[303683184] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"246.802665ms","start":"2026-02-19T00:37:55.752991Z","end":"2026-02-19T00:37:55.999794Z","steps":["trace[303683184] 'process raft request' (duration: 156.003027ms)","trace[303683184] 'compare' (duration: 90.323662ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:56.001761Z","caller":"traceutil/trace.go:171","msg":"trace[1062373365] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"220.842679ms","start":"2026-02-19T00:37:55.780909Z","end":"2026-02-19T00:37:56.001751Z","steps":["trace[1062373365] 'process raft request' (duration: 218.833788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:56.002724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.675772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:56.003974Z","caller":"traceutil/trace.go:171","msg":"trace[2140956921] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:0; response_revision:88; }","duration":"222.99421ms","start":"2026-02-19T00:37:55.780965Z","end":"2026-02-19T00:37:56.003959Z","steps":["trace[2140956921] 'agreement among raft nodes before linearized reading' (duration: 221.455374ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:56.001707Z","caller":"traceutil/trace.go:171","msg":"trace[1399499070] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"220.689671ms","start":"2026-02-19T00:37:55.781003Z","end":"2026-02-19T00:37:56.001692Z","steps":["trace[1399499070] 'read index received' (duration: 128.00122ms)","trace[1399499070] 'applied index is now lower than readState.Index' (duration: 92.687501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:56.336657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.328991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:56.33677Z","caller":"traceutil/trace.go:171","msg":"trace[22833896] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:96; }","duration":"251.889479ms","start":"2026-02-19T00:37:56.084861Z","end":"2026-02-19T00:37:56.336751Z","steps":["trace[22833896] 'read index received' (duration: 18.36729ms)","trace[22833896] 'applied index is now lower than readState.Index' (duration: 233.520739ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:56.336866Z","caller":"traceutil/trace.go:171","msg":"trace[913183702] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"253.487564ms","start":"2026-02-19T00:37:56.083364Z","end":"2026-02-19T00:37:56.336852Z","steps":["trace[913183702] 'process raft request' (duration: 19.887056ms)","trace[913183702] 'compare' (duration: 233.198242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:56.337263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.720846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-19T00:37:56.33741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.557923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18957ed276a58d37\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-19T00:37:56.337455Z","caller":"traceutil/trace.go:171","msg":"trace[926328185] range","detail":"{range_begin:/registry/events/default/instance.18957ed276a58d37; range_end:; response_count:1; response_revision:91; }","duration":"252.617333ms","start":"2026-02-19T00:37:56.084825Z","end":"2026-02-19T00:37:56.337443Z","steps":["trace[926328185] 'agreement among raft nodes before linearized reading' (duration: 252.550434ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:56.337434Z","caller":"traceutil/trace.go:171","msg":"trace[633354830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:91; }","duration":"138.966183ms","start":"2026-02-19T00:37:56.198451Z","end":"2026-02-19T00:37:56.337417Z","steps":["trace[633354830] 'agreement among raft nodes before linearized reading' (duration: 138.634566ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:56.499783Z","caller":"traceutil/trace.go:171","msg":"trace[743038621] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"159.225748ms","start":"2026-02-19T00:37:56.340514Z","end":"2026-02-19T00:37:56.49974Z","steps":["trace[743038621] 'process raft request' (duration: 113.854998ms)","trace[743038621] 'compare' (duration: 45.220412ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:56.598736Z","caller":"traceutil/trace.go:171","msg":"trace[1469778044] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"256.145452ms","start":"2026-02-19T00:37:56.342564Z","end":"2026-02-19T00:37:56.598709Z","steps":["trace[1469778044] 'process raft request' (duration: 255.867555ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:56.59917Z","caller":"traceutil/trace.go:171","msg":"trace[830967241] linearizableReadLoop","detail":"{readStateIndex:99; appliedIndex:97; }","duration":"256.526779ms","start":"2026-02-19T00:37:56.342622Z","end":"2026-02-19T00:37:56.599149Z","steps":["trace[830967241] 'read index received' (duration: 111.756718ms)","trace[830967241] 'applied index is now lower than readState.Index' (duration: 144.768281ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:56.600568Z","caller":"traceutil/trace.go:171","msg":"trace[1924252292] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"257.154813ms","start":"2026-02-19T00:37:56.342821Z","end":"2026-02-19T00:37:56.599976Z","steps":["trace[1924252292] 'process raft request' (duration: 255.818886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:56.799533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.866967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:56.799621Z","caller":"traceutil/trace.go:171","msg":"trace[1553149151] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:94; }","duration":"456.988336ms","start":"2026-02-19T00:37:56.342609Z","end":"2026-02-19T00:37:56.799597Z","steps":["trace[1553149151] 'agreement among raft nodes before linearized reading' (duration: 257.459911ms)","trace[1553149151] 'range keys from in-memory index tree' (duration: 199.395068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:56.799744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:56.342592Z","time spent":"457.114295ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:public-info-viewer\" "} {"level":"warn","ts":"2026-02-19T00:37:56.800093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.567619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18957ed276a5a64b\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-19T00:37:56.800135Z","caller":"traceutil/trace.go:171","msg":"trace[362852872] range","detail":"{range_begin:/registry/events/default/instance.18957ed276a5a64b; range_end:; response_count:1; response_revision:94; }","duration":"296.609439ms","start":"2026-02-19T00:37:56.503512Z","end":"2026-02-19T00:37:56.800122Z","steps":["trace[362852872] 'agreement among raft nodes before linearized reading' (duration: 96.542518ms)","trace[362852872] 'range keys from in-memory index tree' (duration: 199.990771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.052875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.473542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:57.053296Z","caller":"traceutil/trace.go:171","msg":"trace[1823342638] linearizableReadLoop","detail":"{readStateIndex:102; appliedIndex:101; }","duration":"222.694351ms","start":"2026-02-19T00:37:56.830561Z","end":"2026-02-19T00:37:57.053256Z","steps":["trace[1823342638] 'read index received' (duration: 93.682984ms)","trace[1823342638] 'applied index is now lower than readState.Index' (duration: 129.009817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.053701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.136187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18957ed276a5af0b\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-02-19T00:37:57.053785Z","caller":"traceutil/trace.go:171","msg":"trace[2122954576] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"250.150627ms","start":"2026-02-19T00:37:56.803585Z","end":"2026-02-19T00:37:57.053736Z","steps":["trace[2122954576] 'process raft request' (duration: 120.712204ms)","trace[2122954576] 'compare' (duration: 128.258125ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:57.053901Z","caller":"traceutil/trace.go:171","msg":"trace[1477824128] range","detail":"{range_begin:/registry/events/default/instance.18957ed276a5af0b; range_end:; response_count:1; response_revision:96; }","duration":"223.363574ms","start":"2026-02-19T00:37:56.830492Z","end":"2026-02-19T00:37:57.053855Z","steps":["trace[1477824128] 'agreement among raft nodes before linearized reading' (duration: 223.011388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:57.43726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.688192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:57.437501Z","caller":"traceutil/trace.go:171","msg":"trace[608576506] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"312.059014ms","start":"2026-02-19T00:37:57.12541Z","end":"2026-02-19T00:37:57.437469Z","steps":["trace[608576506] 'process raft request' (duration: 187.098394ms)","trace[608576506] 'compare' (duration: 124.588173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.437605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:57.125393Z","time spent":"312.139043ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:37:57.437976Z","caller":"traceutil/trace.go:171","msg":"trace[1150509778] linearizableReadLoop","detail":"{readStateIndex:105; appliedIndex:104; }","duration":"312.089464ms","start":"2026-02-19T00:37:57.125876Z","end":"2026-02-19T00:37:57.437965Z","steps":["trace[1150509778] 'read index received' (duration: 186.612529ms)","trace[1150509778] 'applied index is now lower than readState.Index' (duration: 125.475935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.438407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.53671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:57.438429Z","caller":"traceutil/trace.go:171","msg":"trace[1480885085] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:99; }","duration":"312.661749ms","start":"2026-02-19T00:37:57.125762Z","end":"2026-02-19T00:37:57.438423Z","steps":["trace[1480885085] 'agreement among raft nodes before linearized reading' (duration: 312.310882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:57.438508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:57.125748Z","time spent":"312.696269ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-19T00:37:57.43936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.436086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:57.439383Z","caller":"traceutil/trace.go:171","msg":"trace[1130973858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:99; }","duration":"196.497695ms","start":"2026-02-19T00:37:57.24288Z","end":"2026-02-19T00:37:57.439378Z","steps":["trace[1130973858] 'agreement among raft nodes before linearized reading' (duration: 196.459685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:57.662389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.619115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:57.662927Z","caller":"traceutil/trace.go:171","msg":"trace[813971895] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"219.158221ms","start":"2026-02-19T00:37:57.443745Z","end":"2026-02-19T00:37:57.662903Z","steps":["trace[813971895] 'process raft request' (duration: 219.013502ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:57.663194Z","caller":"traceutil/trace.go:171","msg":"trace[1193243644] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"219.537197ms","start":"2026-02-19T00:37:57.443541Z","end":"2026-02-19T00:37:57.663078Z","steps":["trace[1193243644] 'process raft request' (duration: 94.14881ms)","trace[1193243644] 'compare' (duration: 124.494066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.896946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.203095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:57.897246Z","caller":"traceutil/trace.go:171","msg":"trace[56853700] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"192.282685ms","start":"2026-02-19T00:37:57.704744Z","end":"2026-02-19T00:37:57.897027Z","steps":["trace[56853700] 'read index received' (duration: 90.862772ms)","trace[56853700] 'applied index is now lower than readState.Index' (duration: 101.418763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:57.897377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.627112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:57.897473Z","caller":"traceutil/trace.go:171","msg":"trace[1618109853] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:103; }","duration":"192.746551ms","start":"2026-02-19T00:37:57.704717Z","end":"2026-02-19T00:37:57.897463Z","steps":["trace[1618109853] 'agreement among raft nodes before linearized reading' (duration: 192.576743ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:57.897941Z","caller":"traceutil/trace.go:171","msg":"trace[592111741] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"228.409855ms","start":"2026-02-19T00:37:57.669518Z","end":"2026-02-19T00:37:57.897927Z","steps":["trace[592111741] 'process raft request' (duration: 126.14583ms)","trace[592111741] 'compare' (duration: 101.114606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:58.39559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.487912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:58.395852Z","caller":"traceutil/trace.go:171","msg":"trace[1933293196] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:115; }","duration":"152.391814ms","start":"2026-02-19T00:37:58.243442Z","end":"2026-02-19T00:37:58.395833Z","steps":["trace[1933293196] 'read index received' (duration: 3.605975ms)","trace[1933293196] 'applied index is now lower than readState.Index' (duration: 148.784359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:58.396041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.596632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:58.396078Z","caller":"traceutil/trace.go:171","msg":"trace[1546543867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"152.679292ms","start":"2026-02-19T00:37:58.243389Z","end":"2026-02-19T00:37:58.396068Z","steps":["trace[1546543867] 'agreement among raft nodes before linearized reading' (duration: 152.501444ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:58.396391Z","caller":"traceutil/trace.go:171","msg":"trace[99800904] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"272.076283ms","start":"2026-02-19T00:37:58.124301Z","end":"2026-02-19T00:37:58.396377Z","steps":["trace[99800904] 'process raft request' (duration: 122.739369ms)","trace[99800904] 'compare' (duration: 148.328634ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:58.396614Z","caller":"traceutil/trace.go:171","msg":"trace[1705840566] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"271.112073ms","start":"2026-02-19T00:37:58.125489Z","end":"2026-02-19T00:37:58.396601Z","steps":["trace[1705840566] 'process raft request' (duration: 270.198402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:58.614291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.565205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:58.6148Z","caller":"traceutil/trace.go:171","msg":"trace[998905002] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:119; }","duration":"116.658617ms","start":"2026-02-19T00:37:58.498127Z","end":"2026-02-19T00:37:58.614786Z","steps":["trace[998905002] 'read index received' (duration: 7.543557ms)","trace[998905002] 'applied index is now lower than readState.Index' (duration: 109.11419ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:58.61488Z","caller":"traceutil/trace.go:171","msg":"trace[822161348] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"157.015188ms","start":"2026-02-19T00:37:58.457858Z","end":"2026-02-19T00:37:58.614873Z","steps":["trace[822161348] 'process raft request' (duration: 156.509353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:58.615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.884235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:58.615023Z","caller":"traceutil/trace.go:171","msg":"trace[1452088683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:115; }","duration":"116.943825ms","start":"2026-02-19T00:37:58.498072Z","end":"2026-02-19T00:37:58.615016Z","steps":["trace[1452088683] 'agreement among raft nodes before linearized reading' (duration: 116.891715ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:58.615327Z","caller":"traceutil/trace.go:171","msg":"trace[2115362937] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"158.633773ms","start":"2026-02-19T00:37:58.45667Z","end":"2026-02-19T00:37:58.615304Z","steps":["trace[2115362937] 'process raft request' (duration: 48.994568ms)","trace[2115362937] 'compare' (duration: 108.444446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:58.899206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.990464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-19T00:37:58.89943Z","caller":"traceutil/trace.go:171","msg":"trace[452029096] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"222.687348ms","start":"2026-02-19T00:37:58.676682Z","end":"2026-02-19T00:37:58.899369Z","steps":["trace[452029096] 'process raft request' (duration: 82.459766ms)","trace[452029096] 'compare' (duration: 139.901885ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:58.899487Z","caller":"traceutil/trace.go:171","msg":"trace[1047593652] linearizableReadLoop","detail":"{readStateIndex:125; appliedIndex:124; }","duration":"215.980922ms","start":"2026-02-19T00:37:58.683309Z","end":"2026-02-19T00:37:58.89929Z","steps":["trace[1047593652] 'read index received' (duration: 75.755251ms)","trace[1047593652] 'applied index is now lower than readState.Index' (duration: 140.224511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:58.899648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.327629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:58.899678Z","caller":"traceutil/trace.go:171","msg":"trace[1278485178] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:0; response_revision:119; }","duration":"216.416889ms","start":"2026-02-19T00:37:58.683251Z","end":"2026-02-19T00:37:58.899668Z","steps":["trace[1278485178] 'agreement among raft nodes before linearized reading' (duration: 216.27187ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:59.094632Z","caller":"traceutil/trace.go:171","msg":"trace[1964064163] linearizableReadLoop","detail":"{readStateIndex:129; appliedIndex:128; }","duration":"117.928369ms","start":"2026-02-19T00:37:58.976679Z","end":"2026-02-19T00:37:59.094607Z","steps":["trace[1964064163] 'read index received' (duration: 33.334707ms)","trace[1964064163] 'applied index is now lower than readState.Index' (duration: 84.592582ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:59.095047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.314125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T00:37:59.095089Z","caller":"traceutil/trace.go:171","msg":"trace[1357078496] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:123; }","duration":"118.431704ms","start":"2026-02-19T00:37:58.976647Z","end":"2026-02-19T00:37:59.095079Z","steps":["trace[1357078496] 'agreement among raft nodes before linearized reading' (duration: 118.234686ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:59.094857Z","caller":"traceutil/trace.go:171","msg":"trace[152739832] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"118.86224ms","start":"2026-02-19T00:37:58.975759Z","end":"2026-02-19T00:37:59.094621Z","steps":["trace[152739832] 'process raft request' (duration: 34.286788ms)","trace[152739832] 'compare' (duration: 84.453713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:59.459629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.557122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:37:59.45989Z","caller":"traceutil/trace.go:171","msg":"trace[244922648] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:138; }","duration":"216.192153ms","start":"2026-02-19T00:37:59.24368Z","end":"2026-02-19T00:37:59.459872Z","steps":["trace[244922648] 'read index received' (duration: 86.336044ms)","trace[244922648] 'applied index is now lower than readState.Index' (duration: 129.855139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:59.460073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.382141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:37:59.460111Z","caller":"traceutil/trace.go:171","msg":"trace[85462985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:134; }","duration":"216.449261ms","start":"2026-02-19T00:37:59.24365Z","end":"2026-02-19T00:37:59.460099Z","steps":["trace[85462985] 'agreement among raft nodes before linearized reading' (duration: 216.278662ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:37:59.460544Z","caller":"traceutil/trace.go:171","msg":"trace[117389451] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"218.625129ms","start":"2026-02-19T00:37:59.241894Z","end":"2026-02-19T00:37:59.460519Z","steps":["trace[117389451] 'process raft request' (duration: 88.112827ms)","trace[117389451] 'compare' (duration: 129.427663ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:59.460864Z","caller":"traceutil/trace.go:171","msg":"trace[1282384348] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"218.066494ms","start":"2026-02-19T00:37:59.242786Z","end":"2026-02-19T00:37:59.460853Z","steps":["trace[1282384348] 'process raft request' (duration: 216.997895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:59.827519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.091511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:37:59.827732Z","caller":"traceutil/trace.go:171","msg":"trace[701192590] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"344.89776ms","start":"2026-02-19T00:37:59.482797Z","end":"2026-02-19T00:37:59.827695Z","steps":["trace[701192590] 'process raft request' (duration: 187.562422ms)","trace[701192590] 'compare' (duration: 156.941882ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:37:59.827639Z","caller":"traceutil/trace.go:171","msg":"trace[1926673850] linearizableReadLoop","detail":"{readStateIndex:143; appliedIndex:142; }","duration":"319.194722ms","start":"2026-02-19T00:37:59.508422Z","end":"2026-02-19T00:37:59.827617Z","steps":["trace[1926673850] 'read index received' (duration: 161.858054ms)","trace[1926673850] 'applied index is now lower than readState.Index' (duration: 157.334878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:37:59.828081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:59.482785Z","time spent":"345.040669ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T00:37:59.82835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.913265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:37:59.828397Z","caller":"traceutil/trace.go:171","msg":"trace[1008442858] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:0; response_revision:137; }","duration":"319.998724ms","start":"2026-02-19T00:37:59.508386Z","end":"2026-02-19T00:37:59.828385Z","steps":["trace[1008442858] 'agreement among raft nodes before linearized reading' (duration: 319.730746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:37:59.828715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:37:59.508372Z","time spent":"320.306041ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:volume-scheduler\" "} {"level":"warn","ts":"2026-02-19T00:37:59.82925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.61534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:37:59.829432Z","caller":"traceutil/trace.go:171","msg":"trace[917875790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:137; }","duration":"231.800398ms","start":"2026-02-19T00:37:59.597617Z","end":"2026-02-19T00:37:59.829418Z","steps":["trace[917875790] 'agreement among raft nodes before linearized reading' (duration: 231.583371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:00.376446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.872448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:00.376718Z","caller":"traceutil/trace.go:171","msg":"trace[1508728521] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"294.981381ms","start":"2026-02-19T00:38:00.081713Z","end":"2026-02-19T00:38:00.376694Z","steps":["trace[1508728521] 'process raft request' (duration: 79.781696ms)","trace[1508728521] 'compare' (duration: 214.64945ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:00.377291Z","caller":"traceutil/trace.go:171","msg":"trace[129778161] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"295.551155ms","start":"2026-02-19T00:38:00.081727Z","end":"2026-02-19T00:38:00.377278Z","steps":["trace[129778161] 'process raft request' (duration: 294.903091ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:00.587286Z","caller":"traceutil/trace.go:171","msg":"trace[773138015] linearizableReadLoop","detail":"{readStateIndex:152; appliedIndex:151; }","duration":"205.438215ms","start":"2026-02-19T00:38:00.381828Z","end":"2026-02-19T00:38:00.587266Z","steps":["trace[773138015] 'read index received' (duration: 181.066668ms)","trace[773138015] 'applied index is now lower than readState.Index' (duration: 24.370627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:00.587693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.850821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:00.587913Z","caller":"traceutil/trace.go:171","msg":"trace[1753686769] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:0; response_revision:146; }","duration":"206.112559ms","start":"2026-02-19T00:38:00.381791Z","end":"2026-02-19T00:38:00.587903Z","steps":["trace[1753686769] 'agreement among raft nodes before linearized reading' (duration: 205.715433ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:00.587858Z","caller":"traceutil/trace.go:171","msg":"trace[566147603] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"206.97073ms","start":"2026-02-19T00:38:00.380871Z","end":"2026-02-19T00:38:00.587842Z","steps":["trace[566147603] 'process raft request' (duration: 181.85981ms)","trace[566147603] 'compare' (duration: 24.437507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:00.881976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.687278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:00.882074Z","caller":"traceutil/trace.go:171","msg":"trace[1242018033] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:152; }","duration":"225.475712ms","start":"2026-02-19T00:38:00.656588Z","end":"2026-02-19T00:38:00.882063Z","steps":["trace[1242018033] 'read index received' (duration: 46.673605ms)","trace[1242018033] 'applied index is now lower than readState.Index' (duration: 178.801567ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:00.882088Z","caller":"traceutil/trace.go:171","msg":"trace[1704323177] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"289.310106ms","start":"2026-02-19T00:38:00.592757Z","end":"2026-02-19T00:38:00.882067Z","steps":["trace[1704323177] 'process raft request' (duration: 110.481879ms)","trace[1704323177] 'compare' (duration: 178.599279ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:00.882122Z","caller":"traceutil/trace.go:171","msg":"trace[1207891745] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"289.312526ms","start":"2026-02-19T00:38:00.592805Z","end":"2026-02-19T00:38:00.882118Z","steps":["trace[1207891745] 'process raft request' (duration: 289.230447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:00.882311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.755559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-19T00:38:00.882333Z","caller":"traceutil/trace.go:171","msg":"trace[1938115148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:148; }","duration":"202.817427ms","start":"2026-02-19T00:38:00.679509Z","end":"2026-02-19T00:38:00.882326Z","steps":["trace[1938115148] 'agreement among raft nodes before linearized reading' (duration: 202.739748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:00.882417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.874136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T00:38:00.88242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.837079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T00:38:00.88243Z","caller":"traceutil/trace.go:171","msg":"trace[1903344065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:148; }","duration":"184.907276ms","start":"2026-02-19T00:38:00.697518Z","end":"2026-02-19T00:38:00.882425Z","steps":["trace[1903344065] 'agreement among raft nodes before linearized reading' (duration: 184.882947ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:00.882436Z","caller":"traceutil/trace.go:171","msg":"trace[776781355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:148; }","duration":"225.885017ms","start":"2026-02-19T00:38:00.656546Z","end":"2026-02-19T00:38:00.882431Z","steps":["trace[776781355] 'agreement among raft nodes before linearized reading' (duration: 225.852788ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:01.351421Z","caller":"traceutil/trace.go:171","msg":"trace[1205552256] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"344.408672ms","start":"2026-02-19T00:38:01.006991Z","end":"2026-02-19T00:38:01.3514Z","steps":["trace[1205552256] 'process raft request' (duration: 344.362492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.351701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:01.006971Z","time spent":"344.60474ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:01.35176Z","caller":"traceutil/trace.go:171","msg":"trace[787087107] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"459.125449ms","start":"2026-02-19T00:38:00.892588Z","end":"2026-02-19T00:38:01.351713Z","steps":["trace[787087107] 'process raft request' (duration: 458.693213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.351908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:00.89258Z","time spent":"459.258897ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:01.352338Z","caller":"traceutil/trace.go:171","msg":"trace[968575050] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"452.599023ms","start":"2026-02-19T00:38:00.899727Z","end":"2026-02-19T00:38:01.352326Z","steps":["trace[968575050] 'process raft request' (duration: 451.588434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.358431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:00.899721Z","time spent":"458.649022ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:01.352416Z","caller":"traceutil/trace.go:171","msg":"trace[936263146] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"465.696713ms","start":"2026-02-19T00:38:00.886702Z","end":"2026-02-19T00:38:01.352399Z","steps":["trace[936263146] 'process raft request' (duration: 365.056012ms)","trace[936263146] 'compare' (duration: 99.390573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:01.35891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:00.886692Z","time spent":"472.127708ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:01.352493Z","caller":"traceutil/trace.go:171","msg":"trace[619280302] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"461.816472ms","start":"2026-02-19T00:38:00.890669Z","end":"2026-02-19T00:38:01.352486Z","steps":["trace[619280302] 'process raft request' (duration: 460.578614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.359305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:00.89066Z","time spent":"468.557654ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:01.352703Z","caller":"traceutil/trace.go:171","msg":"trace[748761509] linearizableReadLoop","detail":"{readStateIndex:159; appliedIndex:155; }","duration":"405.334705ms","start":"2026-02-19T00:38:00.947359Z","end":"2026-02-19T00:38:01.352693Z","steps":["trace[748761509] 'read index received' (duration: 304.465237ms)","trace[748761509] 'applied index is now lower than readState.Index' (duration: 100.868388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:01.352842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.471233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:01.359936Z","caller":"traceutil/trace.go:171","msg":"trace[1010777272] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:0; response_revision:154; }","duration":"412.587181ms","start":"2026-02-19T00:38:00.947335Z","end":"2026-02-19T00:38:01.359923Z","steps":["trace[1010777272] 'agreement among raft nodes before linearized reading' (duration: 405.407394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.360035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:00.947328Z","time spent":"412.69368ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" "} {"level":"warn","ts":"2026-02-19T00:38:01.757965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.450652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:01.758069Z","caller":"traceutil/trace.go:171","msg":"trace[642659157] linearizableReadLoop","detail":"{readStateIndex:163; appliedIndex:162; }","duration":"349.882566ms","start":"2026-02-19T00:38:01.408168Z","end":"2026-02-19T00:38:01.758051Z","steps":["trace[642659157] 'read index received' (duration: 87.328535ms)","trace[642659157] 'applied index is now lower than readState.Index' (duration: 262.552691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:01.758171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.993555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:01.758198Z","caller":"traceutil/trace.go:171","msg":"trace[1181328740] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:0; response_revision:157; }","duration":"350.049264ms","start":"2026-02-19T00:38:01.408138Z","end":"2026-02-19T00:38:01.758188Z","steps":["trace[1181328740] 'agreement among raft nodes before linearized reading' (duration: 349.958725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.75823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:01.408126Z","time spent":"350.096664ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"info","ts":"2026-02-19T00:38:01.758419Z","caller":"traceutil/trace.go:171","msg":"trace[363699663] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"377.637794ms","start":"2026-02-19T00:38:01.380769Z","end":"2026-02-19T00:38:01.758407Z","steps":["trace[363699663] 'process raft request' (duration: 114.647038ms)","trace[363699663] 'compare' (duration: 262.356863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:01.758717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:01.380754Z","time spent":"377.930721ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:01.76677Z","caller":"traceutil/trace.go:171","msg":"trace[781581078] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"358.429109ms","start":"2026-02-19T00:38:01.408317Z","end":"2026-02-19T00:38:01.766746Z","steps":["trace[781581078] 'process raft request' (duration: 358.26824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.76714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:01.408308Z","time spent":"358.741026ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:01.936487Z","caller":"traceutil/trace.go:171","msg":"trace[2131422401] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:164; }","duration":"164.952629ms","start":"2026-02-19T00:38:01.771518Z","end":"2026-02-19T00:38:01.936471Z","steps":["trace[2131422401] 'read index received' (duration: 137.335639ms)","trace[2131422401] 'applied index is now lower than readState.Index' (duration: 27.61611ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:01.936504Z","caller":"traceutil/trace.go:171","msg":"trace[1493497312] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"230.892491ms","start":"2026-02-19T00:38:01.705585Z","end":"2026-02-19T00:38:01.936477Z","steps":["trace[1493497312] 'process raft request' (duration: 203.222141ms)","trace[1493497312] 'compare' (duration: 27.437583ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:01.936634Z","caller":"traceutil/trace.go:171","msg":"trace[1913934606] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"172.892429ms","start":"2026-02-19T00:38:01.763734Z","end":"2026-02-19T00:38:01.936627Z","steps":["trace[1913934606] 'process raft request' (duration: 172.667241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.936827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.291316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-19T00:38:01.936853Z","caller":"traceutil/trace.go:171","msg":"trace[409225663] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:160; }","duration":"165.352604ms","start":"2026-02-19T00:38:01.771492Z","end":"2026-02-19T00:38:01.936844Z","steps":["trace[409225663] 'agreement among raft nodes before linearized reading' (duration: 165.235206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:01.948021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.98286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:01.948067Z","caller":"traceutil/trace.go:171","msg":"trace[1451396792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:160; }","duration":"151.05853ms","start":"2026-02-19T00:38:01.796999Z","end":"2026-02-19T00:38:01.948057Z","steps":["trace[1451396792] 'agreement among raft nodes before linearized reading' (duration: 150.960671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:02.231906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.840444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:02.232021Z","caller":"traceutil/trace.go:171","msg":"trace[1398406631] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:167; }","duration":"284.051436ms","start":"2026-02-19T00:38:01.947949Z","end":"2026-02-19T00:38:02.232Z","steps":["trace[1398406631] 'read index received' (duration: 150.962815ms)","trace[1398406631] 'applied index is now lower than readState.Index' (duration: 133.087111ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:02.232123Z","caller":"traceutil/trace.go:171","msg":"trace[1638294385] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"286.032186ms","start":"2026-02-19T00:38:01.946074Z","end":"2026-02-19T00:38:02.232106Z","steps":["trace[1638294385] 'process raft request' (duration: 152.886565ms)","trace[1638294385] 'compare' (duration: 132.602146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:02.232265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.856598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-19T00:38:02.232314Z","caller":"traceutil/trace.go:171","msg":"trace[1759781112] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:161; }","duration":"283.923947ms","start":"2026-02-19T00:38:01.948376Z","end":"2026-02-19T00:38:02.2323Z","steps":["trace[1759781112] 'agreement among raft nodes before linearized reading' (duration: 283.834868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:02.23249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.926176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:02.232536Z","caller":"traceutil/trace.go:171","msg":"trace[1168827369] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:0; response_revision:161; }","duration":"292.972575ms","start":"2026-02-19T00:38:01.939552Z","end":"2026-02-19T00:38:02.232524Z","steps":["trace[1168827369] 'agreement among raft nodes before linearized reading' (duration: 292.862817ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:03.065164Z","caller":"traceutil/trace.go:171","msg":"trace[550348642] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"119.4716ms","start":"2026-02-19T00:38:02.945672Z","end":"2026-02-19T00:38:03.065144Z","steps":["trace[550348642] 'read index received' (duration: 34.233087ms)","trace[550348642] 'applied index is now lower than readState.Index' (duration: 85.237553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:03.065279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.587629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:03.065622Z","caller":"traceutil/trace.go:171","msg":"trace[1044859548] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:0; response_revision:233; }","duration":"119.962465ms","start":"2026-02-19T00:38:02.945642Z","end":"2026-02-19T00:38:03.065604Z","steps":["trace[1044859548] 'agreement among raft nodes before linearized reading' (duration: 119.579009ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:03.065913Z","caller":"traceutil/trace.go:171","msg":"trace[1919850854] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"134.485136ms","start":"2026-02-19T00:38:02.931408Z","end":"2026-02-19T00:38:03.065893Z","steps":["trace[1919850854] 'process raft request' (duration: 48.53642ms)","trace[1919850854] 'compare' (duration: 84.962746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:03.506561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.406292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:03.506664Z","caller":"traceutil/trace.go:171","msg":"trace[2005104571] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"142.357393ms","start":"2026-02-19T00:38:03.364284Z","end":"2026-02-19T00:38:03.506641Z","steps":["trace[2005104571] 'read index received' (duration: 23.805362ms)","trace[2005104571] 'applied index is now lower than readState.Index' (duration: 118.550571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:03.507303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.008056ms","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-19T00:38:03.507347Z","caller":"traceutil/trace.go:171","msg":"trace[2100030777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:240; }","duration":"143.095865ms","start":"2026-02-19T00:38:03.36424Z","end":"2026-02-19T00:38:03.507336Z","steps":["trace[2100030777] 'agreement among raft nodes before linearized reading' (duration: 142.974436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:03.507886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.299113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:38:03.507934Z","caller":"traceutil/trace.go:171","msg":"trace[922573593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:240; }","duration":"143.349023ms","start":"2026-02-19T00:38:03.364573Z","end":"2026-02-19T00:38:03.507922Z","steps":["trace[922573593] 'agreement among raft nodes before linearized reading' (duration: 143.231964ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:03.508174Z","caller":"traceutil/trace.go:171","msg":"trace[746564439] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"179.759223ms","start":"2026-02-19T00:38:03.328398Z","end":"2026-02-19T00:38:03.508158Z","steps":["trace[746564439] 'process raft request' (duration: 59.695387ms)","trace[746564439] 'compare' (duration: 118.162206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:03.508508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.992691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:03.508554Z","caller":"traceutil/trace.go:171","msg":"trace[443703789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:240; }","duration":"110.08366ms","start":"2026-02-19T00:38:03.398459Z","end":"2026-02-19T00:38:03.508543Z","steps":["trace[443703789] 'agreement among raft nodes before linearized reading' (duration: 110.013781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:03.839219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.834088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:03.840994Z","caller":"traceutil/trace.go:171","msg":"trace[1125144137] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"126.602097ms","start":"2026-02-19T00:38:03.714372Z","end":"2026-02-19T00:38:03.840974Z","steps":["trace[1125144137] 'compare' (duration: 118.625521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:05.416397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:05.916652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:06.005077Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127819106s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T00:38:06.006776Z","caller":"traceutil/trace.go:171","msg":"trace[1184566002] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"1.090694009s","start":"2026-02-19T00:38:04.916021Z","end":"2026-02-19T00:38:06.006715Z","steps":["trace[1184566002] 'read index received' (duration: 1.089251965s)","trace[1184566002] 'applied index is now lower than readState.Index' (duration: 1.441264ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:06.0071Z","caller":"traceutil/trace.go:171","msg":"trace[367064999] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"1.129899453s","start":"2026-02-19T00:38:04.877189Z","end":"2026-02-19T00:38:06.007089Z","steps":["trace[367064999] 'process raft request' (duration: 1.128129902s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.007393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:04.877176Z","time spent":"1.130042842s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T00:38:06.007732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091702918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3161"} {"level":"info","ts":"2026-02-19T00:38:06.007833Z","caller":"traceutil/trace.go:171","msg":"trace[1873262170] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:277; }","duration":"1.091822916s","start":"2026-02-19T00:38:04.915979Z","end":"2026-02-19T00:38:06.007802Z","steps":["trace[1873262170] 'agreement among raft nodes before linearized reading' (duration: 1.091679568s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.007922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:04.915958Z","time spent":"1.091954015s","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3184,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-19T00:38:06.008276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.044388ms","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-19T00:38:06.008507Z","caller":"traceutil/trace.go:171","msg":"trace[1613370602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:277; }","duration":"489.308965ms","start":"2026-02-19T00:38:05.519186Z","end":"2026-02-19T00:38:06.008495Z","steps":["trace[1613370602] 'agreement among raft nodes before linearized reading' (duration: 489.052248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.008682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:05.519167Z","time spent":"489.474763ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T00:38:06.00917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.182177ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:06.009485Z","caller":"traceutil/trace.go:171","msg":"trace[1812674585] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:277; }","duration":"258.498043ms","start":"2026-02-19T00:38:05.750974Z","end":"2026-02-19T00:38:06.009472Z","steps":["trace[1812674585] 'agreement among raft nodes before linearized reading' (duration: 258.168697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.010807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.574799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:06.010986Z","caller":"traceutil/trace.go:171","msg":"trace[851894999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:277; }","duration":"384.798217ms","start":"2026-02-19T00:38:05.626175Z","end":"2026-02-19T00:38:06.010974Z","steps":["trace[851894999] 'agreement among raft nodes before linearized reading' (duration: 384.596089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.011351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.900893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7227"} {"level":"warn","ts":"2026-02-19T00:38:06.011103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:05.626157Z","time spent":"384.934505ms","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T00:38:06.01179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.983952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:38:06.015517Z","caller":"traceutil/trace.go:171","msg":"trace[1543965276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:277; }","duration":"472.769882ms","start":"2026-02-19T00:38:05.542733Z","end":"2026-02-19T00:38:06.015503Z","steps":["trace[1543965276] 'agreement among raft nodes before linearized reading' (duration: 469.018832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.015601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:05.542711Z","time spent":"472.877111ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T00:38:06.012502Z","caller":"traceutil/trace.go:171","msg":"trace[1087630362] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:277; }","duration":"389.08967ms","start":"2026-02-19T00:38:05.623397Z","end":"2026-02-19T00:38:06.012486Z","steps":["trace[1087630362] 'agreement among raft nodes before linearized reading' (duration: 387.903403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.015945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:05.623379Z","time spent":"392.559463ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7250,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-19T00:38:06.280949Z","caller":"traceutil/trace.go:171","msg":"trace[172675394] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:286; }","duration":"253.590481ms","start":"2026-02-19T00:38:06.027337Z","end":"2026-02-19T00:38:06.280928Z","steps":["trace[172675394] 'read index received' (duration: 246.808944ms)","trace[172675394] 'applied index is now lower than readState.Index' (duration: 6.780677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:06.281283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.918797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T00:38:06.281425Z","caller":"traceutil/trace.go:171","msg":"trace[135778088] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:281; }","duration":"254.084585ms","start":"2026-02-19T00:38:06.027315Z","end":"2026-02-19T00:38:06.281399Z","steps":["trace[135778088] 'agreement among raft nodes before linearized reading' (duration: 253.813728ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.282009Z","caller":"traceutil/trace.go:171","msg":"trace[1340465850] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"259.447307ms","start":"2026-02-19T00:38:06.022541Z","end":"2026-02-19T00:38:06.281988Z","steps":["trace[1340465850] 'process raft request' (duration: 251.657682ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.282744Z","caller":"traceutil/trace.go:171","msg":"trace[1798500502] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"259.383788ms","start":"2026-02-19T00:38:06.023346Z","end":"2026-02-19T00:38:06.282729Z","steps":["trace[1798500502] 'process raft request' (duration: 257.494739ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.283277Z","caller":"traceutil/trace.go:171","msg":"trace[562038459] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"258.980592ms","start":"2026-02-19T00:38:06.024278Z","end":"2026-02-19T00:38:06.283259Z","steps":["trace[562038459] 'process raft request' (duration: 256.613158ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.506694Z","caller":"traceutil/trace.go:171","msg":"trace[1174995008] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"208.751539ms","start":"2026-02-19T00:38:06.297918Z","end":"2026-02-19T00:38:06.50667Z","steps":["trace[1174995008] 'process raft request' (duration: 153.759287ms)","trace[1174995008] 'compare' (duration: 54.523517ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:06.507404Z","caller":"traceutil/trace.go:171","msg":"trace[1860393766] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"208.405873ms","start":"2026-02-19T00:38:06.298989Z","end":"2026-02-19T00:38:06.507395Z","steps":["trace[1860393766] 'process raft request' (duration: 207.630852ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.507235Z","caller":"traceutil/trace.go:171","msg":"trace[1071786785] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:289; }","duration":"206.212777ms","start":"2026-02-19T00:38:06.301008Z","end":"2026-02-19T00:38:06.507221Z","steps":["trace[1071786785] 'read index received' (duration: 150.680821ms)","trace[1071786785] 'applied index is now lower than readState.Index' (duration: 55.531136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:06.511341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.444393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:06.51276Z","caller":"traceutil/trace.go:171","msg":"trace[356799622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:283; }","duration":"211.760796ms","start":"2026-02-19T00:38:06.300988Z","end":"2026-02-19T00:38:06.512749Z","steps":["trace[356799622] 'agreement among raft nodes before linearized reading' (duration: 208.414352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.511773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.511277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:06.512891Z","caller":"traceutil/trace.go:171","msg":"trace[1562329213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:283; }","duration":"124.689534ms","start":"2026-02-19T00:38:06.388193Z","end":"2026-02-19T00:38:06.512882Z","steps":["trace[1562329213] 'agreement among raft nodes before linearized reading' (duration: 123.448528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.893802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.104445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:06.893888Z","caller":"traceutil/trace.go:171","msg":"trace[903509005] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"372.827283ms","start":"2026-02-19T00:38:06.521045Z","end":"2026-02-19T00:38:06.893872Z","steps":["trace[903509005] 'process raft request' (duration: 151.59901ms)","trace[903509005] 'compare' (duration: 220.974996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:06.893931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:06.521035Z","time spent":"372.879043ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:06.894438Z","caller":"traceutil/trace.go:171","msg":"trace[1249461002] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:292; }","duration":"120.680338ms","start":"2026-02-19T00:38:06.77375Z","end":"2026-02-19T00:38:06.894431Z","steps":["trace[1249461002] 'read index received' (duration: 120.579569ms)","trace[1249461002] 'applied index is now lower than readState.Index' (duration: 100.209µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:06.89451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.754507ms","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-19T00:38:06.894533Z","caller":"traceutil/trace.go:171","msg":"trace[377170130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:286; }","duration":"120.816916ms","start":"2026-02-19T00:38:06.77371Z","end":"2026-02-19T00:38:06.894527Z","steps":["trace[377170130] 'agreement among raft nodes before linearized reading' (duration: 120.757797ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:06.894682Z","caller":"traceutil/trace.go:171","msg":"trace[482685144] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"372.126252ms","start":"2026-02-19T00:38:06.52255Z","end":"2026-02-19T00:38:06.894676Z","steps":["trace[482685144] 'process raft request' (duration: 371.810205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.894729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:06.52251Z","time spent":"372.19433ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:06.89485Z","caller":"traceutil/trace.go:171","msg":"trace[576749827] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"372.25233ms","start":"2026-02-19T00:38:06.52259Z","end":"2026-02-19T00:38:06.894843Z","steps":["trace[576749827] 'process raft request' (duration: 371.814595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:06.894883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:06.522585Z","time spent":"372.284389ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:07.082594Z","caller":"traceutil/trace.go:171","msg":"trace[945073731] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"142.179131ms","start":"2026-02-19T00:38:06.940394Z","end":"2026-02-19T00:38:07.082573Z","steps":["trace[945073731] 'process raft request' (duration: 57.637193ms)","trace[945073731] 'compare' (duration: 84.190212ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:07.083121Z","caller":"traceutil/trace.go:171","msg":"trace[667314935] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"113.679683ms","start":"2026-02-19T00:38:06.968702Z","end":"2026-02-19T00:38:07.082382Z","steps":["trace[667314935] 'read index received' (duration: 29.301822ms)","trace[667314935] 'applied index is now lower than readState.Index' (duration: 84.376821ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:07.084159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.409983ms","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-19T00:38:07.084247Z","caller":"traceutil/trace.go:171","msg":"trace[46839447] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"115.562291ms","start":"2026-02-19T00:38:06.968673Z","end":"2026-02-19T00:38:07.084235Z","steps":["trace[46839447] 'agreement among raft nodes before linearized reading' (duration: 114.503763ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:07.311619Z","caller":"traceutil/trace.go:171","msg":"trace[501691684] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"154.600016ms","start":"2026-02-19T00:38:07.156993Z","end":"2026-02-19T00:38:07.311593Z","steps":["trace[501691684] 'process raft request' (duration: 60.684531ms)","trace[501691684] 'compare' (duration: 93.805307ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:07.612551Z","caller":"traceutil/trace.go:171","msg":"trace[1455553726] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"225.375858ms","start":"2026-02-19T00:38:07.387154Z","end":"2026-02-19T00:38:07.612529Z","steps":["trace[1455553726] 'read index received' (duration: 147.717653ms)","trace[1455553726] 'applied index is now lower than readState.Index' (duration: 77.657275ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:07.612972Z","caller":"traceutil/trace.go:171","msg":"trace[229237134] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"263.052044ms","start":"2026-02-19T00:38:07.349905Z","end":"2026-02-19T00:38:07.612957Z","steps":["trace[229237134] 'process raft request' (duration: 185.018803ms)","trace[229237134] 'compare' (duration: 77.487407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:07.61307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.905503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:07.613613Z","caller":"traceutil/trace.go:171","msg":"trace[1610172345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:294; }","duration":"226.455717ms","start":"2026-02-19T00:38:07.387141Z","end":"2026-02-19T00:38:07.613597Z","steps":["trace[1610172345] 'agreement among raft nodes before linearized reading' (duration: 225.867333ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:07.842405Z","caller":"traceutil/trace.go:171","msg":"trace[638466840] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"218.072368ms","start":"2026-02-19T00:38:07.624311Z","end":"2026-02-19T00:38:07.842383Z","steps":["trace[638466840] 'read index received' (duration: 193.830055ms)","trace[638466840] 'applied index is now lower than readState.Index' (duration: 24.241563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:07.84269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.339715ms","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-19T00:38:07.842771Z","caller":"traceutil/trace.go:171","msg":"trace[963387203] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"218.517103ms","start":"2026-02-19T00:38:07.624242Z","end":"2026-02-19T00:38:07.842759Z","steps":["trace[963387203] 'agreement among raft nodes before linearized reading' (duration: 218.343835ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:07.843337Z","caller":"traceutil/trace.go:171","msg":"trace[183001643] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"220.081066ms","start":"2026-02-19T00:38:07.62324Z","end":"2026-02-19T00:38:07.843321Z","steps":["trace[183001643] 'process raft request' (duration: 194.833234ms)","trace[183001643] 'compare' (duration: 24.213443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:08.143051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.113635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:08.143162Z","caller":"traceutil/trace.go:171","msg":"trace[1797165127] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"224.839186ms","start":"2026-02-19T00:38:07.918303Z","end":"2026-02-19T00:38:08.143142Z","steps":["trace[1797165127] 'read index received' (duration: 40.529873ms)","trace[1797165127] 'applied index is now lower than readState.Index' (duration: 184.307693ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:08.143494Z","caller":"traceutil/trace.go:171","msg":"trace[784285957] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"291.118806ms","start":"2026-02-19T00:38:07.852354Z","end":"2026-02-19T00:38:08.143473Z","steps":["trace[784285957] 'process raft request' (duration: 106.510967ms)","trace[784285957] 'compare' (duration: 183.730229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:08.143986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.666846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:08.144044Z","caller":"traceutil/trace.go:171","msg":"trace[1023742072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:297; }","duration":"225.752806ms","start":"2026-02-19T00:38:07.918276Z","end":"2026-02-19T00:38:08.144029Z","steps":["trace[1023742072] 'agreement among raft nodes before linearized reading' (duration: 225.637797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:08.14442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.986948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:38:08.144501Z","caller":"traceutil/trace.go:171","msg":"trace[1909768304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:297; }","duration":"116.130576ms","start":"2026-02-19T00:38:08.028356Z","end":"2026-02-19T00:38:08.144487Z","steps":["trace[1909768304] 'agreement among raft nodes before linearized reading' (duration: 115.945728ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:08.344203Z","caller":"traceutil/trace.go:171","msg":"trace[1172043490] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"125.470242ms","start":"2026-02-19T00:38:08.218715Z","end":"2026-02-19T00:38:08.344185Z","steps":["trace[1172043490] 'read index received' (duration: 78.404116ms)","trace[1172043490] 'applied index is now lower than readState.Index' (duration: 47.065326ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:08.344594Z","caller":"traceutil/trace.go:171","msg":"trace[1657868326] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"127.818436ms","start":"2026-02-19T00:38:08.216761Z","end":"2026-02-19T00:38:08.34458Z","steps":["trace[1657868326] 'process raft request' (duration: 80.404454ms)","trace[1657868326] 'compare' (duration: 46.931677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:08.344924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.191094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:08.345141Z","caller":"traceutil/trace.go:171","msg":"trace[188541508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:302; }","duration":"126.430362ms","start":"2026-02-19T00:38:08.218694Z","end":"2026-02-19T00:38:08.345125Z","steps":["trace[188541508] 'agreement among raft nodes before linearized reading' (duration: 126.170375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:08.512608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.907975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:08.512677Z","caller":"traceutil/trace.go:171","msg":"trace[192127750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:304; }","duration":"127.004304ms","start":"2026-02-19T00:38:08.385659Z","end":"2026-02-19T00:38:08.512664Z","steps":["trace[192127750] 'agreement among raft nodes before linearized reading' (duration: 126.887805ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:08.519584Z","caller":"traceutil/trace.go:171","msg":"trace[1303490259] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"161.602718ms","start":"2026-02-19T00:38:08.351039Z","end":"2026-02-19T00:38:08.512642Z","steps":["trace[1303490259] 'process raft request' (duration: 160.685288ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:08.655873Z","caller":"traceutil/trace.go:171","msg":"trace[1432933188] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"133.908127ms","start":"2026-02-19T00:38:08.521947Z","end":"2026-02-19T00:38:08.655855Z","steps":["trace[1432933188] 'read index received' (duration: 121.610615ms)","trace[1432933188] 'applied index is now lower than readState.Index' (duration: 12.296602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:08.655997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.033206ms","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-19T00:38:08.656034Z","caller":"traceutil/trace.go:171","msg":"trace[749273437] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:307; }","duration":"134.117744ms","start":"2026-02-19T00:38:08.521906Z","end":"2026-02-19T00:38:08.656024Z","steps":["trace[749273437] 'agreement among raft nodes before linearized reading' (duration: 134.023315ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:08.656514Z","caller":"traceutil/trace.go:171","msg":"trace[1455326980] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"136.531168ms","start":"2026-02-19T00:38:08.519967Z","end":"2026-02-19T00:38:08.656498Z","steps":["trace[1455326980] 'process raft request' (duration: 123.635282ms)","trace[1455326980] 'compare' (duration: 12.176494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:10.75932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:11.259733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:11.732203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493032982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5087"} {"level":"info","ts":"2026-02-19T00:38:11.732262Z","caller":"traceutil/trace.go:171","msg":"trace[390706426] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:348; }","duration":"1.493112322s","start":"2026-02-19T00:38:10.239135Z","end":"2026-02-19T00:38:11.732248Z","steps":["trace[390706426] 'range keys from in-memory index tree' (duration: 1.492952423s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.732288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.239091Z","time spent":"1.49319186s","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5110,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-19T00:38:11.732483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654246275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:11.732496Z","caller":"traceutil/trace.go:171","msg":"trace[1695551265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:348; }","duration":"1.654279974s","start":"2026-02-19T00:38:10.078212Z","end":"2026-02-19T00:38:11.732492Z","steps":["trace[1695551265] 'range keys from in-memory index tree' (duration: 1.654192245s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.732509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.078198Z","time spent":"1.654307414s","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T00:38:11.732585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522696984s","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-19T00:38:11.732598Z","caller":"traceutil/trace.go:171","msg":"trace[246116448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"1.522709784s","start":"2026-02-19T00:38:10.209883Z","end":"2026-02-19T00:38:11.732593Z","steps":["trace[246116448] 'range keys from in-memory index tree' (duration: 1.522650685s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.732609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.209872Z","time spent":"1.522734293s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-19T00:38:11.732604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546880398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T00:38:11.732683Z","caller":"traceutil/trace.go:171","msg":"trace[737492534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"1.546999697s","start":"2026-02-19T00:38:10.185663Z","end":"2026-02-19T00:38:11.732662Z","steps":["trace[737492534] 'range keys from in-memory index tree' (duration: 1.546791169s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.732731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.185649Z","time spent":"1.547073926s","remote":"127.0.0.1:40670","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-19T00:38:11.732818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622864792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-19T00:38:11.732837Z","caller":"traceutil/trace.go:171","msg":"trace[852455645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"1.622907362s","start":"2026-02-19T00:38:10.109925Z","end":"2026-02-19T00:38:11.732832Z","steps":["trace[852455645] 'range keys from in-memory index tree' (duration: 1.622781413s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.732849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.109913Z","time spent":"1.622933251s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:11.732964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573087389s","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-19T00:38:11.732996Z","caller":"traceutil/trace.go:171","msg":"trace[1898778108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"1.573142669s","start":"2026-02-19T00:38:10.159843Z","end":"2026-02-19T00:38:11.732986Z","steps":["trace[1898778108] 'range keys from in-memory index tree' (duration: 1.57303407s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.733021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.159829Z","time spent":"1.573186328s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-19T00:38:11.760865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527279,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:11.970008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277216085s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T00:38:11.970335Z","caller":"traceutil/trace.go:171","msg":"trace[2130171980] transaction","detail":"{read_only:false; number_of_response:0; response_revision:348; }","duration":"1.728818096s","start":"2026-02-19T00:38:10.241498Z","end":"2026-02-19T00:38:11.970316Z","steps":["trace[2130171980] 'process raft request' (duration: 1.728753287s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:11.97043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.241481Z","time spent":"1.728896586s","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T00:38:13.735651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996538078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-19T00:38:13.735772Z","caller":"traceutil/trace.go:171","msg":"trace[715027864] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.996688256s","start":"2026-02-19T00:38:11.739062Z","end":"2026-02-19T00:38:13.73575Z","steps":["trace[715027864] 'agreement among raft nodes before linearized reading' (duration: 1.996552408s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:13.735979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.739051Z","time spent":"1.996910544s","remote":"127.0.0.1:40362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/19 00:38:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-19T00:38:14.516841Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.546638625s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T00:38:14.517385Z","caller":"traceutil/trace.go:171","msg":"trace[277971408] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:363; }","duration":"4.258503935s","start":"2026-02-19T00:38:10.25886Z","end":"2026-02-19T00:38:14.517364Z","steps":["trace[277971408] 'read index received' (duration: 1.711492794s)","trace[277971408] 'applied index is now lower than readState.Index' (duration: 2.547010091s)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:14.517628Z","caller":"traceutil/trace.go:171","msg":"trace[1412934270] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"2.768715137s","start":"2026-02-19T00:38:11.748882Z","end":"2026-02-19T00:38:14.517597Z","steps":["trace[1412934270] 'process raft request' (duration: 2.768195243s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.517725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.748874Z","time spent":"2.768783046s","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:14.517918Z","caller":"traceutil/trace.go:171","msg":"trace[833694196] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"2.768966694s","start":"2026-02-19T00:38:11.748942Z","end":"2026-02-19T00:38:14.517909Z","steps":["trace[833694196] 'process raft request' (duration: 2.768269003s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.51797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.748937Z","time spent":"2.769003583s","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:14.518148Z","caller":"traceutil/trace.go:171","msg":"trace[529154418] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"2.769126082s","start":"2026-02-19T00:38:11.749011Z","end":"2026-02-19T00:38:14.518137Z","steps":["trace[529154418] 'process raft request' (duration: 2.768238982s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.518197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.749007Z","time spent":"2.769162891s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:14.518297Z","caller":"traceutil/trace.go:171","msg":"trace[551494688] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"2.769211681s","start":"2026-02-19T00:38:11.749078Z","end":"2026-02-19T00:38:14.51829Z","steps":["trace[551494688] 'process raft request' (duration: 2.768211573s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.518339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.749075Z","time spent":"2.769242401s","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:14.51853Z","caller":"traceutil/trace.go:171","msg":"trace[779578061] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"2.759854449s","start":"2026-02-19T00:38:11.758667Z","end":"2026-02-19T00:38:14.518521Z","steps":["trace[779578061] 'process raft request' (duration: 2.758655613s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.518577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.758652Z","time spent":"2.759899979s","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5073,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:14.518704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.259839139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-19T00:38:14.518734Z","caller":"traceutil/trace.go:171","msg":"trace[1419869007] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:353; }","duration":"4.259867679s","start":"2026-02-19T00:38:10.258857Z","end":"2026-02-19T00:38:14.518725Z","steps":["trace[1419869007] 'agreement among raft nodes before linearized reading' (duration: 4.259811929s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.51876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.258833Z","time spent":"4.259920588s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.791304Z","caller":"traceutil/trace.go:171","msg":"trace[418644520] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"273.862963ms","start":"2026-02-19T00:38:14.517419Z","end":"2026-02-19T00:38:14.791282Z","steps":["trace[418644520] 'read index received' (duration: 201.206715ms)","trace[418644520] 'applied index is now lower than readState.Index' (duration: 72.655328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:14.791664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.482266709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-19T00:38:14.792169Z","caller":"traceutil/trace.go:171","msg":"trace[290208713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.482805983s","start":"2026-02-19T00:38:10.309331Z","end":"2026-02-19T00:38:14.792137Z","steps":["trace[290208713] 'agreement among raft nodes before linearized reading' (duration: 4.48221977s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.792298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.309257Z","time spent":"4.483025701s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:14.791699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.093710729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-19T00:38:14.792715Z","caller":"traceutil/trace.go:171","msg":"trace[948598770] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:354; }","duration":"4.094762947s","start":"2026-02-19T00:38:10.697936Z","end":"2026-02-19T00:38:14.792699Z","steps":["trace[948598770] 'agreement among raft nodes before linearized reading' (duration: 4.09366982s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.792926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.697922Z","time spent":"4.094988853s","remote":"127.0.0.1:40844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-19T00:38:14.79317Z","caller":"traceutil/trace.go:171","msg":"trace[1601612279] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"248.580883ms","start":"2026-02-19T00:38:14.544575Z","end":"2026-02-19T00:38:14.793155Z","steps":["trace[1601612279] 'process raft request' (duration: 248.541124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.791739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.181996652s","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-19T00:38:14.793583Z","caller":"traceutil/trace.go:171","msg":"trace[687278540] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.18386657s","start":"2026-02-19T00:38:10.609701Z","end":"2026-02-19T00:38:14.793568Z","steps":["trace[687278540] 'agreement among raft nodes before linearized reading' (duration: 4.182009332s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.793767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.609685Z","time spent":"4.184066038s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.791746Z","caller":"traceutil/trace.go:171","msg":"trace[1025077009] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.053750084s","start":"2026-02-19T00:38:13.737983Z","end":"2026-02-19T00:38:14.791733Z","steps":["trace[1025077009] 'process raft request' (duration: 980.560532ms)","trace[1025077009] 'compare' (duration: 72.383942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:14.795175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:13.737965Z","time spent":"1.057140644s","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T00:38:14.791783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.231609797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-19T00:38:14.791789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.818102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T00:38:14.791823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.267050553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-19T00:38:14.791859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.413567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-19T00:38:14.79189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759591792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T00:38:14.791865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.268013562s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T00:38:14.791933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.820603987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7035"} {"level":"warn","ts":"2026-02-19T00:38:14.791943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.281989513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-02-19T00:38:14.791972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.043125043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-19T00:38:14.791981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.332938732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-19T00:38:14.792008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.05131333s","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":"warn","ts":"2026-02-19T00:38:14.792019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.382195261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-19T00:38:14.792045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.812317856s","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-19T00:38:14.792055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.433490665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T00:38:14.794634Z","caller":"traceutil/trace.go:171","msg":"trace[282343373] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"253.91831ms","start":"2026-02-19T00:38:14.540687Z","end":"2026-02-19T00:38:14.794606Z","steps":["trace[282343373] 'process raft request' (duration: 252.336229ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:14.798897Z","caller":"traceutil/trace.go:171","msg":"trace[1387159251] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.238753662s","start":"2026-02-19T00:38:10.560134Z","end":"2026-02-19T00:38:14.798888Z","steps":["trace[1387159251] 'agreement among raft nodes before linearized reading' (duration: 4.231626267s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.799913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.560114Z","time spent":"4.23978728s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.79894Z","caller":"traceutil/trace.go:171","msg":"trace[371110419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:354; }","duration":"658.969538ms","start":"2026-02-19T00:38:14.139964Z","end":"2026-02-19T00:38:14.798934Z","steps":["trace[371110419] 'agreement among raft nodes before linearized reading' (duration: 651.803103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.800016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.139911Z","time spent":"660.096604ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T00:38:14.799026Z","caller":"traceutil/trace.go:171","msg":"trace[909368478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:354; }","duration":"4.274285388s","start":"2026-02-19T00:38:10.524737Z","end":"2026-02-19T00:38:14.799022Z","steps":["trace[909368478] 'agreement among raft nodes before linearized reading' (duration: 4.267063503s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.800115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.524721Z","time spent":"4.275389094s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T00:38:14.799112Z","caller":"traceutil/trace.go:171","msg":"trace[20218524] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:1; response_revision:354; }","duration":"900.733801ms","start":"2026-02-19T00:38:13.898373Z","end":"2026-02-19T00:38:14.799107Z","steps":["trace[20218524] 'agreement among raft nodes before linearized reading' (duration: 893.434537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.800179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:13.898355Z","time spent":"901.820438ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"info","ts":"2026-02-19T00:38:14.799171Z","caller":"traceutil/trace.go:171","msg":"trace[1466839984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"1.766894146s","start":"2026-02-19T00:38:13.032273Z","end":"2026-02-19T00:38:14.799167Z","steps":["trace[1466839984] 'agreement among raft nodes before linearized reading' (duration: 1.759601902s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.80026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:13.03226Z","time spent":"1.767996772s","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T00:38:14.799235Z","caller":"traceutil/trace.go:171","msg":"trace[373908419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:354; }","duration":"4.275389254s","start":"2026-02-19T00:38:10.523841Z","end":"2026-02-19T00:38:14.799231Z","steps":["trace[373908419] 'agreement among raft nodes before linearized reading' (duration: 4.267997362s)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:14.799308Z","caller":"traceutil/trace.go:171","msg":"trace[146420982] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:354; }","duration":"2.828008519s","start":"2026-02-19T00:38:11.971294Z","end":"2026-02-19T00:38:14.799303Z","steps":["trace[146420982] 'agreement among raft nodes before linearized reading' (duration: 2.820612247s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.802508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.97128Z","time spent":"2.831213071s","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7058,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-19T00:38:14.799367Z","caller":"traceutil/trace.go:171","msg":"trace[532715297] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.289449575s","start":"2026-02-19T00:38:10.509912Z","end":"2026-02-19T00:38:14.799361Z","steps":["trace[532715297] 'agreement among raft nodes before linearized reading' (duration: 4.282009573s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.803383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.509896Z","time spent":"4.293474866s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.799435Z","caller":"traceutil/trace.go:171","msg":"trace[1469674317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:354; }","duration":"3.050606885s","start":"2026-02-19T00:38:11.748823Z","end":"2026-02-19T00:38:14.79943Z","steps":["trace[1469674317] 'agreement among raft nodes before linearized reading' (duration: 3.043125393s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.803888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.748813Z","time spent":"3.055063672s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-02-19T00:38:14.799496Z","caller":"traceutil/trace.go:171","msg":"trace[601811296] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.340482722s","start":"2026-02-19T00:38:10.459009Z","end":"2026-02-19T00:38:14.799492Z","steps":["trace[601811296] 'agreement among raft nodes before linearized reading' (duration: 4.332953131s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.804029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.458994Z","time spent":"4.345028668s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.799612Z","caller":"traceutil/trace.go:171","msg":"trace[1768721675] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:354; }","duration":"3.058927029s","start":"2026-02-19T00:38:11.74068Z","end":"2026-02-19T00:38:14.799607Z","steps":["trace[1768721675] 'agreement among raft nodes before linearized reading' (duration: 3.051307079s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.804297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:11.740675Z","time spent":"3.063455065s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-02-19T00:38:14.799627Z","caller":"traceutil/trace.go:171","msg":"trace[859991034] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.389829809s","start":"2026-02-19T00:38:10.409792Z","end":"2026-02-19T00:38:14.799622Z","steps":["trace[859991034] 'agreement among raft nodes before linearized reading' (duration: 4.38220424s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.804417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.409762Z","time spent":"4.394648262s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-02-19T00:38:14.799686Z","caller":"traceutil/trace.go:171","msg":"trace[1487608741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"3.819982865s","start":"2026-02-19T00:38:10.979698Z","end":"2026-02-19T00:38:14.799681Z","steps":["trace[1487608741] 'agreement among raft nodes before linearized reading' (duration: 3.812324316s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.804566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.979685Z","time spent":"3.824852867s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T00:38:14.799783Z","caller":"traceutil/trace.go:171","msg":"trace[608822451] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:354; }","duration":"4.441238854s","start":"2026-02-19T00:38:10.358533Z","end":"2026-02-19T00:38:14.799772Z","steps":["trace[608822451] 'agreement among raft nodes before linearized reading' (duration: 4.433501705s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:14.804684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:10.358519Z","time spent":"4.446155585s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.151349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.406649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T00:38:15.151765Z","caller":"traceutil/trace.go:171","msg":"trace[849109654] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"394.833813ms","start":"2026-02-19T00:38:14.756907Z","end":"2026-02-19T00:38:15.151741Z","steps":["trace[849109654] 'process raft request' (duration: 188.95165ms)","trace[849109654] 'compare' (duration: 205.180101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:15.15194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.756879Z","time spent":"394.939342ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:15.153076Z","caller":"traceutil/trace.go:171","msg":"trace[263380352] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"344.390171ms","start":"2026-02-19T00:38:14.808675Z","end":"2026-02-19T00:38:15.153065Z","steps":["trace[263380352] 'process raft request' (duration: 343.058627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.153241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.808654Z","time spent":"344.457ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:15.153405Z","caller":"traceutil/trace.go:171","msg":"trace[609535152] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"361.30053ms","start":"2026-02-19T00:38:14.792095Z","end":"2026-02-19T00:38:15.153395Z","steps":["trace[609535152] 'read index received' (duration: 1.409713ms)","trace[609535152] 'applied index is now lower than readState.Index' (duration: 359.889917ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:15.153526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.808551Z","time spent":"344.973103ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T00:38:15.153854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.815566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-19T00:38:15.153892Z","caller":"traceutil/trace.go:171","msg":"trace[1941118275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:363; }","duration":"619.864685ms","start":"2026-02-19T00:38:14.534018Z","end":"2026-02-19T00:38:15.153883Z","steps":["trace[1941118275] 'agreement among raft nodes before linearized reading' (duration: 619.775656ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.153423Z","caller":"traceutil/trace.go:171","msg":"trace[578289238] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"331.429454ms","start":"2026-02-19T00:38:14.821944Z","end":"2026-02-19T00:38:15.153373Z","steps":["trace[578289238] 'process raft request' (duration: 331.216637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.154035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.534014Z","time spent":"619.896105ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.154319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.351099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7035"} {"level":"info","ts":"2026-02-19T00:38:15.154728Z","caller":"traceutil/trace.go:171","msg":"trace[1094027707] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:363; }","duration":"620.458758ms","start":"2026-02-19T00:38:14.533936Z","end":"2026-02-19T00:38:15.154394Z","steps":["trace[1094027707] 'agreement among raft nodes before linearized reading' (duration: 620.344289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.154752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.533923Z","time spent":"620.822494ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7058,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-19T00:38:15.15505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.323407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-19T00:38:15.155077Z","caller":"traceutil/trace.go:171","msg":"trace[1586881388] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:363; }","duration":"346.362607ms","start":"2026-02-19T00:38:14.808708Z","end":"2026-02-19T00:38:15.155071Z","steps":["trace[1586881388] 'agreement among raft nodes before linearized reading' (duration: 346.315417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.155173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.808704Z","time spent":"346.461456ms","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3863,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-19T00:38:15.155621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.198646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T00:38:15.15565Z","caller":"traceutil/trace.go:171","msg":"trace[16865485] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:363; }","duration":"337.234985ms","start":"2026-02-19T00:38:14.818408Z","end":"2026-02-19T00:38:15.155643Z","steps":["trace[16865485] 'agreement among raft nodes before linearized reading' (duration: 337.192015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.155668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.818405Z","time spent":"337.258645ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.155924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.514252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-19T00:38:15.155956Z","caller":"traceutil/trace.go:171","msg":"trace[1929526768] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:363; }","duration":"337.555971ms","start":"2026-02-19T00:38:14.818392Z","end":"2026-02-19T00:38:15.155948Z","steps":["trace[1929526768] 'agreement among raft nodes before linearized reading' (duration: 337.487642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.156063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.818389Z","time spent":"337.6614ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.156243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.851038ms","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-19T00:38:15.156268Z","caller":"traceutil/trace.go:171","msg":"trace[898204807] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:363; }","duration":"337.884877ms","start":"2026-02-19T00:38:14.818377Z","end":"2026-02-19T00:38:15.156262Z","steps":["trace[898204807] 'agreement among raft nodes before linearized reading' (duration: 337.841718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.156362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.818375Z","time spent":"337.979276ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.156581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.222864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-02-19T00:38:15.154225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.821938Z","time spent":"332.106856ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:15.15661Z","caller":"traceutil/trace.go:171","msg":"trace[1941686623] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:363; }","duration":"338.262163ms","start":"2026-02-19T00:38:14.81834Z","end":"2026-02-19T00:38:15.156602Z","steps":["trace[1941686623] 'agreement among raft nodes before linearized reading' (duration: 338.204494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.157148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.818334Z","time spent":"338.805307ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-02-19T00:38:15.156774Z","caller":"traceutil/trace.go:171","msg":"trace[821248999] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"347.986657ms","start":"2026-02-19T00:38:14.808776Z","end":"2026-02-19T00:38:15.156763Z","steps":["trace[821248999] 'process raft request' (duration: 343.009267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.157451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.80877Z","time spent":"348.5996ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:15.156799Z","caller":"traceutil/trace.go:171","msg":"trace[1658842192] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"340.643085ms","start":"2026-02-19T00:38:14.81615Z","end":"2026-02-19T00:38:15.156793Z","steps":["trace[1658842192] 'process raft request' (duration: 335.771154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.158212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.816139Z","time spent":"341.972328ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:15.156904Z","caller":"traceutil/trace.go:171","msg":"trace[361688245] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"336.473374ms","start":"2026-02-19T00:38:14.820424Z","end":"2026-02-19T00:38:15.156897Z","steps":["trace[361688245] 'process raft request' (duration: 332.589841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.158535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.820417Z","time spent":"338.026665ms","remote":"127.0.0.1:40482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:15.156927Z","caller":"traceutil/trace.go:171","msg":"trace[382996353] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"335.034742ms","start":"2026-02-19T00:38:14.821887Z","end":"2026-02-19T00:38:15.156922Z","steps":["trace[382996353] 'process raft request' (duration: 331.170368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.158855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.821874Z","time spent":"336.897509ms","remote":"127.0.0.1:40482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:15.159308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.542797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-19T00:38:15.159338Z","caller":"traceutil/trace.go:171","msg":"trace[1319146317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:363; }","duration":"350.579937ms","start":"2026-02-19T00:38:14.80875Z","end":"2026-02-19T00:38:15.15933Z","steps":["trace[1319146317] 'agreement among raft nodes before linearized reading' (duration: 350.530177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.159424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.808747Z","time spent":"350.669566ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.159574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.830044ms","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-19T00:38:15.159603Z","caller":"traceutil/trace.go:171","msg":"trace[385143787] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:363; }","duration":"350.880563ms","start":"2026-02-19T00:38:14.808714Z","end":"2026-02-19T00:38:15.159595Z","steps":["trace[385143787] 'agreement among raft nodes before linearized reading' (duration: 350.835693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.159688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.808702Z","time spent":"350.977592ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-19T00:38:15.156741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.837407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-19T00:38:15.160401Z","caller":"traceutil/trace.go:171","msg":"trace[1271216941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:363; }","duration":"347.498303ms","start":"2026-02-19T00:38:14.812895Z","end":"2026-02-19T00:38:15.160393Z","steps":["trace[1271216941] 'agreement among raft nodes before linearized reading' (duration: 343.788837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.160515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:14.812878Z","time spent":"347.538503ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-19T00:38:15.395069Z","caller":"traceutil/trace.go:171","msg":"trace[1125024263] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"227.38505ms","start":"2026-02-19T00:38:15.16766Z","end":"2026-02-19T00:38:15.395045Z","steps":["trace[1125024263] 'read index received' (duration: 210.772939ms)","trace[1125024263] 'applied index is now lower than readState.Index' (duration: 16.611061ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:15.395167Z","caller":"traceutil/trace.go:171","msg":"trace[1311047013] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"227.593538ms","start":"2026-02-19T00:38:15.167563Z","end":"2026-02-19T00:38:15.395157Z","steps":["trace[1311047013] 'process raft request' (duration: 210.66644ms)","trace[1311047013] 'compare' (duration: 16.685041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:15.395626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.945404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:15.395745Z","caller":"traceutil/trace.go:171","msg":"trace[120976166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:364; }","duration":"228.095482ms","start":"2026-02-19T00:38:15.167631Z","end":"2026-02-19T00:38:15.395727Z","steps":["trace[120976166] 'agreement among raft nodes before linearized reading' (duration: 227.904124ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.404087Z","caller":"traceutil/trace.go:171","msg":"trace[1141001290] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"213.107661ms","start":"2026-02-19T00:38:15.190961Z","end":"2026-02-19T00:38:15.404069Z","steps":["trace[1141001290] 'process raft request' (duration: 213.071282ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.406923Z","caller":"traceutil/trace.go:171","msg":"trace[1237720870] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"236.652679ms","start":"2026-02-19T00:38:15.170262Z","end":"2026-02-19T00:38:15.406915Z","steps":["trace[1237720870] 'process raft request' (duration: 233.20122ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.40711Z","caller":"traceutil/trace.go:171","msg":"trace[1012485] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"234.788841ms","start":"2026-02-19T00:38:15.172313Z","end":"2026-02-19T00:38:15.407102Z","steps":["trace[1012485] 'process raft request' (duration: 231.267263ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.407289Z","caller":"traceutil/trace.go:171","msg":"trace[572954462] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"231.122046ms","start":"2026-02-19T00:38:15.176159Z","end":"2026-02-19T00:38:15.407281Z","steps":["trace[572954462] 'process raft request' (duration: 227.466609ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.407602Z","caller":"traceutil/trace.go:171","msg":"trace[1729196056] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"229.206688ms","start":"2026-02-19T00:38:15.178388Z","end":"2026-02-19T00:38:15.407595Z","steps":["trace[1729196056] 'process raft request' (duration: 225.277165ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.408965Z","caller":"traceutil/trace.go:171","msg":"trace[2116739134] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"230.391364ms","start":"2026-02-19T00:38:15.178566Z","end":"2026-02-19T00:38:15.408958Z","steps":["trace[2116739134] 'process raft request' (duration: 225.134647ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.40922Z","caller":"traceutil/trace.go:171","msg":"trace[318138408] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"227.717806ms","start":"2026-02-19T00:38:15.181495Z","end":"2026-02-19T00:38:15.409213Z","steps":["trace[318138408] 'process raft request' (duration: 222.243151ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.40968Z","caller":"traceutil/trace.go:171","msg":"trace[234962852] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"226.754607ms","start":"2026-02-19T00:38:15.182919Z","end":"2026-02-19T00:38:15.409673Z","steps":["trace[234962852] 'process raft request' (duration: 220.861908ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.411974Z","caller":"traceutil/trace.go:171","msg":"trace[1168328038] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"222.401979ms","start":"2026-02-19T00:38:15.189515Z","end":"2026-02-19T00:38:15.411917Z","steps":["trace[1168328038] 'process raft request' (duration: 214.298036ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.412215Z","caller":"traceutil/trace.go:171","msg":"trace[370173603] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"222.560158ms","start":"2026-02-19T00:38:15.189648Z","end":"2026-02-19T00:38:15.412208Z","steps":["trace[370173603] 'process raft request' (duration: 214.203038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.412306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.768416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T00:38:15.415262Z","caller":"traceutil/trace.go:171","msg":"trace[425356956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"245.7423ms","start":"2026-02-19T00:38:15.169513Z","end":"2026-02-19T00:38:15.415255Z","steps":["trace[425356956] 'agreement among raft nodes before linearized reading' (duration: 242.564648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:15.412389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.606928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5087"} {"level":"info","ts":"2026-02-19T00:38:15.415545Z","caller":"traceutil/trace.go:171","msg":"trace[693588749] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:377; }","duration":"225.774179ms","start":"2026-02-19T00:38:15.189765Z","end":"2026-02-19T00:38:15.415539Z","steps":["trace[693588749] 'agreement among raft nodes before linearized reading' (duration: 222.595017ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.412615Z","caller":"traceutil/trace.go:171","msg":"trace[1763680698] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"222.927813ms","start":"2026-02-19T00:38:15.189678Z","end":"2026-02-19T00:38:15.412605Z","steps":["trace[1763680698] 'process raft request' (duration: 214.200787ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.412706Z","caller":"traceutil/trace.go:171","msg":"trace[1394615784] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"222.979803ms","start":"2026-02-19T00:38:15.189718Z","end":"2026-02-19T00:38:15.412698Z","steps":["trace[1394615784] 'process raft request' (duration: 214.191608ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.412731Z","caller":"traceutil/trace.go:171","msg":"trace[613893928] transaction","detail":"{read_only:false; number_of_response:1; response_revision:375; }","duration":"222.967853ms","start":"2026-02-19T00:38:15.189758Z","end":"2026-02-19T00:38:15.412726Z","steps":["trace[613893928] 'process raft request' (duration: 214.192278ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:15.412753Z","caller":"traceutil/trace.go:171","msg":"trace[205521399] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"222.924563ms","start":"2026-02-19T00:38:15.18982Z","end":"2026-02-19T00:38:15.412745Z","steps":["trace[205521399] 'process raft request' (duration: 214.169858ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:18.747778Z","caller":"traceutil/trace.go:171","msg":"trace[149375053] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"190.321027ms","start":"2026-02-19T00:38:18.55743Z","end":"2026-02-19T00:38:18.747751Z","steps":["trace[149375053] 'process raft request' (duration: 190.210998ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:23.728743Z","caller":"traceutil/trace.go:171","msg":"trace[1756548121] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"205.861222ms","start":"2026-02-19T00:38:23.52286Z","end":"2026-02-19T00:38:23.728721Z","steps":["trace[1756548121] 'read index received' (duration: 205.688303ms)","trace[1756548121] 'applied index is now lower than readState.Index' (duration: 172.029µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:23.729243Z","caller":"traceutil/trace.go:171","msg":"trace[1496320601] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"241.125768ms","start":"2026-02-19T00:38:23.488103Z","end":"2026-02-19T00:38:23.729229Z","steps":["trace[1496320601] 'process raft request' (duration: 240.496062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:23.729327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.457725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-19T00:38:23.729896Z","caller":"traceutil/trace.go:171","msg":"trace[976925977] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:443; }","duration":"207.034188ms","start":"2026-02-19T00:38:23.522848Z","end":"2026-02-19T00:38:23.729882Z","steps":["trace[976925977] 'agreement among raft nodes before linearized reading' (duration: 206.431744ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:24.28067Z","caller":"traceutil/trace.go:171","msg":"trace[639293727] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"407.757972ms","start":"2026-02-19T00:38:23.872893Z","end":"2026-02-19T00:38:24.280651Z","steps":["trace[639293727] 'process raft request' (duration: 339.675396ms)","trace[639293727] 'compare' (duration: 67.79421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:24.28077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:23.872871Z","time spent":"407.846997ms","remote":"127.0.0.1:40944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:24.286586Z","caller":"traceutil/trace.go:171","msg":"trace[1983626459] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:472; }","duration":"387.876407ms","start":"2026-02-19T00:38:23.898698Z","end":"2026-02-19T00:38:24.286574Z","steps":["trace[1983626459] 'read index received' (duration: 315.832542ms)","trace[1983626459] 'applied index is now lower than readState.Index' (duration: 72.043085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:24.28679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.076058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T00:38:24.286829Z","caller":"traceutil/trace.go:171","msg":"trace[1338114419] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:1; response_revision:454; }","duration":"388.146132ms","start":"2026-02-19T00:38:23.898676Z","end":"2026-02-19T00:38:24.286822Z","steps":["trace[1338114419] 'agreement among raft nodes before linearized reading' (duration: 388.064877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:24.286856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:23.89867Z","time spent":"388.180743ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"info","ts":"2026-02-19T00:38:24.286695Z","caller":"traceutil/trace.go:171","msg":"trace[1967917324] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"388.11001ms","start":"2026-02-19T00:38:23.898567Z","end":"2026-02-19T00:38:24.286677Z","steps":["trace[1967917324] 'process raft request' (duration: 387.943751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:24.287057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.591142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:24.287082Z","caller":"traceutil/trace.go:171","msg":"trace[1453691736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"205.655976ms","start":"2026-02-19T00:38:24.08142Z","end":"2026-02-19T00:38:24.287076Z","steps":["trace[1453691736] 'agreement among raft nodes before linearized reading' (duration: 205.619574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:24.287207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:23.898543Z","time spent":"388.494021ms","remote":"127.0.0.1:40944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:24.90179Z","caller":"traceutil/trace.go:171","msg":"trace[59462922] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"121.445018ms","start":"2026-02-19T00:38:24.780329Z","end":"2026-02-19T00:38:24.901774Z","steps":["trace[59462922] 'process raft request' (duration: 121.350172ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:27.456607Z","caller":"traceutil/trace.go:171","msg":"trace[102184217] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"105.334293ms","start":"2026-02-19T00:38:27.351258Z","end":"2026-02-19T00:38:27.456592Z","steps":["trace[102184217] 'read index received' (duration: 105.196176ms)","trace[102184217] 'applied index is now lower than readState.Index' (duration: 137.607µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:27.456717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.442068ms","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-19T00:38:27.456734Z","caller":"traceutil/trace.go:171","msg":"trace[1816006591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"105.501081ms","start":"2026-02-19T00:38:27.351228Z","end":"2026-02-19T00:38:27.456729Z","steps":["trace[1816006591] 'agreement among raft nodes before linearized reading' (duration: 105.431717ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:27.456962Z","caller":"traceutil/trace.go:171","msg":"trace[463821026] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"124.953541ms","start":"2026-02-19T00:38:27.332001Z","end":"2026-02-19T00:38:27.456955Z","steps":["trace[463821026] 'process raft request' (duration: 124.500007ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:29.89269Z","caller":"traceutil/trace.go:171","msg":"trace[424812265] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"106.786951ms","start":"2026-02-19T00:38:29.785881Z","end":"2026-02-19T00:38:29.892668Z","steps":["trace[424812265] 'process raft request' (duration: 106.675375ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:35.169845Z","caller":"traceutil/trace.go:171","msg":"trace[760412506] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"398.71649ms","start":"2026-02-19T00:38:34.771114Z","end":"2026-02-19T00:38:35.169831Z","steps":["trace[760412506] 'process raft request' (duration: 389.803505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.170083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.771101Z","time spent":"398.924809ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3600,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:35.17297Z","caller":"traceutil/trace.go:171","msg":"trace[341952996] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"401.745468ms","start":"2026-02-19T00:38:34.771215Z","end":"2026-02-19T00:38:35.172961Z","steps":["trace[341952996] 'process raft request' (duration: 399.063226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.173115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.771196Z","time spent":"401.872334ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:35.173382Z","caller":"traceutil/trace.go:171","msg":"trace[1697329503] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"391.174428ms","start":"2026-02-19T00:38:34.7822Z","end":"2026-02-19T00:38:35.173374Z","steps":["trace[1697329503] 'process raft request' (duration: 388.158322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.17351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.782189Z","time spent":"391.280274ms","remote":"127.0.0.1:40944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:35.173878Z","caller":"traceutil/trace.go:171","msg":"trace[988814150] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"391.322185ms","start":"2026-02-19T00:38:34.782545Z","end":"2026-02-19T00:38:35.173868Z","steps":["trace[988814150] 'process raft request' (duration: 387.945262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.175381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.782537Z","time spent":"392.823583ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:35.175558Z","caller":"traceutil/trace.go:171","msg":"trace[2101413130] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"393.147398ms","start":"2026-02-19T00:38:34.782403Z","end":"2026-02-19T00:38:35.175551Z","steps":["trace[2101413130] 'process raft request' (duration: 388.017105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.175672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.782397Z","time spent":"393.235952ms","remote":"127.0.0.1:40938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:35.175874Z","caller":"traceutil/trace.go:171","msg":"trace[516883427] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"393.439201ms","start":"2026-02-19T00:38:34.782427Z","end":"2026-02-19T00:38:35.175866Z","steps":["trace[516883427] 'process raft request' (duration: 388.033236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.175988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.782418Z","time spent":"393.533635ms","remote":"127.0.0.1:40912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4595,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:35.176874Z","caller":"traceutil/trace.go:171","msg":"trace[556331687] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"394.29677ms","start":"2026-02-19T00:38:34.782569Z","end":"2026-02-19T00:38:35.176866Z","steps":["trace[556331687] 'process raft request' (duration: 387.947222ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:35.173182Z","caller":"traceutil/trace.go:171","msg":"trace[1146975417] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"401.869503ms","start":"2026-02-19T00:38:34.77129Z","end":"2026-02-19T00:38:35.173159Z","steps":["trace[1146975417] 'read index received' (duration: 389.673009ms)","trace[1146975417] 'applied index is now lower than readState.Index' (duration: 12.193664ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:35.177739Z","caller":"traceutil/trace.go:171","msg":"trace[872910532] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"359.114024ms","start":"2026-02-19T00:38:34.818614Z","end":"2026-02-19T00:38:35.177728Z","steps":["trace[872910532] 'process raft request' (duration: 352.19683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.182986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.818589Z","time spent":"364.363292ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:35.183223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.439154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:38:35.183303Z","caller":"traceutil/trace.go:171","msg":"trace[230977409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"212.565769ms","start":"2026-02-19T00:38:34.970728Z","end":"2026-02-19T00:38:35.183294Z","steps":["trace[230977409] 'agreement among raft nodes before linearized reading' (duration: 212.457554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.177861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.554205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k4q9m\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-19T00:38:35.183536Z","caller":"traceutil/trace.go:171","msg":"trace[1986267747] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k4q9m; range_end:; response_count:1; response_revision:523; }","duration":"412.261534ms","start":"2026-02-19T00:38:34.771268Z","end":"2026-02-19T00:38:35.183529Z","steps":["trace[1986267747] 'agreement among raft nodes before linearized reading' (duration: 406.486002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.18362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.771263Z","time spent":"412.349168ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14944,"request content":"key:\"/registry/pods/kube-system/cilium-k4q9m\" "} {"level":"warn","ts":"2026-02-19T00:38:35.183951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.272919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-19T00:38:35.184501Z","caller":"traceutil/trace.go:171","msg":"trace[547354291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:523; }","duration":"185.845075ms","start":"2026-02-19T00:38:34.998649Z","end":"2026-02-19T00:38:35.184494Z","steps":["trace[547354291] 'agreement among raft nodes before linearized reading' (duration: 185.282299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:35.184929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:34.782561Z","time spent":"398.069062ms","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T00:38:35.763067Z","caller":"traceutil/trace.go:171","msg":"trace[100403931] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"150.13698ms","start":"2026-02-19T00:38:35.612771Z","end":"2026-02-19T00:38:35.762908Z","steps":["trace[100403931] 'process raft request' (duration: 148.633972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:37.992043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T00:38:38.083275Z","caller":"traceutil/trace.go:171","msg":"trace[1521776417] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"592.250084ms","start":"2026-02-19T00:38:37.491003Z","end":"2026-02-19T00:38:38.083253Z","steps":["trace[1521776417] 'read index received' (duration: 591.798545ms)","trace[1521776417] 'applied index is now lower than readState.Index' (duration: 450.719µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:38.083497Z","caller":"traceutil/trace.go:171","msg":"trace[707655584] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"808.557502ms","start":"2026-02-19T00:38:37.274927Z","end":"2026-02-19T00:38:38.083485Z","steps":["trace[707655584] 'process raft request' (duration: 807.867293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:38.083595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:37.27492Z","time spent":"808.612574ms","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:38.083886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.888442ms","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-19T00:38:38.08391Z","caller":"traceutil/trace.go:171","msg":"trace[1548613043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"592.945854ms","start":"2026-02-19T00:38:37.490958Z","end":"2026-02-19T00:38:38.083904Z","steps":["trace[1548613043] 'agreement among raft nodes before linearized reading' (duration: 592.869261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:38.083926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:37.49094Z","time spent":"592.981656ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T00:38:38.084044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.25714ms","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-19T00:38:38.084109Z","caller":"traceutil/trace.go:171","msg":"trace[2039273524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"556.361805ms","start":"2026-02-19T00:38:37.527716Z","end":"2026-02-19T00:38:38.084078Z","steps":["trace[2039273524] 'agreement among raft nodes before linearized reading' (duration: 556.152236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:38.084135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:37.527696Z","time spent":"556.434578ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T00:38:38.084062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.448559ms","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-19T00:38:38.084184Z","caller":"traceutil/trace.go:171","msg":"trace[146847921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"167.596385ms","start":"2026-02-19T00:38:37.916582Z","end":"2026-02-19T00:38:38.084179Z","steps":["trace[146847921] 'agreement among raft nodes before linearized reading' (duration: 167.449379ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:39.5367Z","caller":"traceutil/trace.go:171","msg":"trace[1599992843] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"294.6773ms","start":"2026-02-19T00:38:39.242006Z","end":"2026-02-19T00:38:39.536683Z","steps":["trace[1599992843] 'process raft request' (duration: 294.571135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:39.831246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.77272ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-19T00:38:40.413259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.443193ms","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-19T00:38:40.413309Z","caller":"traceutil/trace.go:171","msg":"trace[1357564614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"317.526046ms","start":"2026-02-19T00:38:40.09577Z","end":"2026-02-19T00:38:40.413296Z","steps":["trace[1357564614] 'range keys from in-memory index tree' (duration: 317.338038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:40.413331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:40.095756Z","time spent":"317.570218ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T00:38:40.413461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.318766ms","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-19T00:38:40.413483Z","caller":"traceutil/trace.go:171","msg":"trace[2042315246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"316.351787ms","start":"2026-02-19T00:38:40.097125Z","end":"2026-02-19T00:38:40.413477Z","steps":["trace[2042315246] 'range keys from in-memory index tree' (duration: 316.269134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:40.413496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:40.097118Z","time spent":"316.374518ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T00:38:40.413568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.511923ms","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-19T00:38:40.413584Z","caller":"traceutil/trace.go:171","msg":"trace[1088954979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"315.539684ms","start":"2026-02-19T00:38:40.098041Z","end":"2026-02-19T00:38:40.41358Z","steps":["trace[1088954979] 'range keys from in-memory index tree' (duration: 315.45404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:40.413598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:40.098034Z","time spent":"315.558225ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T00:38:46.259307Z","caller":"traceutil/trace.go:171","msg":"trace[1135988863] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"681.865507ms","start":"2026-02-19T00:38:45.577426Z","end":"2026-02-19T00:38:46.259292Z","steps":["trace[1135988863] 'process raft request' (duration: 681.783334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:46.259426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:45.577414Z","time spent":"681.963392ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:46.268987Z","caller":"traceutil/trace.go:171","msg":"trace[375189463] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"189.786929ms","start":"2026-02-19T00:38:46.079188Z","end":"2026-02-19T00:38:46.268975Z","steps":["trace[375189463] 'read index received' (duration: 180.572448ms)","trace[375189463] 'applied index is now lower than readState.Index' (duration: 9.214021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:38:46.269068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.867802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:46.269088Z","caller":"traceutil/trace.go:171","msg":"trace[1959020729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"189.918394ms","start":"2026-02-19T00:38:46.079163Z","end":"2026-02-19T00:38:46.269081Z","steps":["trace[1959020729] 'agreement among raft nodes before linearized reading' (duration: 189.852551ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:38:46.269269Z","caller":"traceutil/trace.go:171","msg":"trace[1995414768] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"498.157457ms","start":"2026-02-19T00:38:45.771103Z","end":"2026-02-19T00:38:46.269261Z","steps":["trace[1995414768] 'process raft request' (duration: 497.818805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:46.269315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:45.771087Z","time spent":"498.198299ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:47.870845Z","caller":"traceutil/trace.go:171","msg":"trace[1637889045] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"531.028812ms","start":"2026-02-19T00:38:47.339801Z","end":"2026-02-19T00:38:47.87083Z","steps":["trace[1637889045] 'process raft request' (duration: 530.944099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:47.870938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:47.339782Z","time spent":"531.108876ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:48.947885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:49.44842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:49.949576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:50.450158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345800090527724,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T00:38:50.614876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.349422775s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T00:38:50.615226Z","caller":"traceutil/trace.go:171","msg":"trace[782983063] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.349811388s","start":"2026-02-19T00:38:48.265398Z","end":"2026-02-19T00:38:50.61521Z","steps":["trace[782983063] 'process raft request' (duration: 2.349696094s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.615311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:48.265387Z","time spent":"2.349878501s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:38:50.778674Z","caller":"traceutil/trace.go:171","msg":"trace[2134506924] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"2.331427116s","start":"2026-02-19T00:38:48.447226Z","end":"2026-02-19T00:38:50.778653Z","steps":["trace[2134506924] 'read index received' (duration: 2.168308534s)","trace[2134506924] 'applied index is now lower than readState.Index' (duration: 163.117602ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:38:50.778791Z","caller":"traceutil/trace.go:171","msg":"trace[403117710] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"803.152173ms","start":"2026-02-19T00:38:49.975629Z","end":"2026-02-19T00:38:50.778781Z","steps":["trace[403117710] 'process raft request' (duration: 802.904314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.778965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.327917991s","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-19T00:38:50.779015Z","caller":"traceutil/trace.go:171","msg":"trace[840612634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"2.327973673s","start":"2026-02-19T00:38:48.451027Z","end":"2026-02-19T00:38:50.779Z","steps":["trace[840612634] 'agreement among raft nodes before linearized reading' (duration: 2.327830878s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.779042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:48.450994Z","time spent":"2.328042365s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T00:38:50.779181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.331953554s","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-19T00:38:50.779207Z","caller":"traceutil/trace.go:171","msg":"trace[2136395144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"2.331992986s","start":"2026-02-19T00:38:48.447199Z","end":"2026-02-19T00:38:50.779192Z","steps":["trace[2136395144] 'agreement among raft nodes before linearized reading' (duration: 2.331942734s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.779224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:48.447186Z","time spent":"2.332033597s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T00:38:50.779352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69918335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:38:50.779381Z","caller":"traceutil/trace.go:171","msg":"trace[2012805065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"1.699240002s","start":"2026-02-19T00:38:49.080131Z","end":"2026-02-19T00:38:50.779371Z","steps":["trace[2012805065] 'agreement among raft nodes before linearized reading' (duration: 1.69919242s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.779412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:49.080116Z","time spent":"1.699288454s","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T00:38:50.779467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:49.975608Z","time spent":"803.207975ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T00:38:50.779559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.322512697s","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-19T00:38:50.779616Z","caller":"traceutil/trace.go:171","msg":"trace[1798537638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"2.3225886s","start":"2026-02-19T00:38:48.457022Z","end":"2026-02-19T00:38:50.779611Z","steps":["trace[1798537638] 'agreement among raft nodes before linearized reading' (duration: 2.322493947s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:50.779667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:48.457012Z","time spent":"2.322618181s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T00:38:53.097532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.764711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T00:38:53.097601Z","caller":"traceutil/trace.go:171","msg":"trace[190573776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"479.871475ms","start":"2026-02-19T00:38:52.617712Z","end":"2026-02-19T00:38:53.097583Z","steps":["trace[190573776] 'range keys from in-memory index tree' (duration: 479.618096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:53.097638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:38:52.617697Z","time spent":"479.933186ms","remote":"127.0.0.1:40670","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-19T00:38:53.09796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.677073ms","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-19T00:38:53.09799Z","caller":"traceutil/trace.go:171","msg":"trace[1891433768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"297.747056ms","start":"2026-02-19T00:38:52.800234Z","end":"2026-02-19T00:38:53.097981Z","steps":["trace[1891433768] 'range keys from in-memory index tree' (duration: 297.567669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:53.098162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.235513ms","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-19T00:38:53.098189Z","caller":"traceutil/trace.go:171","msg":"trace[108459253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"292.276335ms","start":"2026-02-19T00:38:52.805905Z","end":"2026-02-19T00:38:53.098181Z","steps":["trace[108459253] 'range keys from in-memory index tree' (duration: 292.171122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:53.09833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.51528ms","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-19T00:38:53.098355Z","caller":"traceutil/trace.go:171","msg":"trace[2034108675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"291.55128ms","start":"2026-02-19T00:38:52.806797Z","end":"2026-02-19T00:38:53.098348Z","steps":["trace[2034108675] 'range keys from in-memory index tree' (duration: 291.454668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:55.803433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.67761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T00:38:55.803532Z","caller":"traceutil/trace.go:171","msg":"trace[1602552526] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:658; }","duration":"140.903998ms","start":"2026-02-19T00:38:55.66261Z","end":"2026-02-19T00:38:55.803514Z","steps":["trace[1602552526] 'count revisions from in-memory index tree' (duration: 140.559806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:38:55.803754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.024349ms","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-19T00:38:55.804326Z","caller":"traceutil/trace.go:171","msg":"trace[1827780232] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"131.627748ms","start":"2026-02-19T00:38:55.672677Z","end":"2026-02-19T00:38:55.804304Z","steps":["trace[1827780232] 'range keys from in-memory index tree' (duration: 130.886895ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:39:10.86218Z","caller":"traceutil/trace.go:171","msg":"trace[308816648] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"173.419648ms","start":"2026-02-19T00:39:10.688741Z","end":"2026-02-19T00:39:10.862161Z","steps":["trace[308816648] 'read index received' (duration: 173.282825ms)","trace[308816648] 'applied index is now lower than readState.Index' (duration: 136.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:39:10.862495Z","caller":"traceutil/trace.go:171","msg":"trace[709772524] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"304.753235ms","start":"2026-02-19T00:39:10.557734Z","end":"2026-02-19T00:39:10.862487Z","steps":["trace[709772524] 'process raft request' (duration: 304.312515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.862623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.810057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.86278Z","caller":"traceutil/trace.go:171","msg":"trace[553866013] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:786; }","duration":"173.995051ms","start":"2026-02-19T00:39:10.688766Z","end":"2026-02-19T00:39:10.862761Z","steps":["trace[553866013] 'agreement among raft nodes before linearized reading' (duration: 173.782526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.304578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.863108Z","caller":"traceutil/trace.go:171","msg":"trace[236810026] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:786; }","duration":"174.376789ms","start":"2026-02-19T00:39:10.688718Z","end":"2026-02-19T00:39:10.863094Z","steps":["trace[236810026] 'agreement among raft nodes before linearized reading' (duration: 174.305328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.536056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.863287Z","caller":"traceutil/trace.go:171","msg":"trace[75064388] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:786; }","duration":"155.579647ms","start":"2026-02-19T00:39:10.707699Z","end":"2026-02-19T00:39:10.863279Z","steps":["trace[75064388] 'agreement among raft nodes before linearized reading' (duration: 155.528235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.769481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.863509Z","caller":"traceutil/trace.go:171","msg":"trace[741250392] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:786; }","duration":"155.814202ms","start":"2026-02-19T00:39:10.707686Z","end":"2026-02-19T00:39:10.8635Z","steps":["trace[741250392] 'agreement among raft nodes before linearized reading' (duration: 155.763671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.956546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.863669Z","caller":"traceutil/trace.go:171","msg":"trace[13580381] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:786; }","duration":"156.017936ms","start":"2026-02-19T00:39:10.707641Z","end":"2026-02-19T00:39:10.863659Z","steps":["trace[13580381] 'agreement among raft nodes before linearized reading' (duration: 155.969145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.965779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.863849Z","caller":"traceutil/trace.go:171","msg":"trace[989220740] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"167.00448ms","start":"2026-02-19T00:39:10.696836Z","end":"2026-02-19T00:39:10.863841Z","steps":["trace[989220740] 'agreement among raft nodes before linearized reading' (duration: 166.949998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.863988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.172724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.864022Z","caller":"traceutil/trace.go:171","msg":"trace[2067533679] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"167.230255ms","start":"2026-02-19T00:39:10.696784Z","end":"2026-02-19T00:39:10.864014Z","steps":["trace[2067533679] 'agreement among raft nodes before linearized reading' (duration: 167.179953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.864134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.356868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.864167Z","caller":"traceutil/trace.go:171","msg":"trace[1926580758] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"167.409369ms","start":"2026-02-19T00:39:10.69675Z","end":"2026-02-19T00:39:10.86416Z","steps":["trace[1926580758] 'agreement among raft nodes before linearized reading' (duration: 167.363618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.864293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.494256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:39:10.864328Z","caller":"traceutil/trace.go:171","msg":"trace[508453595] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:786; }","duration":"175.536547ms","start":"2026-02-19T00:39:10.688784Z","end":"2026-02-19T00:39:10.864321Z","steps":["trace[508453595] 'agreement among raft nodes before linearized reading' (duration: 175.488176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:39:10.865572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:39:10.557717Z","time spent":"304.870158ms","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:40:10.035892Z","caller":"traceutil/trace.go:171","msg":"trace[971689523] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"413.207203ms","start":"2026-02-19T00:40:09.622665Z","end":"2026-02-19T00:40:10.035872Z","steps":["trace[971689523] 'process raft request' (duration: 370.65323ms)","trace[971689523] 'compare' (duration: 42.159292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:40:10.036422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:09.622653Z","time spent":"413.624905ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:40:10.036726Z","caller":"traceutil/trace.go:171","msg":"trace[1936353258] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"412.789711ms","start":"2026-02-19T00:40:09.62392Z","end":"2026-02-19T00:40:10.03671Z","steps":["trace[1936353258] 'process raft request' (duration: 411.671966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:10.037097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:09.623913Z","time spent":"413.143183ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T00:40:10.036789Z","caller":"traceutil/trace.go:171","msg":"trace[944566690] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"330.900627ms","start":"2026-02-19T00:40:09.704871Z","end":"2026-02-19T00:40:10.035771Z","steps":["trace[944566690] 'read index received' (duration: 288.537704ms)","trace[944566690] 'applied index is now lower than readState.Index' (duration: 42.361923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:40:10.037752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.541438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-19T00:40:10.037872Z","caller":"traceutil/trace.go:171","msg":"trace[389924301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"175.688698ms","start":"2026-02-19T00:40:09.862171Z","end":"2026-02-19T00:40:10.037859Z","steps":["trace[389924301] 'agreement among raft nodes before linearized reading' (duration: 175.491837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:10.038507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.637609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:40:10.038644Z","caller":"traceutil/trace.go:171","msg":"trace[1496090155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:988; }","duration":"333.77563ms","start":"2026-02-19T00:40:09.704856Z","end":"2026-02-19T00:40:10.038632Z","steps":["trace[1496090155] 'agreement among raft nodes before linearized reading' (duration: 331.952272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:10.038779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:09.704803Z","time spent":"333.95599ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T00:40:12.277137Z","caller":"traceutil/trace.go:171","msg":"trace[762533151] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"105.867129ms","start":"2026-02-19T00:40:12.171251Z","end":"2026-02-19T00:40:12.277119Z","steps":["trace[762533151] 'process raft request' (duration: 105.293226ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:14.327503Z","caller":"traceutil/trace.go:171","msg":"trace[917336822] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"124.450812ms","start":"2026-02-19T00:40:14.203025Z","end":"2026-02-19T00:40:14.327476Z","steps":["trace[917336822] 'process raft request' (duration: 63.38479ms)","trace[917336822] 'compare' (duration: 60.827341ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:40:14.329042Z","caller":"traceutil/trace.go:171","msg":"trace[384808622] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"123.370327ms","start":"2026-02-19T00:40:14.205654Z","end":"2026-02-19T00:40:14.329024Z","steps":["trace[384808622] 'process raft request' (duration: 121.776151ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:14.329641Z","caller":"traceutil/trace.go:171","msg":"trace[1828122296] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"123.861779ms","start":"2026-02-19T00:40:14.205748Z","end":"2026-02-19T00:40:14.32961Z","steps":["trace[1828122296] 'process raft request' (duration: 123.575518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:17.286378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.644914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:40:17.286461Z","caller":"traceutil/trace.go:171","msg":"trace[654649262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"196.795435ms","start":"2026-02-19T00:40:17.089646Z","end":"2026-02-19T00:40:17.286441Z","steps":["trace[654649262] 'range keys from in-memory index tree' (duration: 196.321563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.309292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227441987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:40:19.309346Z","caller":"traceutil/trace.go:171","msg":"trace[359719407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"1.227526117s","start":"2026-02-19T00:40:18.081806Z","end":"2026-02-19T00:40:19.309333Z","steps":["trace[359719407] 'range keys from in-memory index tree' (duration: 1.227364667s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.309368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.081794Z","time spent":"1.227569277s","remote":"127.0.0.1:40358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T00:40:19.309607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088170944s","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-19T00:40:19.309625Z","caller":"traceutil/trace.go:171","msg":"trace[971647570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"1.088186114s","start":"2026-02-19T00:40:18.221431Z","end":"2026-02-19T00:40:19.309617Z","steps":["trace[971647570] 'range keys from in-memory index tree' (duration: 1.088059544s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.309637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.221383Z","time spent":"1.088250474s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T00:40:19.309771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.489038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-19T00:40:19.309786Z","caller":"traceutil/trace.go:171","msg":"trace[1816628747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"954.517908ms","start":"2026-02-19T00:40:18.355263Z","end":"2026-02-19T00:40:19.309781Z","steps":["trace[1816628747] 'range keys from in-memory index tree' (duration: 954.430908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.309798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.355257Z","time spent":"954.537598ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T00:40:19.309877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.664199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-19T00:40:19.309863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.260269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T00:40:19.309924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.308708ms","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-19T00:40:19.309942Z","caller":"traceutil/trace.go:171","msg":"trace[251806216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"954.343798ms","start":"2026-02-19T00:40:18.355594Z","end":"2026-02-19T00:40:19.309938Z","steps":["trace[251806216] 'range keys from in-memory index tree' (duration: 954.252808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.309954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.355585Z","time spent":"954.366048ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T00:40:19.309917Z","caller":"traceutil/trace.go:171","msg":"trace[751473981] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1016; }","duration":"456.375809ms","start":"2026-02-19T00:40:18.853528Z","end":"2026-02-19T00:40:19.309904Z","steps":["trace[751473981] 'count revisions from in-memory index tree' (duration: 456.178518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.310094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.853508Z","time spent":"456.576779ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-19T00:40:19.309892Z","caller":"traceutil/trace.go:171","msg":"trace[350873535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"954.715419ms","start":"2026-02-19T00:40:18.355173Z","end":"2026-02-19T00:40:19.309888Z","steps":["trace[350873535] 'range keys from in-memory index tree' (duration: 954.519939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T00:40:19.31018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T00:40:18.355152Z","time spent":"955.023099ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T00:40:19.474847Z","caller":"traceutil/trace.go:171","msg":"trace[1338775400] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"159.985882ms","start":"2026-02-19T00:40:19.314832Z","end":"2026-02-19T00:40:19.474818Z","steps":["trace[1338775400] 'read index received' (duration: 38.497494ms)","trace[1338775400] 'applied index is now lower than readState.Index' (duration: 121.487498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:40:19.475025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.167382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T00:40:19.475088Z","caller":"traceutil/trace.go:171","msg":"trace[1133030606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"160.265822ms","start":"2026-02-19T00:40:19.314808Z","end":"2026-02-19T00:40:19.475074Z","steps":["trace[1133030606] 'agreement among raft nodes before linearized reading' (duration: 160.122432ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:19.475454Z","caller":"traceutil/trace.go:171","msg":"trace[1943323038] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"160.969594ms","start":"2026-02-19T00:40:19.314469Z","end":"2026-02-19T00:40:19.475438Z","steps":["trace[1943323038] 'process raft request' (duration: 160.104782ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:19.475631Z","caller":"traceutil/trace.go:171","msg":"trace[658186322] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"160.690934ms","start":"2026-02-19T00:40:19.314928Z","end":"2026-02-19T00:40:19.475619Z","steps":["trace[658186322] 'process raft request' (duration: 159.777491ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:19.475821Z","caller":"traceutil/trace.go:171","msg":"trace[1133769631] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"157.784605ms","start":"2026-02-19T00:40:19.317981Z","end":"2026-02-19T00:40:19.475765Z","steps":["trace[1133769631] 'process raft request' (duration: 156.769932ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:19.475951Z","caller":"traceutil/trace.go:171","msg":"trace[866456849] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"157.913855ms","start":"2026-02-19T00:40:19.318028Z","end":"2026-02-19T00:40:19.475941Z","steps":["trace[866456849] 'process raft request' (duration: 156.757432ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T00:40:24.043281Z","caller":"traceutil/trace.go:171","msg":"trace[780790885] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"117.142747ms","start":"2026-02-19T00:40:23.926122Z","end":"2026-02-19T00:40:24.043265Z","steps":["trace[780790885] 'read index received' (duration: 40.040843ms)","trace[780790885] 'applied index is now lower than readState.Index' (duration: 77.101324ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T00:40:24.043364Z","caller":"traceutil/trace.go:171","msg":"trace[2055729474] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"119.861503ms","start":"2026-02-19T00:40:23.923494Z","end":"2026-02-19T00:40:24.043355Z","steps":["trace[2055729474] 'process raft request' (duration: 42.70684ms)","trace[2055729474] 'compare' (duration: 76.997833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T00:40:24.04358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.443908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-19T00:40:24.043603Z","caller":"traceutil/trace.go:171","msg":"trace[2004955496] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1037; }","duration":"117.489208ms","start":"2026-02-19T00:40:23.926107Z","end":"2026-02-19T00:40:24.043596Z","steps":["trace[2004955496] 'agreement among raft nodes before linearized reading' (duration: 117.382127ms)"],"step_count":1}