{"level":"warn","ts":"2026-02-20T18:28:56.326015Z","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-20T18:28:56.32621Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-20T18:28:56.326405Z","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-20T18:28:56.326479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-20T18:28:56.326981Z","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-20T18:28:56.328527Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-02-20T18:28:56.328747Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-20T18:28:56.344028Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.920864ms"} {"level":"info","ts":"2026-02-20T18:28:56.401431Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-02-20T18:28:56.401565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:28:56.401604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-02-20T18:28:56.401625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:28:56.401647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-02-20T18:28:56.401706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-02-20T18:28:56.43942Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:28:56.454768Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:28:56.462758Z","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-20T18:28:56.466452Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:28:56.466562Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:28:56.467568Z","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-20T18:28:56.467874Z","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-20T18:28:56.467913Z","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-20T18:28:56.470135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-02-20T18:28:56.470464Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-20T18:28:56.472472Z","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-20T18:28:56.472723Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-20T18:28:56.472774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-20T18:28:56.47287Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:28:56.472921Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:28:57.002176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:28:57.002243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:28:57.002287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-02-20T18:28:57.00231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:28:57.002323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-20T18:28:57.002345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-02-20T18:28:57.002361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-20T18:28:57.02187Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:57.029453Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:28:57.029783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:28:57.029818Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:28:57.030024Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:28:57.030235Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:28:57.033688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:28:57.033828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-02-20T18:28:57.03842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:57.038496Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:57.038524Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:58.681272Z","caller":"traceutil/trace.go:171","msg":"trace[1532357339] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"122.486742ms","start":"2026-02-20T18:28:58.55874Z","end":"2026-02-20T18:28:58.681227Z","steps":["trace[1532357339] 'process raft request' (duration: 100.331252ms)","trace[1532357339] 'compare' (duration: 21.949736ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:58.681305Z","caller":"traceutil/trace.go:171","msg":"trace[564025814] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"122.172266ms","start":"2026-02-20T18:28:58.559102Z","end":"2026-02-20T18:28:58.681275Z","steps":["trace[564025814] 'process raft request' (duration: 122.076674ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:58.681379Z","caller":"traceutil/trace.go:171","msg":"trace[959707739] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"116.636931ms","start":"2026-02-20T18:28:58.564719Z","end":"2026-02-20T18:28:58.681356Z","steps":["trace[959707739] 'process raft request' (duration: 116.58917ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:58.681573Z","caller":"traceutil/trace.go:171","msg":"trace[1564126215] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:44; }","duration":"120.924982ms","start":"2026-02-20T18:28:58.560596Z","end":"2026-02-20T18:28:58.681521Z","steps":["trace[1564126215] 'read index received' (duration: 98.572379ms)","trace[1564126215] 'applied index is now lower than readState.Index' (duration: 22.351183ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:58.681597Z","caller":"traceutil/trace.go:171","msg":"trace[344110119] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"119.618647ms","start":"2026-02-20T18:28:58.561965Z","end":"2026-02-20T18:28:58.681584Z","steps":["trace[344110119] 'process raft request' (duration: 119.311822ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:58.681744Z","caller":"traceutil/trace.go:171","msg":"trace[104236534] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"120.853931ms","start":"2026-02-20T18:28:58.560724Z","end":"2026-02-20T18:28:58.681578Z","steps":["trace[104236534] 'process raft request' (duration: 120.505415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.682603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.968712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T18:28:58.682681Z","caller":"traceutil/trace.go:171","msg":"trace[805217148] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:45; }","duration":"122.088805ms","start":"2026-02-20T18:28:58.560572Z","end":"2026-02-20T18:28:58.682661Z","steps":["trace[805217148] 'agreement among raft nodes before linearized reading' (duration: 121.041395ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:05.568383Z","caller":"traceutil/trace.go:171","msg":"trace[1445069233] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"505.129719ms","start":"2026-02-20T18:29:05.063228Z","end":"2026-02-20T18:29:05.568357Z","steps":["trace[1445069233] 'process raft request' (duration: 504.969356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:05.568985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:05.063206Z","time spent":"505.262792ms","remote":"127.0.0.1:48346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:05.690948Z","caller":"traceutil/trace.go:171","msg":"trace[70749748] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"108.842734ms","start":"2026-02-20T18:29:05.582069Z","end":"2026-02-20T18:29:05.690912Z","steps":["trace[70749748] 'process raft request' (duration: 105.48145ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:09.192582Z","caller":"traceutil/trace.go:171","msg":"trace[742376478] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"164.929018ms","start":"2026-02-20T18:29:09.027626Z","end":"2026-02-20T18:29:09.192555Z","steps":["trace[742376478] 'process raft request' (duration: 164.712193ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:09.224793Z","caller":"traceutil/trace.go:171","msg":"trace[608223428] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"191.017763ms","start":"2026-02-20T18:29:09.033752Z","end":"2026-02-20T18:29:09.22477Z","steps":["trace[608223428] 'process raft request' (duration: 190.880629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:11.313719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:11.814116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:12.314885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:12.815666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:13.316012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:13.326094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.521532644s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:13.32646Z","caller":"traceutil/trace.go:171","msg":"trace[734834018] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"2.521954313s","start":"2026-02-20T18:29:10.804482Z","end":"2026-02-20T18:29:13.326436Z","steps":["trace[734834018] 'process raft request' (duration: 2.521770469s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.326793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:10.804466Z","time spent":"2.522058834s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:14.100505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000598597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:29:14.100643Z","caller":"traceutil/trace.go:171","msg":"trace[580582134] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00075754s","start":"2026-02-20T18:29:12.099859Z","end":"2026-02-20T18:29:14.100617Z","steps":["trace[580582134] 'agreement among raft nodes before linearized reading' (duration: 2.000593907s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.100712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.099807Z","time spent":"2.000888672s","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:29:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:29:14.771522Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.354521355s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:29:15.032266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.368397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:15.032353Z","caller":"traceutil/trace.go:171","msg":"trace[84874827] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"4.21942888s","start":"2026-02-20T18:29:10.81291Z","end":"2026-02-20T18:29:15.032339Z","steps":["trace[84874827] 'read index received' (duration: 2.51339195s)","trace[84874827] 'applied index is now lower than readState.Index' (duration: 1.70603579s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:15.032534Z","caller":"traceutil/trace.go:171","msg":"trace[433324393] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"3.041464504s","start":"2026-02-20T18:29:11.991036Z","end":"2026-02-20T18:29:15.0325Z","steps":["trace[433324393] 'process raft request' (duration: 2.780658098s)","trace[433324393] 'compare' (duration: 259.720265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:15.032694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.991002Z","time spent":"3.041609017s","remote":"127.0.0.1:48412","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-20T18:29:15.032848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.21991681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:15.03295Z","caller":"traceutil/trace.go:171","msg":"trace[680635579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"4.220046302s","start":"2026-02-20T18:29:10.812878Z","end":"2026-02-20T18:29:15.032924Z","steps":["trace[680635579] 'agreement among raft nodes before linearized reading' (duration: 4.219856699s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.033001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:10.812868Z","time spent":"4.220123833s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T18:29:15.342486Z","caller":"traceutil/trace.go:171","msg":"trace[994683719] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"310.086801ms","start":"2026-02-20T18:29:15.032378Z","end":"2026-02-20T18:29:15.342465Z","steps":["trace[994683719] 'read index received' (duration: 288.104463ms)","trace[994683719] 'applied index is now lower than readState.Index' (duration: 21.981458ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:15.342609Z","caller":"traceutil/trace.go:171","msg":"trace[931959517] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.238560237s","start":"2026-02-20T18:29:14.104024Z","end":"2026-02-20T18:29:15.342585Z","steps":["trace[931959517] 'process raft request' (duration: 1.216445877s)","trace[931959517] 'compare' (duration: 21.869355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:15.342626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.250749855s","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-20T18:29:15.342727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.103999Z","time spent":"1.238670258s","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:15.342724Z","caller":"traceutil/trace.go:171","msg":"trace[451042509] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:367; }","duration":"4.250862787s","start":"2026-02-20T18:29:11.091844Z","end":"2026-02-20T18:29:15.342707Z","steps":["trace[451042509] 'agreement among raft nodes before linearized reading' (duration: 4.250724055s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.342669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214702614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-20T18:29:15.34284Z","caller":"traceutil/trace.go:171","msg":"trace[1800131263] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:367; }","duration":"1.214874237s","start":"2026-02-20T18:29:14.127903Z","end":"2026-02-20T18:29:15.342777Z","steps":["trace[1800131263] 'agreement among raft nodes before linearized reading' (duration: 1.214578212s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.3427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.091999712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-20T18:29:15.343054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.030141879s","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-20T18:29:15.34309Z","caller":"traceutil/trace.go:171","msg":"trace[598356518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"3.030234921s","start":"2026-02-20T18:29:12.312847Z","end":"2026-02-20T18:29:15.343082Z","steps":["trace[598356518] 'agreement among raft nodes before linearized reading' (duration: 3.02965078s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.343111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.312832Z","time spent":"3.030274871s","remote":"127.0.0.1:48412","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-20T18:29:15.34295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.127885Z","time spent":"1.215012681s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4279,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-20T18:29:15.343074Z","caller":"traceutil/trace.go:171","msg":"trace[2053000537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"3.092372328s","start":"2026-02-20T18:29:12.250679Z","end":"2026-02-20T18:29:15.343051Z","steps":["trace[2053000537] 'agreement among raft nodes before linearized reading' (duration: 3.09188623s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.343321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.250624Z","time spent":"3.092673495s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:29:15.400566Z","caller":"traceutil/trace.go:171","msg":"trace[56018178] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"363.134666ms","start":"2026-02-20T18:29:15.037415Z","end":"2026-02-20T18:29:15.40055Z","steps":["trace[56018178] 'process raft request' (duration: 362.975003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.400665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:15.037382Z","time spent":"363.233497ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:17.927529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.111852ms","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-20T18:29:17.927644Z","caller":"traceutil/trace.go:171","msg":"trace[504707591] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:379; }","duration":"211.271895ms","start":"2026-02-20T18:29:17.716344Z","end":"2026-02-20T18:29:17.927616Z","steps":["trace[504707591] 'range keys from in-memory index tree' (duration: 210.984189ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:18.14448Z","caller":"traceutil/trace.go:171","msg":"trace[1860726962] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"203.261142ms","start":"2026-02-20T18:29:17.94119Z","end":"2026-02-20T18:29:18.144451Z","steps":["trace[1860726962] 'process raft request' (duration: 203.093959ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:18.388896Z","caller":"traceutil/trace.go:171","msg":"trace[1741898846] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"155.814709ms","start":"2026-02-20T18:29:18.233058Z","end":"2026-02-20T18:29:18.388873Z","steps":["trace[1741898846] 'process raft request' (duration: 155.701067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:19.555835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.355094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:19.55632Z","caller":"traceutil/trace.go:171","msg":"trace[218178209] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.323202129s","start":"2026-02-20T18:29:18.233091Z","end":"2026-02-20T18:29:19.556293Z","steps":["trace[218178209] 'process raft request' (duration: 930.280723ms)","trace[218178209] 'compare' (duration: 391.795704ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:19.556383Z","caller":"traceutil/trace.go:171","msg":"trace[1794590682] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"1.319546769s","start":"2026-02-20T18:29:18.236809Z","end":"2026-02-20T18:29:19.556356Z","steps":["trace[1794590682] 'process raft request' (duration: 1.319160161s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:19.55646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:18.233076Z","time spent":"1.323310462s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:29:19.556487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:18.236793Z","time spent":"1.31963156s","remote":"127.0.0.1:48616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:19.55665Z","caller":"traceutil/trace.go:171","msg":"trace[1920102823] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:398; }","duration":"128.06483ms","start":"2026-02-20T18:29:19.428573Z","end":"2026-02-20T18:29:19.556638Z","steps":["trace[1920102823] 'read index received' (duration: 31.53µs)","trace[1920102823] 'applied index is now lower than readState.Index' (duration: 128.0314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:19.556957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.392956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:19.556997Z","caller":"traceutil/trace.go:171","msg":"trace[1016476874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:385; }","duration":"128.468928ms","start":"2026-02-20T18:29:19.428514Z","end":"2026-02-20T18:29:19.556983Z","steps":["trace[1016476874] 'agreement among raft nodes before linearized reading' (duration: 128.372206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.258889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.776073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:20.259235Z","caller":"traceutil/trace.go:171","msg":"trace[1467464556] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"696.802055ms","start":"2026-02-20T18:29:19.562416Z","end":"2026-02-20T18:29:20.259218Z","steps":["trace[1467464556] 'process raft request' (duration: 696.736494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.259324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.562397Z","time spent":"696.884156ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:20.259394Z","caller":"traceutil/trace.go:171","msg":"trace[486132866] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"697.357925ms","start":"2026-02-20T18:29:19.561995Z","end":"2026-02-20T18:29:20.259352Z","steps":["trace[486132866] 'process raft request' (duration: 147.047711ms)","trace[486132866] 'compare' (duration: 549.63831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:20.25955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.561972Z","time spent":"697.501618ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:20.259531Z","caller":"traceutil/trace.go:171","msg":"trace[2125597640] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"697.443047ms","start":"2026-02-20T18:29:19.562059Z","end":"2026-02-20T18:29:20.259502Z","steps":["trace[2125597640] 'process raft request' (duration: 696.938016ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:20.259581Z","caller":"traceutil/trace.go:171","msg":"trace[609327226] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"697.334375ms","start":"2026-02-20T18:29:19.562237Z","end":"2026-02-20T18:29:20.259571Z","steps":["trace[609327226] 'process raft request' (duration: 696.806934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.259635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.562229Z","time spent":"697.378805ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:20.25967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.562051Z","time spent":"697.545538ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:20.259758Z","caller":"traceutil/trace.go:171","msg":"trace[498026709] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"697.374015ms","start":"2026-02-20T18:29:19.562376Z","end":"2026-02-20T18:29:20.25975Z","steps":["trace[498026709] 'process raft request' (duration: 696.742623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.259809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.562367Z","time spent":"697.417357ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:20.260046Z","caller":"traceutil/trace.go:171","msg":"trace[713041714] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:401; }","duration":"664.477578ms","start":"2026-02-20T18:29:19.595553Z","end":"2026-02-20T18:29:20.260031Z","steps":["trace[713041714] 'read index received' (duration: 48.472883ms)","trace[713041714] 'applied index is now lower than readState.Index' (duration: 616.003645ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:20.260026Z","caller":"traceutil/trace.go:171","msg":"trace[323795600] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"696.852146ms","start":"2026-02-20T18:29:19.563147Z","end":"2026-02-20T18:29:20.259999Z","steps":["trace[323795600] 'process raft request' (duration: 696.036131ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:20.260127Z","caller":"traceutil/trace.go:171","msg":"trace[819430842] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"696.527159ms","start":"2026-02-20T18:29:19.563592Z","end":"2026-02-20T18:29:20.260119Z","steps":["trace[819430842] 'process raft request' (duration: 696.257744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.260178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.563142Z","time spent":"696.951567ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:20.260189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.563581Z","time spent":"696.566049ms","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:20.260239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.690923ms","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-20T18:29:20.26029Z","caller":"traceutil/trace.go:171","msg":"trace[1179733739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"664.789165ms","start":"2026-02-20T18:29:19.595479Z","end":"2026-02-20T18:29:20.260268Z","steps":["trace[1179733739] 'agreement among raft nodes before linearized reading' (duration: 664.693883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:20.260328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:19.595446Z","time spent":"664.874016ms","remote":"127.0.0.1:48412","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-20T18:29:21.432128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.944503ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T18:29:21.4324Z","caller":"traceutil/trace.go:171","msg":"trace[2080179791] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"1.166972712s","start":"2026-02-20T18:29:20.26541Z","end":"2026-02-20T18:29:21.432383Z","steps":["trace[2080179791] 'process raft request' (duration: 1.166819999s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.432502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.265389Z","time spent":"1.167054984s","remote":"127.0.0.1:48412","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-20T18:29:21.432805Z","caller":"traceutil/trace.go:171","msg":"trace[240301788] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:409; }","duration":"1.166616905s","start":"2026-02-20T18:29:20.266178Z","end":"2026-02-20T18:29:21.432795Z","steps":["trace[240301788] 'read index received' (duration: 211.902046ms)","trace[240301788] 'applied index is now lower than readState.Index' (duration: 954.714129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:21.432885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.262112Z","time spent":"1.170772085s","remote":"127.0.0.1:48184","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:29:21.433137Z","caller":"traceutil/trace.go:171","msg":"trace[1756801484] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.167048554s","start":"2026-02-20T18:29:20.266079Z","end":"2026-02-20T18:29:21.433128Z","steps":["trace[1756801484] 'process raft request' (duration: 1.166272499s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.433209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.266042Z","time spent":"1.167125905s","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:21.433447Z","caller":"traceutil/trace.go:171","msg":"trace[927723421] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.159276686s","start":"2026-02-20T18:29:20.274161Z","end":"2026-02-20T18:29:21.433438Z","steps":["trace[927723421] 'process raft request' (duration: 1.158587843s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.433509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.274147Z","time spent":"1.159330627s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:21.433667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167484961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-hd595\" ","response":"range_response_count:1 size:3994"} {"level":"info","ts":"2026-02-20T18:29:21.433705Z","caller":"traceutil/trace.go:171","msg":"trace[2075933845] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-hd595; range_end:; response_count:1; response_revision:396; }","duration":"1.167576913s","start":"2026-02-20T18:29:20.266118Z","end":"2026-02-20T18:29:21.433695Z","steps":["trace[2075933845] 'agreement among raft nodes before linearized reading' (duration: 1.167508001s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.433737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.266104Z","time spent":"1.167626684s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4017,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-hd595\" "} {"level":"warn","ts":"2026-02-20T18:29:21.433879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159876558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-20T18:29:21.433911Z","caller":"traceutil/trace.go:171","msg":"trace[411190189] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:396; }","duration":"1.159932538s","start":"2026-02-20T18:29:20.27397Z","end":"2026-02-20T18:29:21.433903Z","steps":["trace[411190189] 'agreement among raft nodes before linearized reading' (duration: 1.159879297s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.433938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.273959Z","time spent":"1.15997301s","remote":"127.0.0.1:48616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4196,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-20T18:29:31.034072Z","caller":"traceutil/trace.go:171","msg":"trace[511031962] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"272.029711ms","start":"2026-02-20T18:29:30.762017Z","end":"2026-02-20T18:29:31.034047Z","steps":["trace[511031962] 'process raft request' (duration: 268.928202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:31.372054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.508532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-02-20T18:29:31.372065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.049733ms","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-20T18:29:31.372149Z","caller":"traceutil/trace.go:171","msg":"trace[1240822246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:471; }","duration":"114.669445ms","start":"2026-02-20T18:29:31.257461Z","end":"2026-02-20T18:29:31.372131Z","steps":["trace[1240822246] 'range keys from in-memory index tree' (duration: 114.328348ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:31.372178Z","caller":"traceutil/trace.go:171","msg":"trace[167069178] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:471; }","duration":"115.190716ms","start":"2026-02-20T18:29:31.256963Z","end":"2026-02-20T18:29:31.372154Z","steps":["trace[167069178] 'range keys from in-memory index tree' (duration: 114.945611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:31.372328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.702992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-20T18:29:31.372407Z","caller":"traceutil/trace.go:171","msg":"trace[1672175117] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:471; }","duration":"117.829575ms","start":"2026-02-20T18:29:31.254556Z","end":"2026-02-20T18:29:31.372385Z","steps":["trace[1672175117] 'range keys from in-memory index tree' (duration: 117.261843ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:33.664364Z","caller":"traceutil/trace.go:171","msg":"trace[1403748359] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"161.922865ms","start":"2026-02-20T18:29:33.502412Z","end":"2026-02-20T18:29:33.664335Z","steps":["trace[1403748359] 'process raft request' (duration: 161.743082ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:35.794793Z","caller":"traceutil/trace.go:171","msg":"trace[1963514171] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"126.774424ms","start":"2026-02-20T18:29:35.667993Z","end":"2026-02-20T18:29:35.794767Z","steps":["trace[1963514171] 'read index received' (duration: 126.5215ms)","trace[1963514171] 'applied index is now lower than readState.Index' (duration: 251.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.794862Z","caller":"traceutil/trace.go:171","msg":"trace[456476906] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"240.828388ms","start":"2026-02-20T18:29:35.553998Z","end":"2026-02-20T18:29:35.794826Z","steps":["trace[456476906] 'process raft request' (duration: 240.602014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:35.795124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.102292ms","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-20T18:29:35.795233Z","caller":"traceutil/trace.go:171","msg":"trace[467654238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"127.277275ms","start":"2026-02-20T18:29:35.667932Z","end":"2026-02-20T18:29:35.795209Z","steps":["trace[467654238] 'agreement among raft nodes before linearized reading' (duration: 126.980329ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:40.070502Z","caller":"traceutil/trace.go:171","msg":"trace[334917464] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"181.038619ms","start":"2026-02-20T18:29:39.889435Z","end":"2026-02-20T18:29:40.070474Z","steps":["trace[334917464] 'read index received' (duration: 180.965708ms)","trace[334917464] 'applied index is now lower than readState.Index' (duration: 71.751µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:40.070701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.245382ms","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-20T18:29:40.070744Z","caller":"traceutil/trace.go:171","msg":"trace[794759093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"181.332704ms","start":"2026-02-20T18:29:39.889399Z","end":"2026-02-20T18:29:40.070732Z","steps":["trace[794759093] 'agreement among raft nodes before linearized reading' (duration: 181.187391ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:40.070922Z","caller":"traceutil/trace.go:171","msg":"trace[505456503] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"240.011752ms","start":"2026-02-20T18:29:39.830885Z","end":"2026-02-20T18:29:40.070897Z","steps":["trace[505456503] 'process raft request' (duration: 239.182366ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:40.353227Z","caller":"traceutil/trace.go:171","msg":"trace[104106968] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"387.069343ms","start":"2026-02-20T18:29:39.966128Z","end":"2026-02-20T18:29:40.353197Z","steps":["trace[104106968] 'process raft request' (duration: 312.494473ms)","trace[104106968] 'compare' (duration: 74.440278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:40.353397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.966117Z","time spent":"387.188045ms","remote":"127.0.0.1:48412","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-20T18:29:40.376066Z","caller":"traceutil/trace.go:171","msg":"trace[1940412318] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"198.690105ms","start":"2026-02-20T18:29:40.177355Z","end":"2026-02-20T18:29:40.376045Z","steps":["trace[1940412318] 'read index received' (duration: 101.28481ms)","trace[1940412318] 'applied index is now lower than readState.Index' (duration: 97.402125ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:40.376115Z","caller":"traceutil/trace.go:171","msg":"trace[1275663749] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"300.8261ms","start":"2026-02-20T18:29:40.075255Z","end":"2026-02-20T18:29:40.376081Z","steps":["trace[1275663749] 'process raft request' (duration: 300.685597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.376188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.811207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:29:40.376227Z","caller":"traceutil/trace.go:171","msg":"trace[193413267] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:494; }","duration":"198.886489ms","start":"2026-02-20T18:29:40.177331Z","end":"2026-02-20T18:29:40.376217Z","steps":["trace[193413267] 'agreement among raft nodes before linearized reading' (duration: 198.803138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.376245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:40.075229Z","time spent":"300.943582ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:43.367376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.154745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:43.367487Z","caller":"traceutil/trace.go:171","msg":"trace[2040192429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"778.354538ms","start":"2026-02-20T18:29:42.589108Z","end":"2026-02-20T18:29:43.367463Z","steps":["trace[2040192429] 'range keys from in-memory index tree' (duration: 777.957001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.367511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.623944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:43.367544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.589069Z","time spent":"778.45951ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:43.367619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.054661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jczdd\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-20T18:29:43.367554Z","caller":"traceutil/trace.go:171","msg":"trace[230389271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"283.706086ms","start":"2026-02-20T18:29:43.083835Z","end":"2026-02-20T18:29:43.367541Z","steps":["trace[230389271] 'range keys from in-memory index tree' (duration: 283.534353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.367704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.67792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:29:43.367718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.504972ms","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-20T18:29:43.367738Z","caller":"traceutil/trace.go:171","msg":"trace[1742562377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"793.740651ms","start":"2026-02-20T18:29:42.573986Z","end":"2026-02-20T18:29:43.367727Z","steps":["trace[1742562377] 'range keys from in-memory index tree' (duration: 793.611179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.367767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.573967Z","time spent":"793.792953ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:29:43.367772Z","caller":"traceutil/trace.go:171","msg":"trace[418577959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"987.595043ms","start":"2026-02-20T18:29:42.38016Z","end":"2026-02-20T18:29:43.367755Z","steps":["trace[418577959] 'range keys from in-memory index tree' (duration: 987.39885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.367814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.380139Z","time spent":"987.664084ms","remote":"127.0.0.1:48412","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-20T18:29:43.368817Z","caller":"traceutil/trace.go:171","msg":"trace[749890503] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jczdd; range_end:; response_count:1; response_revision:501; }","duration":"265.283665ms","start":"2026-02-20T18:29:43.103518Z","end":"2026-02-20T18:29:43.368801Z","steps":["trace[749890503] 'range keys from in-memory index tree' (duration: 263.960219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.952881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:29:44.225632Z","caller":"traceutil/trace.go:171","msg":"trace[1032879925] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"853.304165ms","start":"2026-02-20T18:29:43.372307Z","end":"2026-02-20T18:29:44.225611Z","steps":["trace[1032879925] 'process raft request' (duration: 853.147673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.225794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.372282Z","time spent":"853.428087ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:45.380027Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15441404s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:45.380441Z","caller":"traceutil/trace.go:171","msg":"trace[1204992757] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"2.007981241s","start":"2026-02-20T18:29:43.372434Z","end":"2026-02-20T18:29:45.380415Z","steps":["trace[1204992757] 'process raft request' (duration: 2.007773476s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.380585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.372407Z","time spent":"2.008085272s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:45.380727Z","caller":"traceutil/trace.go:171","msg":"trace[873024315] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"2.007646553s","start":"2026-02-20T18:29:43.373054Z","end":"2026-02-20T18:29:45.380701Z","steps":["trace[873024315] 'process raft request' (duration: 2.007317717s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.380836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.373037Z","time spent":"2.007744346s","remote":"127.0.0.1:48412","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-20T18:29:45.380897Z","caller":"traceutil/trace.go:171","msg":"trace[112181726] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:522; }","duration":"1.928424193s","start":"2026-02-20T18:29:43.452454Z","end":"2026-02-20T18:29:45.380878Z","steps":["trace[112181726] 'read index received' (duration: 773.039165ms)","trace[112181726] 'applied index is now lower than readState.Index' (duration: 1.155384128s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:45.381029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928574666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:45.381049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296940465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:45.381073Z","caller":"traceutil/trace.go:171","msg":"trace[694168273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"1.928671918s","start":"2026-02-20T18:29:43.452392Z","end":"2026-02-20T18:29:45.381064Z","steps":["trace[694168273] 'agreement among raft nodes before linearized reading' (duration: 1.928597467s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:45.381019Z","caller":"traceutil/trace.go:171","msg":"trace[811061483] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"2.000955775s","start":"2026-02-20T18:29:43.38003Z","end":"2026-02-20T18:29:45.380985Z","steps":["trace[811061483] 'process raft request' (duration: 2.000556798s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:45.381099Z","caller":"traceutil/trace.go:171","msg":"trace[1637344414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"1.297023686s","start":"2026-02-20T18:29:44.084059Z","end":"2026-02-20T18:29:45.381083Z","steps":["trace[1637344414] 'agreement among raft nodes before linearized reading' (duration: 1.296956165s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.381137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.084039Z","time spent":"1.297089838s","remote":"127.0.0.1:48132","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-20T18:29:45.381174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272210445s","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-20T18:29:45.381207Z","caller":"traceutil/trace.go:171","msg":"trace[764054133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"1.272263275s","start":"2026-02-20T18:29:44.108938Z","end":"2026-02-20T18:29:45.381201Z","steps":["trace[764054133] 'agreement among raft nodes before linearized reading' (duration: 1.272195844s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.381226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.108925Z","time spent":"1.272295486s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:29:45.381098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.45237Z","time spent":"1.928722039s","remote":"127.0.0.1:48130","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-20T18:29:45.381226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.380012Z","time spent":"2.001117579s","remote":"127.0.0.1:48332","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-20T18:29:45.889721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159397,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:29:46.271993Z","caller":"traceutil/trace.go:171","msg":"trace[317639375] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"885.084709ms","start":"2026-02-20T18:29:45.386879Z","end":"2026-02-20T18:29:46.271964Z","steps":["trace[317639375] 'process raft request' (duration: 884.931856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:46.272181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:45.386853Z","time spent":"885.226162ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:47.389818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000367586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:29:47.389905Z","caller":"traceutil/trace.go:171","msg":"trace[1045325311] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000509699s","start":"2026-02-20T18:29:45.389372Z","end":"2026-02-20T18:29:47.389882Z","steps":["trace[1045325311] 'agreement among raft nodes before linearized reading' (duration: 2.000407207s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:47.389955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:45.389353Z","time spent":"2.00059323s","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:29:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:29:49.390636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000528708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:29:49.390723Z","caller":"traceutil/trace.go:171","msg":"trace[903058101] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00065813s","start":"2026-02-20T18:29:47.390046Z","end":"2026-02-20T18:29:49.390704Z","steps":["trace[903058101] 'agreement among raft nodes before linearized reading' (duration: 2.000545308s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:49.390786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:47.390035Z","time spent":"2.000740392s","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:29:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:29:50.082458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156744964s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:29:50.082945Z","caller":"traceutil/trace.go:171","msg":"trace[152722407] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"4.693478836s","start":"2026-02-20T18:29:45.389438Z","end":"2026-02-20T18:29:50.082917Z","steps":["trace[152722407] 'read index received' (duration: 883.077181ms)","trace[152722407] 'applied index is now lower than readState.Index' (duration: 3.810399945s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:50.083Z","caller":"traceutil/trace.go:171","msg":"trace[849651142] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"3.944757873s","start":"2026-02-20T18:29:46.138218Z","end":"2026-02-20T18:29:50.082976Z","steps":["trace[849651142] 'process raft request' (duration: 3.944344855s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.083141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.13819Z","time spent":"3.944858125s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:50.096007Z","caller":"traceutil/trace.go:171","msg":"trace[94020695] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"701.944862ms","start":"2026-02-20T18:29:49.394037Z","end":"2026-02-20T18:29:50.095981Z","steps":["trace[94020695] 'process raft request' (duration: 692.658905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.096083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.865870861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:29:50.096094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.62323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:50.096137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:49.394019Z","time spent":"702.054164ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:50.096165Z","caller":"traceutil/trace.go:171","msg":"trace[1911208712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"697.752232ms","start":"2026-02-20T18:29:49.398394Z","end":"2026-02-20T18:29:50.096146Z","steps":["trace[1911208712] 'agreement among raft nodes before linearized reading' (duration: 697.61934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.09618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.710120565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:29:50.096199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.710268828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-20T18:29:50.096206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:49.398358Z","time spent":"697.840754ms","remote":"127.0.0.1:48130","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-20T18:29:50.096231Z","caller":"traceutil/trace.go:171","msg":"trace[137273539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"2.710337559s","start":"2026-02-20T18:29:47.385885Z","end":"2026-02-20T18:29:50.096223Z","steps":["trace[137273539] 'agreement among raft nodes before linearized reading' (duration: 2.710290089s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:50.096228Z","caller":"traceutil/trace.go:171","msg":"trace[2122290937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"2.710200486s","start":"2026-02-20T18:29:47.386014Z","end":"2026-02-20T18:29:50.096214Z","steps":["trace[2122290937] 'agreement among raft nodes before linearized reading' (duration: 2.710140305s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.096264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:47.385861Z","time spent":"2.71039593s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:50.096269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:47.385992Z","time spent":"2.710269648s","remote":"127.0.0.1:48412","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-20T18:29:50.096151Z","caller":"traceutil/trace.go:171","msg":"trace[1902426329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"3.865948033s","start":"2026-02-20T18:29:46.230186Z","end":"2026-02-20T18:29:50.096134Z","steps":["trace[1902426329] 'agreement among raft nodes before linearized reading' (duration: 3.86579494s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.096348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.230122Z","time spent":"3.866210648s","remote":"127.0.0.1:48412","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-20T18:29:50.096092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819669262s","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-20T18:29:50.096446Z","caller":"traceutil/trace.go:171","msg":"trace[579991428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"1.820068799s","start":"2026-02-20T18:29:48.276366Z","end":"2026-02-20T18:29:50.096434Z","steps":["trace[579991428] 'agreement among raft nodes before linearized reading' (duration: 1.819623481s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:50.096496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.276348Z","time spent":"1.820140771s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:29:52.758938Z","caller":"traceutil/trace.go:171","msg":"trace[1169758607] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"198.069883ms","start":"2026-02-20T18:29:52.560843Z","end":"2026-02-20T18:29:52.758913Z","steps":["trace[1169758607] 'read index received' (duration: 197.88304ms)","trace[1169758607] 'applied index is now lower than readState.Index' (duration: 185.833µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:52.759143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.286007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:52.759199Z","caller":"traceutil/trace.go:171","msg":"trace[837490291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:542; }","duration":"198.41198ms","start":"2026-02-20T18:29:52.560773Z","end":"2026-02-20T18:29:52.759185Z","steps":["trace[837490291] 'agreement among raft nodes before linearized reading' (duration: 198.264147ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:52.759472Z","caller":"traceutil/trace.go:171","msg":"trace[25832625] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"395.438004ms","start":"2026-02-20T18:29:52.363976Z","end":"2026-02-20T18:29:52.759414Z","steps":["trace[25832625] 'process raft request' (duration: 394.820522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:52.759713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:52.363946Z","time spent":"395.650478ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:52.944199Z","caller":"traceutil/trace.go:171","msg":"trace[355473248] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"181.312465ms","start":"2026-02-20T18:29:52.762854Z","end":"2026-02-20T18:29:52.944167Z","steps":["trace[355473248] 'process raft request' (duration: 98.399086ms)","trace[355473248] 'compare' (duration: 82.719146ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:52.95096Z","caller":"traceutil/trace.go:171","msg":"trace[250910526] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"185.74506ms","start":"2026-02-20T18:29:52.765195Z","end":"2026-02-20T18:29:52.95094Z","steps":["trace[250910526] 'process raft request' (duration: 185.613016ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:54.324209Z","caller":"traceutil/trace.go:171","msg":"trace[1160914852] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"162.298792ms","start":"2026-02-20T18:29:54.161886Z","end":"2026-02-20T18:29:54.324185Z","steps":["trace[1160914852] 'process raft request' (duration: 162.17042ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:54.324389Z","caller":"traceutil/trace.go:171","msg":"trace[836702500] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:591; }","duration":"109.705701ms","start":"2026-02-20T18:29:54.214653Z","end":"2026-02-20T18:29:54.324359Z","steps":["trace[836702500] 'read index received' (duration: 109.68972ms)","trace[836702500] 'applied index is now lower than readState.Index' (duration: 13.591µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:54.324536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.992316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-20T18:29:54.32457Z","caller":"traceutil/trace.go:171","msg":"trace[96873132] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"163.088518ms","start":"2026-02-20T18:29:54.16147Z","end":"2026-02-20T18:29:54.324558Z","steps":["trace[96873132] 'agreement among raft nodes before linearized reading' (duration: 162.980446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:54.324613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.402992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-20T18:29:54.324707Z","caller":"traceutil/trace.go:171","msg":"trace[843905902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"143.541715ms","start":"2026-02-20T18:29:54.181142Z","end":"2026-02-20T18:29:54.324684Z","steps":["trace[843905902] 'agreement among raft nodes before linearized reading' (duration: 143.25234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:54.324709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.970955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-20T18:29:54.324762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.783766ms","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-20T18:29:54.324803Z","caller":"traceutil/trace.go:171","msg":"trace[1513183976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"157.852317ms","start":"2026-02-20T18:29:54.166939Z","end":"2026-02-20T18:29:54.324792Z","steps":["trace[1513183976] 'agreement among raft nodes before linearized reading' (duration: 157.719645ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:54.324817Z","caller":"traceutil/trace.go:171","msg":"trace[962018730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"153.105327ms","start":"2026-02-20T18:29:54.171686Z","end":"2026-02-20T18:29:54.324791Z","steps":["trace[962018730] 'agreement among raft nodes before linearized reading' (duration: 152.830562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:54.32488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.531665ms","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-20T18:29:54.324904Z","caller":"traceutil/trace.go:171","msg":"trace[67343568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"143.573766ms","start":"2026-02-20T18:29:54.181324Z","end":"2026-02-20T18:29:54.324897Z","steps":["trace[67343568] 'agreement among raft nodes before linearized reading' (duration: 143.478414ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:54.338747Z","caller":"traceutil/trace.go:171","msg":"trace[2137216565] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"120.473905ms","start":"2026-02-20T18:29:54.218219Z","end":"2026-02-20T18:29:54.338693Z","steps":["trace[2137216565] 'process raft request' (duration: 120.321632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.178189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.833503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:55.17828Z","caller":"traceutil/trace.go:171","msg":"trace[1895341626] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"767.061433ms","start":"2026-02-20T18:29:54.411201Z","end":"2026-02-20T18:29:55.178263Z","steps":["trace[1895341626] 'process raft request' (duration: 25.092688ms)","trace[1895341626] 'compare' (duration: 741.378844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:55.178334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:54.41118Z","time spent":"767.127244ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:29:55.26174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:55.761955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:56.263047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:56.291462Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151980215s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:56.291946Z","caller":"traceutil/trace.go:171","msg":"trace[347391204] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.723646067s","start":"2026-02-20T18:29:54.568272Z","end":"2026-02-20T18:29:56.291918Z","steps":["trace[347391204] 'process raft request' (duration: 1.723507474s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.292073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:54.568251Z","time spent":"1.723752929s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:56.664156Z","caller":"traceutil/trace.go:171","msg":"trace[2073542741] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.073898919s","start":"2026-02-20T18:29:55.590232Z","end":"2026-02-20T18:29:56.664131Z","steps":["trace[2073542741] 'process raft request' (duration: 1.073653164s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.664258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.590211Z","time spent":"1.074000981s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:56.664383Z","caller":"traceutil/trace.go:171","msg":"trace[665073914] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"1.90271845s","start":"2026-02-20T18:29:54.761642Z","end":"2026-02-20T18:29:56.66436Z","steps":["trace[665073914] 'read index received' (duration: 1.530751313s)","trace[665073914] 'applied index is now lower than readState.Index' (duration: 371.964226ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:56.664445Z","caller":"traceutil/trace.go:171","msg":"trace[1674722303] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"677.856703ms","start":"2026-02-20T18:29:55.986553Z","end":"2026-02-20T18:29:56.664409Z","steps":["trace[1674722303] 'process raft request' (duration: 677.630659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.66458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902937073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-20T18:29:56.664615Z","caller":"traceutil/trace.go:171","msg":"trace[79149] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"1.903005015s","start":"2026-02-20T18:29:54.761602Z","end":"2026-02-20T18:29:56.664607Z","steps":["trace[79149] 'agreement among raft nodes before linearized reading' (duration: 1.902875573s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.664635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:54.761585Z","time spent":"1.903045105s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:29:56.664634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.986522Z","time spent":"678.031967ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:29:56.666256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.93712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-20T18:29:56.666302Z","caller":"traceutil/trace.go:171","msg":"trace[801896659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"325.011392ms","start":"2026-02-20T18:29:56.341284Z","end":"2026-02-20T18:29:56.666296Z","steps":["trace[801896659] 'agreement among raft nodes before linearized reading' (duration: 324.91421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.341266Z","time spent":"325.047742ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:29:56.666342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70222323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-20T18:29:56.66639Z","caller":"traceutil/trace.go:171","msg":"trace[2121701011] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"1.702305252s","start":"2026-02-20T18:29:54.964071Z","end":"2026-02-20T18:29:56.666377Z","steps":["trace[2121701011] 'agreement among raft nodes before linearized reading' (duration: 1.702099808s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:54.96405Z","time spent":"1.702365332s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-20T18:29:56.666447Z","caller":"traceutil/trace.go:171","msg":"trace[1863262034] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"357.868967ms","start":"2026-02-20T18:29:56.308572Z","end":"2026-02-20T18:29:56.666441Z","steps":["trace[1863262034] 'process raft request' (duration: 357.461199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.6665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.308547Z","time spent":"357.921808ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:56.666472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304713697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-02-20T18:29:56.666559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.79162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-20T18:29:56.666557Z","caller":"traceutil/trace.go:171","msg":"trace[404721073] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"1.304836318s","start":"2026-02-20T18:29:55.3617Z","end":"2026-02-20T18:29:56.666536Z","steps":["trace[404721073] 'agreement among raft nodes before linearized reading' (duration: 1.304642245s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:56.666577Z","caller":"traceutil/trace.go:171","msg":"trace[1832296542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:579; }","duration":"321.81754ms","start":"2026-02-20T18:29:56.344756Z","end":"2026-02-20T18:29:56.666573Z","steps":["trace[1832296542] 'agreement among raft nodes before linearized reading' (duration: 321.78625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.344751Z","time spent":"321.83667ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:56.666598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.361683Z","time spent":"1.30490715s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:29:56.666659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.198089ms","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-20T18:29:56.666671Z","caller":"traceutil/trace.go:171","msg":"trace[1169445928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"322.219379ms","start":"2026-02-20T18:29:56.344448Z","end":"2026-02-20T18:29:56.666667Z","steps":["trace[1169445928] 'agreement among raft nodes before linearized reading' (duration: 322.194918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.66667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504799018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-02-20T18:29:56.666703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.265029ms","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-20T18:29:56.666705Z","caller":"traceutil/trace.go:171","msg":"trace[658294386] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"1.504833238s","start":"2026-02-20T18:29:55.161861Z","end":"2026-02-20T18:29:56.666694Z","steps":["trace[658294386] 'agreement among raft nodes before linearized reading' (duration: 1.504743997s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.779339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-20T18:29:56.666739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.161828Z","time spent":"1.504905549s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:29:56.666861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.889159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-20T18:29:56.666883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.86861ms","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-20T18:29:56.666886Z","caller":"traceutil/trace.go:171","msg":"trace[961069389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"504.93573ms","start":"2026-02-20T18:29:56.161944Z","end":"2026-02-20T18:29:56.666879Z","steps":["trace[961069389] 'agreement among raft nodes before linearized reading' (duration: 504.836708ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:56.666901Z","caller":"traceutil/trace.go:171","msg":"trace[1105524610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:579; }","duration":"904.91912ms","start":"2026-02-20T18:29:55.761976Z","end":"2026-02-20T18:29:56.666896Z","steps":["trace[1105524610] 'agreement among raft nodes before linearized reading' (duration: 904.868449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.161929Z","time spent":"504.973131ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:29:56.666915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.76196Z","time spent":"904.951861ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:29:56.66691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.513016835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jczdd\" ","response":"range_response_count:1 size:17666"} {"level":"warn","ts":"2026-02-20T18:29:56.666683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.344445Z","time spent":"322.235509ms","remote":"127.0.0.1:48412","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-20T18:29:56.666716Z","caller":"traceutil/trace.go:171","msg":"trace[1139231466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"322.28843ms","start":"2026-02-20T18:29:56.344423Z","end":"2026-02-20T18:29:56.666711Z","steps":["trace[1139231466] 'agreement among raft nodes before linearized reading' (duration: 322.268589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.666975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.344416Z","time spent":"322.549885ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:29:56.66694Z","caller":"traceutil/trace.go:171","msg":"trace[221921080] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jczdd; range_end:; response_count:1; response_revision:579; }","duration":"1.513045726s","start":"2026-02-20T18:29:55.153886Z","end":"2026-02-20T18:29:56.666931Z","steps":["trace[221921080] 'agreement among raft nodes before linearized reading' (duration: 1.512958394s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.667007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.153827Z","time spent":"1.513175388s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17689,"request content":"key:\"/registry/pods/kube-system/cilium-jczdd\" "} {"level":"warn","ts":"2026-02-20T18:29:56.667027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583354845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:56.667047Z","caller":"traceutil/trace.go:171","msg":"trace[1359323257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"1.583413737s","start":"2026-02-20T18:29:55.083628Z","end":"2026-02-20T18:29:56.667042Z","steps":["trace[1359323257] 'agreement among raft nodes before linearized reading' (duration: 1.583376356s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.667062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.083606Z","time spent":"1.583452967s","remote":"127.0.0.1:48130","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-20T18:29:56.666748Z","caller":"traceutil/trace.go:171","msg":"trace[850504760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"322.813339ms","start":"2026-02-20T18:29:56.343932Z","end":"2026-02-20T18:29:56.666745Z","steps":["trace[850504760] 'agreement among raft nodes before linearized reading' (duration: 322.794249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:56.667145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:56.343886Z","time spent":"323.249758ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:29:58.35749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:58.857863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:59.35818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:59.367741Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.516828628s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:59.368112Z","caller":"traceutil/trace.go:171","msg":"trace[1575020769] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"1.511232592s","start":"2026-02-20T18:29:57.856854Z","end":"2026-02-20T18:29:59.368087Z","steps":["trace[1575020769] 'read index received' (duration: 1.511032698s)","trace[1575020769] 'applied index is now lower than readState.Index' (duration: 198.933µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.368384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511496746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.368427Z","caller":"traceutil/trace.go:171","msg":"trace[2142232420] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.511598258s","start":"2026-02-20T18:29:57.856816Z","end":"2026-02-20T18:29:59.368415Z","steps":["trace[2142232420] 'agreement among raft nodes before linearized reading' (duration: 1.511478115s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.856794Z","time spent":"1.511660339s","remote":"127.0.0.1:35618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-20T18:29:59.368497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511506395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.368548Z","caller":"traceutil/trace.go:171","msg":"trace[1407135973] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.511587097s","start":"2026-02-20T18:29:57.856948Z","end":"2026-02-20T18:29:59.368535Z","steps":["trace[1407135973] 'agreement among raft nodes before linearized reading' (duration: 1.511483855s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.856927Z","time spent":"1.511653968s","remote":"127.0.0.1:35618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:29:59.368641Z","caller":"traceutil/trace.go:171","msg":"trace[1931028343] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.517870276s","start":"2026-02-20T18:29:57.850759Z","end":"2026-02-20T18:29:59.368629Z","steps":["trace[1931028343] 'process raft request' (duration: 1.517199544s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190771265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jczdd\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-02-20T18:29:59.368714Z","caller":"traceutil/trace.go:171","msg":"trace[1674168153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jczdd; range_end:; response_count:1; response_revision:595; }","duration":"1.190896447s","start":"2026-02-20T18:29:58.177798Z","end":"2026-02-20T18:29:59.368695Z","steps":["trace[1674168153] 'agreement among raft nodes before linearized reading' (duration: 1.190673263s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.177775Z","time spent":"1.190974299s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17937,"request content":"key:\"/registry/pods/kube-system/cilium-jczdd\" "} {"level":"warn","ts":"2026-02-20T18:29:59.368768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495174435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.368792Z","caller":"traceutil/trace.go:171","msg":"trace[1512136176] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:595; }","duration":"1.495234966s","start":"2026-02-20T18:29:57.87355Z","end":"2026-02-20T18:29:59.368785Z","steps":["trace[1512136176] 'agreement among raft nodes before linearized reading' (duration: 1.495196666s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495568452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:59.368817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.873529Z","time spent":"1.495282757s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-02-20T18:29:59.368841Z","caller":"traceutil/trace.go:171","msg":"trace[430275888] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:595; }","duration":"1.495635153s","start":"2026-02-20T18:29:57.873196Z","end":"2026-02-20T18:29:59.368831Z","steps":["trace[430275888] 'agreement among raft nodes before linearized reading' (duration: 1.495586382s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.873179Z","time spent":"1.495692704s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-20T18:29:59.368952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284310627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.368979Z","caller":"traceutil/trace.go:171","msg":"trace[1841088293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"1.284386619s","start":"2026-02-20T18:29:58.084587Z","end":"2026-02-20T18:29:59.368974Z","steps":["trace[1841088293] 'agreement among raft nodes before linearized reading' (duration: 1.284347138s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.368998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.084569Z","time spent":"1.284425449s","remote":"127.0.0.1:48130","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-20T18:29:59.369023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495928039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.36906Z","caller":"traceutil/trace.go:171","msg":"trace[2144084057] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:595; }","duration":"1.49597428s","start":"2026-02-20T18:29:57.873077Z","end":"2026-02-20T18:29:59.369052Z","steps":["trace[2144084057] 'agreement among raft nodes before linearized reading' (duration: 1.495920488s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.87307Z","time spent":"1.496014241s","remote":"127.0.0.1:35620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:59.369123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.336816ms","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-20T18:29:59.369161Z","caller":"traceutil/trace.go:171","msg":"trace[101928029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"664.396608ms","start":"2026-02-20T18:29:58.704749Z","end":"2026-02-20T18:29:59.369145Z","steps":["trace[101928029] 'agreement among raft nodes before linearized reading' (duration: 664.307156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.704739Z","time spent":"664.478439ms","remote":"127.0.0.1:48412","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-20T18:29:59.369275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510739991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.369317Z","caller":"traceutil/trace.go:171","msg":"trace[1837239307] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.510782952s","start":"2026-02-20T18:29:57.858525Z","end":"2026-02-20T18:29:59.369308Z","steps":["trace[1837239307] 'agreement among raft nodes before linearized reading' (duration: 1.510723111s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.858511Z","time spent":"1.510835403s","remote":"127.0.0.1:35618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-20T18:29:59.369394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.915537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:29:59.368719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.850728Z","time spent":"1.517943528s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4325,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:59.369426Z","caller":"traceutil/trace.go:171","msg":"trace[413445048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"664.946988ms","start":"2026-02-20T18:29:58.704472Z","end":"2026-02-20T18:29:59.369419Z","steps":["trace[413445048] 'agreement among raft nodes before linearized reading' (duration: 664.866846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.704441Z","time spent":"665.0085ms","remote":"127.0.0.1:48412","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-20T18:29:59.369526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.851626ms","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-20T18:29:59.369554Z","caller":"traceutil/trace.go:171","msg":"trace[338987021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:595; }","duration":"665.899856ms","start":"2026-02-20T18:29:58.703648Z","end":"2026-02-20T18:29:59.369548Z","steps":["trace[338987021] 'agreement among raft nodes before linearized reading' (duration: 665.844265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.36913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457723303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:59.369574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.703639Z","time spent":"665.930217ms","remote":"127.0.0.1:48412","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-20T18:29:59.369593Z","caller":"traceutil/trace.go:171","msg":"trace[1646943724] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.458241562s","start":"2026-02-20T18:29:57.911338Z","end":"2026-02-20T18:29:59.36958Z","steps":["trace[1646943724] 'agreement among raft nodes before linearized reading' (duration: 1.457778363s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.911292Z","time spent":"1.458328334s","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:59.369665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.314784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:59.369682Z","caller":"traceutil/trace.go:171","msg":"trace[1465967820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"666.368135ms","start":"2026-02-20T18:29:58.703308Z","end":"2026-02-20T18:29:59.369676Z","steps":["trace[1465967820] 'agreement among raft nodes before linearized reading' (duration: 666.334604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.703286Z","time spent":"666.408546ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:59.369192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457938686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:59.369748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.07687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:29:59.369759Z","caller":"traceutil/trace.go:171","msg":"trace[1792833671] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.458541837s","start":"2026-02-20T18:29:57.911206Z","end":"2026-02-20T18:29:59.369748Z","steps":["trace[1792833671] 'agreement among raft nodes before linearized reading' (duration: 1.457936936s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.369783Z","caller":"traceutil/trace.go:171","msg":"trace[641934779] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:595; }","duration":"750.155741ms","start":"2026-02-20T18:29:58.619617Z","end":"2026-02-20T18:29:59.369773Z","steps":["trace[641934779] 'agreement among raft nodes before linearized reading' (duration: 750.07555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.911199Z","time spent":"1.458579098s","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-20T18:29:59.369813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.619596Z","time spent":"750.210052ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":59,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:59.369104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457299223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.369959Z","caller":"traceutil/trace.go:171","msg":"trace[1068099481] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:595; }","duration":"1.45817163s","start":"2026-02-20T18:29:57.91178Z","end":"2026-02-20T18:29:59.369951Z","steps":["trace[1068099481] 'agreement among raft nodes before linearized reading' (duration: 1.457306644s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.369986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.911771Z","time spent":"1.4582067s","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-20T18:29:59.370032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.216097ms","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-20T18:29:59.370075Z","caller":"traceutil/trace.go:171","msg":"trace[1524994881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"691.286149ms","start":"2026-02-20T18:29:58.678777Z","end":"2026-02-20T18:29:59.370063Z","steps":["trace[1524994881] 'agreement among raft nodes before linearized reading' (duration: 691.160716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.370111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.678764Z","time spent":"691.340121ms","remote":"127.0.0.1:48412","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-20T18:29:59.881819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:00.388483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.057489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:00.388595Z","caller":"traceutil/trace.go:171","msg":"trace[1040099808] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.012311446s","start":"2026-02-20T18:29:59.376261Z","end":"2026-02-20T18:30:00.388573Z","steps":["trace[1040099808] 'process raft request' (duration: 588.076053ms)","trace[1040099808] 'compare' (duration: 423.701292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:00.388655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.376245Z","time spent":"1.012381408s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:00.627574Z","caller":"traceutil/trace.go:171","msg":"trace[1779967112] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.250975003s","start":"2026-02-20T18:29:59.376577Z","end":"2026-02-20T18:30:00.627552Z","steps":["trace[1779967112] 'process raft request' (duration: 1.195165359s)","trace[1779967112] 'compare' (duration: 55.666971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:00.627693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.376548Z","time spent":"1.251083114s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:00.627778Z","caller":"traceutil/trace.go:171","msg":"trace[1769992057] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.251068125s","start":"2026-02-20T18:29:59.376692Z","end":"2026-02-20T18:30:00.62776Z","steps":["trace[1769992057] 'process raft request' (duration: 1.25083099s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.627898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.376684Z","time spent":"1.251153177s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:00.628154Z","caller":"traceutil/trace.go:171","msg":"trace[1487458975] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.251000423s","start":"2026-02-20T18:29:59.377137Z","end":"2026-02-20T18:30:00.628137Z","steps":["trace[1487458975] 'process raft request' (duration: 1.250916502s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:00.628185Z","caller":"traceutil/trace.go:171","msg":"trace[160316195] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.251173857s","start":"2026-02-20T18:29:59.376977Z","end":"2026-02-20T18:30:00.628151Z","steps":["trace[160316195] 'process raft request' (duration: 1.250721097s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:00.628246Z","caller":"traceutil/trace.go:171","msg":"trace[1907884544] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.243456499s","start":"2026-02-20T18:29:59.384783Z","end":"2026-02-20T18:30:00.628239Z","steps":["trace[1907884544] 'process raft request' (duration: 1.243320057s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.628261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.377128Z","time spent":"1.251079246s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:00.628284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.384766Z","time spent":"1.24349784s","remote":"127.0.0.1:48332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:00.628171Z","caller":"traceutil/trace.go:171","msg":"trace[1511378813] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:619; }","duration":"1.246901176s","start":"2026-02-20T18:29:59.38126Z","end":"2026-02-20T18:30:00.628162Z","steps":["trace[1511378813] 'read index received' (duration: 583.165221ms)","trace[1511378813] 'applied index is now lower than readState.Index' (duration: 663.735235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:00.628359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.376965Z","time spent":"1.25130928s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:00.628221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246959977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:00.628461Z","caller":"traceutil/trace.go:171","msg":"trace[506081811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.247213911s","start":"2026-02-20T18:29:59.381227Z","end":"2026-02-20T18:30:00.628441Z","steps":["trace[506081811] 'agreement among raft nodes before linearized reading' (duration: 1.246961986s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.62854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.381219Z","time spent":"1.247297942s","remote":"127.0.0.1:48130","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-20T18:30:00.649232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108702722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:00.649312Z","caller":"traceutil/trace.go:171","msg":"trace[2003521581] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:602; }","duration":"1.108850415s","start":"2026-02-20T18:29:59.540447Z","end":"2026-02-20T18:30:00.649297Z","steps":["trace[2003521581] 'agreement among raft nodes before linearized reading' (duration: 1.108687622s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:00.649313Z","caller":"traceutil/trace.go:171","msg":"trace[759953741] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"501.434303ms","start":"2026-02-20T18:30:00.147858Z","end":"2026-02-20T18:30:00.649292Z","steps":["trace[759953741] 'process raft request' (duration: 501.078857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.649319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.983832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-20T18:30:00.649324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108142932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:30:00.649354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.540427Z","time spent":"1.108918436s","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:30:00.649348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108858365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:00.649387Z","caller":"traceutil/trace.go:171","msg":"trace[559573123] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:602; }","duration":"1.108228754s","start":"2026-02-20T18:29:59.541145Z","end":"2026-02-20T18:30:00.649374Z","steps":["trace[559573123] 'agreement among raft nodes before linearized reading' (duration: 1.108137452s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:00.649385Z","caller":"traceutil/trace.go:171","msg":"trace[767217825] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:602; }","duration":"471.114926ms","start":"2026-02-20T18:30:00.178254Z","end":"2026-02-20T18:30:00.649369Z","steps":["trace[767217825] 'agreement among raft nodes before linearized reading' (duration: 470.925562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.649401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:00.147835Z","time spent":"501.524534ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:00.649419Z","caller":"traceutil/trace.go:171","msg":"trace[749580258] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:602; }","duration":"1.108973727s","start":"2026-02-20T18:29:59.54043Z","end":"2026-02-20T18:30:00.649404Z","steps":["trace[749580258] 'agreement among raft nodes before linearized reading' (duration: 1.108846645s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.649428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:00.178236Z","time spent":"471.185037ms","remote":"127.0.0.1:48184","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.33\" "} {"level":"warn","ts":"2026-02-20T18:30:00.649425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.541137Z","time spent":"1.108278114s","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-02-20T18:30:00.649468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.540407Z","time spent":"1.109050218s","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-02-20T18:30:00.856205Z","caller":"traceutil/trace.go:171","msg":"trace[1187633688] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"135.447461ms","start":"2026-02-20T18:30:00.72072Z","end":"2026-02-20T18:30:00.856167Z","steps":["trace[1187633688] 'process raft request' (duration: 41.223195ms)","trace[1187633688] 'compare' (duration: 94.071043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:01.275938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.193604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:01.276057Z","caller":"traceutil/trace.go:171","msg":"trace[1058249937] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"416.268601ms","start":"2026-02-20T18:30:00.859769Z","end":"2026-02-20T18:30:01.276038Z","steps":["trace[1058249937] 'read index received' (duration: 205.78951ms)","trace[1058249937] 'applied index is now lower than readState.Index' (duration: 210.476661ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:01.276102Z","caller":"traceutil/trace.go:171","msg":"trace[495972661] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"502.052205ms","start":"2026-02-20T18:30:00.774013Z","end":"2026-02-20T18:30:01.276065Z","steps":["trace[495972661] 'process raft request' (duration: 291.634676ms)","trace[495972661] 'compare' (duration: 210.064752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:01.276148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.376933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T18:30:01.276178Z","caller":"traceutil/trace.go:171","msg":"trace[146672787] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:604; }","duration":"416.436774ms","start":"2026-02-20T18:30:00.859732Z","end":"2026-02-20T18:30:01.276169Z","steps":["trace[146672787] 'agreement among raft nodes before linearized reading' (duration: 416.353153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:01.27622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:00.859717Z","time spent":"416.495615ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T18:30:01.276273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:00.773983Z","time spent":"502.194177ms","remote":"127.0.0.1:48412","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-20T18:30:16.79808Z","caller":"traceutil/trace.go:171","msg":"trace[1254282267] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"194.663219ms","start":"2026-02-20T18:30:16.603389Z","end":"2026-02-20T18:30:16.798052Z","steps":["trace[1254282267] 'read index received' (duration: 194.484715ms)","trace[1254282267] 'applied index is now lower than readState.Index' (duration: 177.334µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:16.798408Z","caller":"traceutil/trace.go:171","msg":"trace[698874700] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"337.361287ms","start":"2026-02-20T18:30:16.46102Z","end":"2026-02-20T18:30:16.798382Z","steps":["trace[698874700] 'process raft request' (duration: 336.849657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:16.798481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.073236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:16.79855Z","caller":"traceutil/trace.go:171","msg":"trace[2039480280] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:712; }","duration":"195.206299ms","start":"2026-02-20T18:30:16.603327Z","end":"2026-02-20T18:30:16.798533Z","steps":["trace[2039480280] 'agreement among raft nodes before linearized reading' (duration: 194.997215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:16.798571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.460993Z","time spent":"337.49519ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:17.585764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787159797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:17.926884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081587636s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:30:18.184328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.19248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:18.184437Z","caller":"traceutil/trace.go:171","msg":"trace[1577251684] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.382736474s","start":"2026-02-20T18:30:16.801679Z","end":"2026-02-20T18:30:18.184416Z","steps":["trace[1577251684] 'process raft request' (duration: 1.12536464s)","trace[1577251684] 'compare' (duration: 257.042428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:18.184505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.801657Z","time spent":"1.382810255s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:18.371467Z","caller":"traceutil/trace.go:171","msg":"trace[294611624] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:743; }","duration":"1.286405867s","start":"2026-02-20T18:30:17.085045Z","end":"2026-02-20T18:30:18.371451Z","steps":["trace[294611624] 'read index received' (duration: 841.99986ms)","trace[294611624] 'applied index is now lower than readState.Index' (duration: 444.405427ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:18.371491Z","caller":"traceutil/trace.go:171","msg":"trace[586424473] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.568917689s","start":"2026-02-20T18:30:16.802521Z","end":"2026-02-20T18:30:18.371439Z","steps":["trace[586424473] 'process raft request' (duration: 1.521435485s)","trace[586424473] 'compare' (duration: 47.325731ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:18.371516Z","caller":"traceutil/trace.go:171","msg":"trace[2014243004] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.568860008s","start":"2026-02-20T18:30:16.802618Z","end":"2026-02-20T18:30:18.371478Z","steps":["trace[2014243004] 'process raft request' (duration: 1.568750666s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.371622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.397926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:18.371643Z","caller":"traceutil/trace.go:171","msg":"trace[1908105931] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:717; }","duration":"866.445456ms","start":"2026-02-20T18:30:17.505191Z","end":"2026-02-20T18:30:18.371636Z","steps":["trace[1908105931] 'agreement among raft nodes before linearized reading' (duration: 866.412215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.371666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.505173Z","time spent":"866.488247ms","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:30:18.37167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.802512Z","time spent":"1.569079572s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:18.371698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.802596Z","time spent":"1.569022322s","remote":"127.0.0.1:48412","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-20T18:30:18.371884Z","caller":"traceutil/trace.go:171","msg":"trace[564971289] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"254.886246ms","start":"2026-02-20T18:30:18.116555Z","end":"2026-02-20T18:30:18.371441Z","steps":["trace[564971289] 'process raft request' (duration: 254.861066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.371896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.671339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:18.371913Z","caller":"traceutil/trace.go:171","msg":"trace[1568696510] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:717; }","duration":"732.70533ms","start":"2026-02-20T18:30:17.639203Z","end":"2026-02-20T18:30:18.371909Z","steps":["trace[1568696510] 'agreement among raft nodes before linearized reading' (duration: 732.652899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.371926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.639195Z","time spent":"732.7284ms","remote":"127.0.0.1:48492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:30:18.371587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2865264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:18.37198Z","caller":"traceutil/trace.go:171","msg":"trace[1676404673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"1.286960278s","start":"2026-02-20T18:30:17.085009Z","end":"2026-02-20T18:30:18.37197Z","steps":["trace[1676404673] 'agreement among raft nodes before linearized reading' (duration: 1.286523669s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.372008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.084985Z","time spent":"1.28701542s","remote":"127.0.0.1:48130","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-20T18:30:18.372016Z","caller":"traceutil/trace.go:171","msg":"trace[289394602] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.568826207s","start":"2026-02-20T18:30:16.802621Z","end":"2026-02-20T18:30:18.371447Z","steps":["trace[289394602] 'process raft request' (duration: 1.568770686s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.372057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.802597Z","time spent":"1.56943641s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:20.500727Z","caller":"traceutil/trace.go:171","msg":"trace[655467480] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"312.381511ms","start":"2026-02-20T18:30:20.188327Z","end":"2026-02-20T18:30:20.500709Z","steps":["trace[655467480] 'read index received' (duration: 312.255658ms)","trace[655467480] 'applied index is now lower than readState.Index' (duration: 124.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:20.500768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.180227Z","time spent":"320.534346ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T18:30:20.500908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.554845ms","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-20T18:30:20.500932Z","caller":"traceutil/trace.go:171","msg":"trace[1890022654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"312.624395ms","start":"2026-02-20T18:30:20.188301Z","end":"2026-02-20T18:30:20.500925Z","steps":["trace[1890022654] 'agreement among raft nodes before linearized reading' (duration: 312.492233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:20.500954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.188288Z","time spent":"312.661576ms","remote":"127.0.0.1:48412","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-20T18:30:20.500988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.262095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:30:20.500995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.311606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:30:20.501011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.328217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:30:20.501039Z","caller":"traceutil/trace.go:171","msg":"trace[343366605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"125.333507ms","start":"2026-02-20T18:30:20.37569Z","end":"2026-02-20T18:30:20.501024Z","steps":["trace[343366605] 'agreement among raft nodes before linearized reading' (duration: 125.198975ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:20.50105Z","caller":"traceutil/trace.go:171","msg":"trace[1474457448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"125.409058ms","start":"2026-02-20T18:30:20.375624Z","end":"2026-02-20T18:30:20.501033Z","steps":["trace[1474457448] 'agreement among raft nodes before linearized reading' (duration: 125.233075ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:20.501051Z","caller":"traceutil/trace.go:171","msg":"trace[1014965474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"125.396569ms","start":"2026-02-20T18:30:20.375643Z","end":"2026-02-20T18:30:20.50104Z","steps":["trace[1014965474] 'agreement among raft nodes before linearized reading' (duration: 125.255796ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:20.797052Z","caller":"traceutil/trace.go:171","msg":"trace[754877676] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"294.772376ms","start":"2026-02-20T18:30:20.502251Z","end":"2026-02-20T18:30:20.797023Z","steps":["trace[754877676] 'process raft request' (duration: 232.54794ms)","trace[754877676] 'compare' (duration: 62.097124ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:21.001257Z","caller":"traceutil/trace.go:171","msg":"trace[1270002685] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:751; }","duration":"202.014219ms","start":"2026-02-20T18:30:20.799219Z","end":"2026-02-20T18:30:21.001233Z","steps":["trace[1270002685] 'read index received' (duration: 189.37517ms)","trace[1270002685] 'applied index is now lower than readState.Index' (duration: 12.638109ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:21.001389Z","caller":"traceutil/trace.go:171","msg":"trace[1170341931] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"495.460138ms","start":"2026-02-20T18:30:20.505917Z","end":"2026-02-20T18:30:21.001377Z","steps":["trace[1170341931] 'process raft request' (duration: 495.228934ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:21.00135Z","caller":"traceutil/trace.go:171","msg":"trace[1261714100] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"495.512779ms","start":"2026-02-20T18:30:20.505797Z","end":"2026-02-20T18:30:21.00131Z","steps":["trace[1261714100] 'process raft request' (duration: 495.310015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:21.001489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.505906Z","time spent":"495.509059ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:21.00154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.505772Z","time spent":"495.665942ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:21.001703Z","caller":"traceutil/trace.go:171","msg":"trace[1697446215] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"497.067899ms","start":"2026-02-20T18:30:20.504605Z","end":"2026-02-20T18:30:21.001673Z","steps":["trace[1697446215] 'process raft request' (duration: 483.996672ms)","trace[1697446215] 'compare' (duration: 12.313032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:21.001767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.504431Z","time spent":"497.299163ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:21.001801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.556228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T18:30:21.001892Z","caller":"traceutil/trace.go:171","msg":"trace[709368790] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:723; }","duration":"202.68285ms","start":"2026-02-20T18:30:20.799183Z","end":"2026-02-20T18:30:21.001866Z","steps":["trace[709368790] 'agreement among raft nodes before linearized reading' (duration: 202.524567ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:21.001622Z","caller":"traceutil/trace.go:171","msg":"trace[512014324] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"493.97193ms","start":"2026-02-20T18:30:20.507641Z","end":"2026-02-20T18:30:21.001613Z","steps":["trace[512014324] 'process raft request' (duration: 493.543112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:21.002036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:20.507626Z","time spent":"494.381767ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:21.002405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.424001ms","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-20T18:30:21.002467Z","caller":"traceutil/trace.go:171","msg":"trace[1225208767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"111.567784ms","start":"2026-02-20T18:30:20.890878Z","end":"2026-02-20T18:30:21.002446Z","steps":["trace[1225208767] 'agreement among raft nodes before linearized reading' (duration: 111.34607ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:31.230218Z","caller":"traceutil/trace.go:171","msg":"trace[1649507387] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"135.490843ms","start":"2026-02-20T18:30:31.094706Z","end":"2026-02-20T18:30:31.230197Z","steps":["trace[1649507387] 'process raft request' (duration: 135.046485ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:31.23065Z","caller":"traceutil/trace.go:171","msg":"trace[2094151042] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"135.742369ms","start":"2026-02-20T18:30:31.094808Z","end":"2026-02-20T18:30:31.230551Z","steps":["trace[2094151042] 'process raft request' (duration: 135.091576ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:31.230944Z","caller":"traceutil/trace.go:171","msg":"trace[1421985062] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"126.931573ms","start":"2026-02-20T18:30:31.103985Z","end":"2026-02-20T18:30:31.230916Z","steps":["trace[1421985062] 'process raft request' (duration: 126.173649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.863745ms","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-20T18:30:45.376283Z","caller":"traceutil/trace.go:171","msg":"trace[186451123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:841; }","duration":"203.003138ms","start":"2026-02-20T18:30:45.17325Z","end":"2026-02-20T18:30:45.376253Z","steps":["trace[186451123] 'range keys from in-memory index tree' (duration: 202.758242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.37624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292012536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:45.37635Z","caller":"traceutil/trace.go:171","msg":"trace[814210015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"1.292181829s","start":"2026-02-20T18:30:44.084145Z","end":"2026-02-20T18:30:45.376327Z","steps":["trace[814210015] 'range keys from in-memory index tree' (duration: 1.291917444s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:44.084131Z","time spent":"1.29226375s","remote":"127.0.0.1:48132","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-20T18:30:45.376311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14421884s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:30:45.376394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.06831274s","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-20T18:30:45.376468Z","caller":"traceutil/trace.go:171","msg":"trace[639860594] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:841; }","duration":"1.144376523s","start":"2026-02-20T18:30:44.232065Z","end":"2026-02-20T18:30:45.376441Z","steps":["trace[639860594] 'range keys from in-memory index tree' (duration: 1.14420632s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.501203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-20T18:30:45.376513Z","caller":"traceutil/trace.go:171","msg":"trace[1415613865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"2.068479623s","start":"2026-02-20T18:30:43.308018Z","end":"2026-02-20T18:30:45.376498Z","steps":["trace[1415613865] 'range keys from in-memory index tree' (duration: 2.068144297s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.376536Z","caller":"traceutil/trace.go:171","msg":"trace[2125238216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:841; }","duration":"197.576005ms","start":"2026-02-20T18:30:45.178951Z","end":"2026-02-20T18:30:45.376527Z","steps":["trace[2125238216] 'range keys from in-memory index tree' (duration: 197.392121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:43.307996Z","time spent":"2.068542455s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:30:45.376655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925164462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:45.376703Z","caller":"traceutil/trace.go:171","msg":"trace[1566851118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"1.925236623s","start":"2026-02-20T18:30:43.45146Z","end":"2026-02-20T18:30:45.376697Z","steps":["trace[1566851118] 'range keys from in-memory index tree' (duration: 1.925102361s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:43.451446Z","time spent":"1.925270654s","remote":"127.0.0.1:48130","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-20T18:30:45.376733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.789665ms","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-20T18:30:45.376768Z","caller":"traceutil/trace.go:171","msg":"trace[1423280963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"174.854237ms","start":"2026-02-20T18:30:45.201904Z","end":"2026-02-20T18:30:45.376758Z","steps":["trace[1423280963] 'range keys from in-memory index tree' (duration: 174.716964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.376947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031752931s","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-20T18:30:45.377043Z","caller":"traceutil/trace.go:171","msg":"trace[1107708783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:841; }","duration":"2.031878823s","start":"2026-02-20T18:30:43.34514Z","end":"2026-02-20T18:30:45.377019Z","steps":["trace[1107708783] 'range keys from in-memory index tree' (duration: 2.031578247s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.37711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:43.345119Z","time spent":"2.031978625s","remote":"127.0.0.1:48412","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-20T18:30:45.376083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.286434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:45.378319Z","caller":"traceutil/trace.go:171","msg":"trace[1335208296] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:841; }","duration":"934.871244ms","start":"2026-02-20T18:30:44.442721Z","end":"2026-02-20T18:30:45.377592Z","steps":["trace[1335208296] 'count revisions from in-memory index tree' (duration: 933.222742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.378367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:44.442706Z","time spent":"935.648668ms","remote":"127.0.0.1:48570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:30:45.596434Z","caller":"traceutil/trace.go:171","msg":"trace[593052925] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"215.106446ms","start":"2026-02-20T18:30:45.381302Z","end":"2026-02-20T18:30:45.596409Z","steps":["trace[593052925] 'process raft request' (duration: 214.80077ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.596488Z","caller":"traceutil/trace.go:171","msg":"trace[1535957909] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"215.054775ms","start":"2026-02-20T18:30:45.381425Z","end":"2026-02-20T18:30:45.596479Z","steps":["trace[1535957909] 'process raft request' (duration: 214.858111ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.596492Z","caller":"traceutil/trace.go:171","msg":"trace[1702310617] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:878; }","duration":"212.289003ms","start":"2026-02-20T18:30:45.384177Z","end":"2026-02-20T18:30:45.596466Z","steps":["trace[1702310617] 'read index received' (duration: 15.395461ms)","trace[1702310617] 'applied index is now lower than readState.Index' (duration: 196.892032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:45.596637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.437265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:45.596695Z","caller":"traceutil/trace.go:171","msg":"trace[632345236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"212.528496ms","start":"2026-02-20T18:30:45.384152Z","end":"2026-02-20T18:30:45.596681Z","steps":["trace[632345236] 'agreement among raft nodes before linearized reading' (duration: 212.409244ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.596432Z","caller":"traceutil/trace.go:171","msg":"trace[556235265] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"214.927253ms","start":"2026-02-20T18:30:45.381483Z","end":"2026-02-20T18:30:45.59641Z","steps":["trace[556235265] 'process raft request' (duration: 214.858311ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.596954Z","caller":"traceutil/trace.go:171","msg":"trace[253742038] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"215.046363ms","start":"2026-02-20T18:30:45.381891Z","end":"2026-02-20T18:30:45.596938Z","steps":["trace[253742038] 'process raft request' (duration: 214.478653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:02.766138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.930473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:02.766312Z","caller":"traceutil/trace.go:171","msg":"trace[2084725829] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"958.491419ms","start":"2026-02-20T18:31:01.807806Z","end":"2026-02-20T18:31:02.766298Z","steps":["trace[2084725829] 'read index received' (duration: 266.473974ms)","trace[2084725829] 'applied index is now lower than readState.Index' (duration: 692.016405ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:02.766383Z","caller":"traceutil/trace.go:171","msg":"trace[1182765336] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"1.037891256s","start":"2026-02-20T18:31:01.728455Z","end":"2026-02-20T18:31:02.766346Z","steps":["trace[1182765336] 'process raft request' (duration: 345.678697ms)","trace[1182765336] 'compare' (duration: 691.837222ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:02.766492Z","caller":"traceutil/trace.go:171","msg":"trace[1817386573] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"949.966558ms","start":"2026-02-20T18:31:01.816496Z","end":"2026-02-20T18:31:02.766463Z","steps":["trace[1817386573] 'process raft request' (duration: 949.734883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:02.766538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.728435Z","time spent":"1.038017247s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:02.766634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.81648Z","time spent":"950.075299ms","remote":"127.0.0.1:48412","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-20T18:31:02.76674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.923536ms","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-20T18:31:02.766791Z","caller":"traceutil/trace.go:171","msg":"trace[655463709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"958.993668ms","start":"2026-02-20T18:31:01.807783Z","end":"2026-02-20T18:31:02.766776Z","steps":["trace[655463709] 'agreement among raft nodes before linearized reading' (duration: 958.573551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:02.766825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.80777Z","time spent":"959.047119ms","remote":"127.0.0.1:48412","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-20T18:31:03.26645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787160267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:31:03.43551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.055677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:03.435884Z","caller":"traceutil/trace.go:171","msg":"trace[1319522161] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"669.521302ms","start":"2026-02-20T18:31:02.766339Z","end":"2026-02-20T18:31:03.43586Z","steps":["trace[1319522161] 'read index received' (duration: 561.032117ms)","trace[1319522161] 'applied index is now lower than readState.Index' (duration: 108.488105ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:03.435987Z","caller":"traceutil/trace.go:171","msg":"trace[1111766108] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"697.767293ms","start":"2026-02-20T18:31:02.73819Z","end":"2026-02-20T18:31:03.435957Z","steps":["trace[1111766108] 'process raft request' (duration: 589.158217ms)","trace[1111766108] 'compare' (duration: 107.67919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:03.436031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627416439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:31:03.436067Z","caller":"traceutil/trace.go:171","msg":"trace[208381807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.627461049s","start":"2026-02-20T18:31:01.808596Z","end":"2026-02-20T18:31:03.436057Z","steps":["trace[208381807] 'agreement among raft nodes before linearized reading' (duration: 1.627326677s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:03.436295Z","caller":"traceutil/trace.go:171","msg":"trace[728607870] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"662.631722ms","start":"2026-02-20T18:31:02.773626Z","end":"2026-02-20T18:31:03.436257Z","steps":["trace[728607870] 'process raft request' (duration: 661.98865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.436334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.808593Z","time spent":"1.62750752s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:31:03.436354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352301382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:31:03.436386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:02.773614Z","time spent":"662.724614ms","remote":"127.0.0.1:48412","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-20T18:31:03.436413Z","caller":"traceutil/trace.go:171","msg":"trace[1082698255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"1.352388584s","start":"2026-02-20T18:31:02.084011Z","end":"2026-02-20T18:31:03.436399Z","steps":["trace[1082698255] 'agreement among raft nodes before linearized reading' (duration: 1.352298052s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.436444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:02.083993Z","time spent":"1.352445245s","remote":"127.0.0.1:48130","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-20T18:31:03.436698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:02.738166Z","time spent":"697.891056ms","remote":"127.0.0.1:48412","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-20T18:31:04.029739Z","caller":"traceutil/trace.go:171","msg":"trace[1120613542] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"130.537201ms","start":"2026-02-20T18:31:03.899154Z","end":"2026-02-20T18:31:04.029691Z","steps":["trace[1120613542] 'process raft request' (duration: 46.022158ms)","trace[1120613542] 'compare' (duration: 84.230427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:04.723708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.278524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:04.723813Z","caller":"traceutil/trace.go:171","msg":"trace[2052069505] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:916; }","duration":"410.479257ms","start":"2026-02-20T18:31:04.313315Z","end":"2026-02-20T18:31:04.723794Z","steps":["trace[2052069505] 'range keys from in-memory index tree' (duration: 410.151861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:04.723857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.313288Z","time spent":"410.560119ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:31:04.914235Z","caller":"traceutil/trace.go:171","msg":"trace[1575407723] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"142.911574ms","start":"2026-02-20T18:31:04.77129Z","end":"2026-02-20T18:31:04.914201Z","steps":["trace[1575407723] 'read index received' (duration: 142.72911ms)","trace[1575407723] 'applied index is now lower than readState.Index' (duration: 180.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:04.914312Z","caller":"traceutil/trace.go:171","msg":"trace[1322377101] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"185.439955ms","start":"2026-02-20T18:31:04.728827Z","end":"2026-02-20T18:31:04.914267Z","steps":["trace[1322377101] 'process raft request' (duration: 185.124069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:04.914549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.243809ms","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-20T18:31:04.914615Z","caller":"traceutil/trace.go:171","msg":"trace[94313945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"143.365202ms","start":"2026-02-20T18:31:04.771236Z","end":"2026-02-20T18:31:04.914601Z","steps":["trace[94313945] 'agreement among raft nodes before linearized reading' (duration: 143.097137ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:05.227071Z","caller":"traceutil/trace.go:171","msg":"trace[1367334343] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"395.295882ms","start":"2026-02-20T18:31:04.831749Z","end":"2026-02-20T18:31:05.227045Z","steps":["trace[1367334343] 'process raft request' (duration: 395.030207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.227227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.83173Z","time spent":"395.415085ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:05.22736Z","caller":"traceutil/trace.go:171","msg":"trace[315736495] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:959; }","duration":"312.944001ms","start":"2026-02-20T18:31:04.914406Z","end":"2026-02-20T18:31:05.22735Z","steps":["trace[315736495] 'read index received' (duration: 312.326578ms)","trace[315736495] 'applied index is now lower than readState.Index' (duration: 616.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:05.227417Z","caller":"traceutil/trace.go:171","msg":"trace[1241925618] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"316.866275ms","start":"2026-02-20T18:31:04.910543Z","end":"2026-02-20T18:31:05.227409Z","steps":["trace[1241925618] 'process raft request' (duration: 316.447376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.22746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.910496Z","time spent":"316.938755ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:31:05.227689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.410569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:05.227759Z","caller":"traceutil/trace.go:171","msg":"trace[48601436] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:919; }","duration":"434.553762ms","start":"2026-02-20T18:31:04.793188Z","end":"2026-02-20T18:31:05.227742Z","steps":["trace[48601436] 'agreement among raft nodes before linearized reading' (duration: 434.416479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.227801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.793156Z","time spent":"434.634884ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:31:05.27253Z","caller":"traceutil/trace.go:171","msg":"trace[493013073] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"354.101426ms","start":"2026-02-20T18:31:04.918404Z","end":"2026-02-20T18:31:05.272505Z","steps":["trace[493013073] 'process raft request' (duration: 353.913652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.272775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.918389Z","time spent":"354.211107ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:05.272817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.441505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:05.272872Z","caller":"traceutil/trace.go:171","msg":"trace[1218098550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"186.566337ms","start":"2026-02-20T18:31:05.086292Z","end":"2026-02-20T18:31:05.272859Z","steps":["trace[1218098550] 'agreement among raft nodes before linearized reading' (duration: 186.446545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.272912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.578365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T18:31:05.273002Z","caller":"traceutil/trace.go:171","msg":"trace[1232549879] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:921; }","duration":"353.711927ms","start":"2026-02-20T18:31:04.919264Z","end":"2026-02-20T18:31:05.272976Z","steps":["trace[1232549879] 'agreement among raft nodes before linearized reading' (duration: 353.433282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.273076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.919253Z","time spent":"353.809709ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:31:05.272779Z","caller":"traceutil/trace.go:171","msg":"trace[1672263470] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"352.302991ms","start":"2026-02-20T18:31:04.920416Z","end":"2026-02-20T18:31:05.272719Z","steps":["trace[1672263470] 'process raft request' (duration: 352.036587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.274146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:04.920404Z","time spent":"353.044486ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:31:05.772403Z","caller":"traceutil/trace.go:171","msg":"trace[520963178] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"497.755174ms","start":"2026-02-20T18:31:05.274615Z","end":"2026-02-20T18:31:05.77237Z","steps":["trace[520963178] 'read index received' (duration: 497.672992ms)","trace[520963178] 'applied index is now lower than readState.Index' (duration: 79.902µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:05.772476Z","caller":"traceutil/trace.go:171","msg":"trace[1612179579] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"540.408248ms","start":"2026-02-20T18:31:05.232029Z","end":"2026-02-20T18:31:05.772437Z","steps":["trace[1612179579] 'process raft request' (duration: 540.122893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.772666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.232005Z","time spent":"540.56595ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:31:05.772659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.999558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:05.772725Z","caller":"traceutil/trace.go:171","msg":"trace[506668876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"498.163541ms","start":"2026-02-20T18:31:05.274545Z","end":"2026-02-20T18:31:05.772708Z","steps":["trace[506668876] 'agreement among raft nodes before linearized reading' (duration: 497.978627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.772787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.274521Z","time spent":"498.253853ms","remote":"127.0.0.1:48132","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-20T18:31:05.846098Z","caller":"traceutil/trace.go:171","msg":"trace[1869421738] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"567.658891ms","start":"2026-02-20T18:31:05.278403Z","end":"2026-02-20T18:31:05.846062Z","steps":["trace[1869421738] 'process raft request' (duration: 567.435606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:05.846318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.278376Z","time spent":"567.807604ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:06.189449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.450344ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:31:06.189629Z","caller":"traceutil/trace.go:171","msg":"trace[144730696] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"417.118003ms","start":"2026-02-20T18:31:05.772486Z","end":"2026-02-20T18:31:06.189604Z","steps":["trace[144730696] 'read index received' (duration: 73.305971ms)","trace[144730696] 'applied index is now lower than readState.Index' (duration: 343.809212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:06.189782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.368579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:31:06.189843Z","caller":"traceutil/trace.go:171","msg":"trace[1321267397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"712.478271ms","start":"2026-02-20T18:31:05.477349Z","end":"2026-02-20T18:31:06.189827Z","steps":["trace[1321267397] 'agreement among raft nodes before linearized reading' (duration: 712.307668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.189869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.477321Z","time spent":"712.542713ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:31:06.189855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.270131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:31:06.189907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.549229ms","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-20T18:31:06.189948Z","caller":"traceutil/trace.go:171","msg":"trace[2085921128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"749.645601ms","start":"2026-02-20T18:31:05.440289Z","end":"2026-02-20T18:31:06.189934Z","steps":["trace[2085921128] 'agreement among raft nodes before linearized reading' (duration: 749.438797ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:06.189954Z","caller":"traceutil/trace.go:171","msg":"trace[1756268299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"544.429074ms","start":"2026-02-20T18:31:05.645502Z","end":"2026-02-20T18:31:06.189931Z","steps":["trace[1756268299] 'agreement among raft nodes before linearized reading' (duration: 544.109788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.189987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.440255Z","time spent":"749.723793ms","remote":"127.0.0.1:48412","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-20T18:31:06.189881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.378635ms","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-20T18:31:06.190137Z","caller":"traceutil/trace.go:171","msg":"trace[799640854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"324.693781ms","start":"2026-02-20T18:31:05.865435Z","end":"2026-02-20T18:31:06.190129Z","steps":["trace[799640854] 'agreement among raft nodes before linearized reading' (duration: 324.311164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.190158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.865412Z","time spent":"324.739532ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:31:06.190005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:05.645478Z","time spent":"544.516895ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:31:06.376348Z","caller":"traceutil/trace.go:171","msg":"trace[1964681329] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"182.699564ms","start":"2026-02-20T18:31:06.193613Z","end":"2026-02-20T18:31:06.376313Z","steps":["trace[1964681329] 'process raft request' (duration: 182.372628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.934386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.752933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:06.934655Z","caller":"traceutil/trace.go:171","msg":"trace[1560837899] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"740.695983ms","start":"2026-02-20T18:31:06.19393Z","end":"2026-02-20T18:31:06.934626Z","steps":["trace[1560837899] 'process raft request' (duration: 625.628644ms)","trace[1560837899] 'compare' (duration: 114.510669ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:06.934824Z","caller":"traceutil/trace.go:171","msg":"trace[676517275] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"738.588943ms","start":"2026-02-20T18:31:06.196185Z","end":"2026-02-20T18:31:06.934774Z","steps":["trace[676517275] 'process raft request' (duration: 738.346798ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:06.9349Z","caller":"traceutil/trace.go:171","msg":"trace[1360164803] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"739.748904ms","start":"2026-02-20T18:31:06.19506Z","end":"2026-02-20T18:31:06.934809Z","steps":["trace[1360164803] 'process raft request' (duration: 739.400788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.93493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:06.193901Z","time spent":"740.809995ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:06.935039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:06.195049Z","time spent":"739.957029ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:06.935085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:06.196172Z","time spent":"738.780276ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:07.357857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.253172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:07.357959Z","caller":"traceutil/trace.go:171","msg":"trace[1364297251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"272.373184ms","start":"2026-02-20T18:31:07.085563Z","end":"2026-02-20T18:31:07.357936Z","steps":["trace[1364297251] 'range keys from in-memory index tree' (duration: 272.141219ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:07.717157Z","caller":"traceutil/trace.go:171","msg":"trace[1674738252] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"240.135787ms","start":"2026-02-20T18:31:07.476997Z","end":"2026-02-20T18:31:07.717133Z","steps":["trace[1674738252] 'read index received' (duration: 240.022235ms)","trace[1674738252] 'applied index is now lower than readState.Index' (duration: 112.672µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:07.717218Z","caller":"traceutil/trace.go:171","msg":"trace[202446370] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"353.902041ms","start":"2026-02-20T18:31:07.363285Z","end":"2026-02-20T18:31:07.717187Z","steps":["trace[202446370] 'process raft request' (duration: 353.685237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:07.717279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.26734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:07.717313Z","caller":"traceutil/trace.go:171","msg":"trace[190313588] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:928; }","duration":"240.364432ms","start":"2026-02-20T18:31:07.47694Z","end":"2026-02-20T18:31:07.717304Z","steps":["trace[190313588] 'agreement among raft nodes before linearized reading' (duration: 240.28603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:07.717372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:07.363268Z","time spent":"354.034104ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:13.58568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154630787160365,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:31:13.656357Z","caller":"traceutil/trace.go:171","msg":"trace[966465529] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"694.180616ms","start":"2026-02-20T18:31:12.962146Z","end":"2026-02-20T18:31:13.656326Z","steps":["trace[966465529] 'process raft request' (duration: 693.931862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:13.66092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:12.962132Z","time spent":"694.284538ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:14.048447Z","caller":"traceutil/trace.go:171","msg":"trace[2009881871] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:999; }","duration":"963.33956ms","start":"2026-02-20T18:31:13.085052Z","end":"2026-02-20T18:31:14.048391Z","steps":["trace[2009881871] 'read index received' (duration: 571.066635ms)","trace[2009881871] 'applied index is now lower than readState.Index' (duration: 392.271995ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:14.048522Z","caller":"traceutil/trace.go:171","msg":"trace[79347285] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.086033023s","start":"2026-02-20T18:31:12.962455Z","end":"2026-02-20T18:31:14.048488Z","steps":["trace[79347285] 'process raft request' (duration: 1.08582455s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:14.048588Z","caller":"traceutil/trace.go:171","msg":"trace[1181859622] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.064842824s","start":"2026-02-20T18:31:12.983734Z","end":"2026-02-20T18:31:14.048577Z","steps":["trace[1181859622] 'process raft request' (duration: 1.064584829s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:14.048727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:12.983715Z","time spent":"1.064908425s","remote":"127.0.0.1:48412","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-20T18:31:14.048778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:12.962448Z","time spent":"1.086209716s","remote":"127.0.0.1:48412","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-20T18:31:14.048995Z","caller":"traceutil/trace.go:171","msg":"trace[1925736794] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.086721956s","start":"2026-02-20T18:31:12.962251Z","end":"2026-02-20T18:31:14.048973Z","steps":["trace[1925736794] 'process raft request' (duration: 1.085803409s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:14.049094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:12.962245Z","time spent":"1.086799887s","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:14.049099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.971243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:31:14.049119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.088903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:14.049149Z","caller":"traceutil/trace.go:171","msg":"trace[927028027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"310.060415ms","start":"2026-02-20T18:31:13.739075Z","end":"2026-02-20T18:31:14.049135Z","steps":["trace[927028027] 'agreement among raft nodes before linearized reading' (duration: 309.908912ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:14.049118Z","caller":"traceutil/trace.go:171","msg":"trace[983927567] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"442.545182ms","start":"2026-02-20T18:31:13.606497Z","end":"2026-02-20T18:31:14.049043Z","steps":["trace[983927567] 'process raft request' (duration: 441.8592ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:14.049177Z","caller":"traceutil/trace.go:171","msg":"trace[1342859595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"964.175605ms","start":"2026-02-20T18:31:13.084982Z","end":"2026-02-20T18:31:14.049158Z","steps":["trace[1342859595] 'agreement among raft nodes before linearized reading' (duration: 964.071634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:14.049232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:13.084968Z","time spent":"964.257487ms","remote":"127.0.0.1:48130","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-20T18:31:14.049181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:13.739051Z","time spent":"310.124076ms","remote":"127.0.0.1:48412","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-20T18:31:14.04942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:13.606469Z","time spent":"442.778216ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:14.439679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.600354ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:14.439911Z","caller":"traceutil/trace.go:171","msg":"trace[1111201220] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:960; }","duration":"206.851129ms","start":"2026-02-20T18:31:14.233032Z","end":"2026-02-20T18:31:14.439883Z","steps":["trace[1111201220] 'range keys from in-memory index tree' (duration: 206.579834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:14.440101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.234158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:14.440251Z","caller":"traceutil/trace.go:171","msg":"trace[1902154007] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"385.300344ms","start":"2026-02-20T18:31:14.054925Z","end":"2026-02-20T18:31:14.440225Z","steps":["trace[1902154007] 'process raft request' (duration: 127.7908ms)","trace[1902154007] 'compare' (duration: 257.092906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:14.440341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:14.054908Z","time spent":"385.392725ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:16.30297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.752471ms","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-20T18:31:16.303036Z","caller":"traceutil/trace.go:171","msg":"trace[176672451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"247.871062ms","start":"2026-02-20T18:31:16.055149Z","end":"2026-02-20T18:31:16.30302Z","steps":["trace[176672451] 'range keys from in-memory index tree' (duration: 247.503626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:16.30327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.854772ms","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-20T18:31:16.303371Z","caller":"traceutil/trace.go:171","msg":"trace[1104551273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"248.159108ms","start":"2026-02-20T18:31:16.05519Z","end":"2026-02-20T18:31:16.303349Z","steps":["trace[1104551273] 'range keys from in-memory index tree' (duration: 247.667149ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:34.528085Z","caller":"traceutil/trace.go:171","msg":"trace[1995158393] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"118.150165ms","start":"2026-02-20T18:31:34.409913Z","end":"2026-02-20T18:31:34.528063Z","steps":["trace[1995158393] 'process raft request' (duration: 118.042423ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:34.578626Z","caller":"traceutil/trace.go:171","msg":"trace[105781611] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"122.900653ms","start":"2026-02-20T18:31:34.455698Z","end":"2026-02-20T18:31:34.578599Z","steps":["trace[105781611] 'process raft request' (duration: 122.025886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:35.400183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.365302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:31:50.831365Z","caller":"traceutil/trace.go:171","msg":"trace[554647084] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1176; }","duration":"168.821937ms","start":"2026-02-20T18:31:50.662513Z","end":"2026-02-20T18:31:50.831335Z","steps":["trace[554647084] 'read index received' (duration: 35.197194ms)","trace[554647084] 'applied index is now lower than readState.Index' (duration: 133.622873ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:50.831473Z","caller":"traceutil/trace.go:171","msg":"trace[2086303704] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"179.856412ms","start":"2026-02-20T18:31:50.651584Z","end":"2026-02-20T18:31:50.83144Z","steps":["trace[2086303704] 'process raft request' (duration: 179.615538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:50.831598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.061061ms","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-20T18:31:50.831658Z","caller":"traceutil/trace.go:171","msg":"trace[1189515801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1126; }","duration":"169.147433ms","start":"2026-02-20T18:31:50.662492Z","end":"2026-02-20T18:31:50.831639Z","steps":["trace[1189515801] 'agreement among raft nodes before linearized reading' (duration: 168.961959ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:53.049311Z","caller":"traceutil/trace.go:171","msg":"trace[312551900] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"163.262004ms","start":"2026-02-20T18:31:52.886027Z","end":"2026-02-20T18:31:53.049289Z","steps":["trace[312551900] 'process raft request' (duration: 149.999527ms)","trace[312551900] 'compare' (duration: 13.149115ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:32:11.022327Z","caller":"traceutil/trace.go:171","msg":"trace[1161583610] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"116.810551ms","start":"2026-02-20T18:32:10.905471Z","end":"2026-02-20T18:32:11.022282Z","steps":["trace[1161583610] 'process raft request' (duration: 116.425833ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:46.597891Z","caller":"traceutil/trace.go:171","msg":"trace[470832491] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"105.975318ms","start":"2026-02-20T18:33:46.491881Z","end":"2026-02-20T18:33:46.597856Z","steps":["trace[470832491] 'process raft request' (duration: 105.857286ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:55.429523Z","caller":"traceutil/trace.go:171","msg":"trace[1195272436] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"111.159176ms","start":"2026-02-20T18:33:55.318339Z","end":"2026-02-20T18:33:55.429498Z","steps":["trace[1195272436] 'process raft request' (duration: 111.019833ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:03.689881Z","caller":"traceutil/trace.go:171","msg":"trace[1671474911] linearizableReadLoop","detail":"{readStateIndex:1744; appliedIndex:1743; }","duration":"227.781935ms","start":"2026-02-20T18:34:03.462074Z","end":"2026-02-20T18:34:03.689856Z","steps":["trace[1671474911] 'read index received' (duration: 227.583851ms)","trace[1671474911] 'applied index is now lower than readState.Index' (duration: 196.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:03.689959Z","caller":"traceutil/trace.go:171","msg":"trace[316787920] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"372.032659ms","start":"2026-02-20T18:34:03.317897Z","end":"2026-02-20T18:34:03.68993Z","steps":["trace[316787920] 'process raft request' (duration: 371.821805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:03.690072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.982828ms","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-20T18:34:03.690106Z","caller":"traceutil/trace.go:171","msg":"trace[1111570064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1666; }","duration":"228.05905ms","start":"2026-02-20T18:34:03.462036Z","end":"2026-02-20T18:34:03.690095Z","steps":["trace[1111570064] 'agreement among raft nodes before linearized reading' (duration: 227.920428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:03.6901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:03.317876Z","time spent":"372.134951ms","remote":"127.0.0.1:48412","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-20T18:34:03.978391Z","caller":"traceutil/trace.go:171","msg":"trace[2145740130] linearizableReadLoop","detail":"{readStateIndex:1745; appliedIndex:1744; }","duration":"249.626423ms","start":"2026-02-20T18:34:03.728741Z","end":"2026-02-20T18:34:03.978367Z","steps":["trace[2145740130] 'read index received' (duration: 177.995315ms)","trace[2145740130] 'applied index is now lower than readState.Index' (duration: 71.629947ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:03.978751Z","caller":"traceutil/trace.go:171","msg":"trace[1919153261] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"283.950123ms","start":"2026-02-20T18:34:03.694783Z","end":"2026-02-20T18:34:03.978733Z","steps":["trace[1919153261] 'process raft request' (duration: 211.950579ms)","trace[1919153261] 'compare' (duration: 71.504845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:03.978751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.387241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:34:03.978824Z","caller":"traceutil/trace.go:171","msg":"trace[1316672999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1667; }","duration":"171.491222ms","start":"2026-02-20T18:34:03.807314Z","end":"2026-02-20T18:34:03.978805Z","steps":["trace[1316672999] 'agreement among raft nodes before linearized reading' (duration: 171.296319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:03.978848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.756015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:34:03.978855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.105191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:34:03.978896Z","caller":"traceutil/trace.go:171","msg":"trace[1459500830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1667; }","duration":"120.854257ms","start":"2026-02-20T18:34:03.858029Z","end":"2026-02-20T18:34:03.978883Z","steps":["trace[1459500830] 'agreement among raft nodes before linearized reading' (duration: 120.705124ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:03.978915Z","caller":"traceutil/trace.go:171","msg":"trace[789118209] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1667; }","duration":"250.200662ms","start":"2026-02-20T18:34:03.728699Z","end":"2026-02-20T18:34:03.978899Z","steps":["trace[789118209] 'agreement among raft nodes before linearized reading' (duration: 250.013919ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:04.861706Z","caller":"traceutil/trace.go:171","msg":"trace[1596776669] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"119.999012ms","start":"2026-02-20T18:34:04.74168Z","end":"2026-02-20T18:34:04.861679Z","steps":["trace[1596776669] 'process raft request' (duration: 119.862319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:05.368992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.007659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:34:05.369087Z","caller":"traceutil/trace.go:171","msg":"trace[2053194749] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1671; }","duration":"205.205352ms","start":"2026-02-20T18:34:05.16386Z","end":"2026-02-20T18:34:05.369066Z","steps":["trace[2053194749] 'count revisions from in-memory index tree' (duration: 204.882787ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:06.015413Z","caller":"traceutil/trace.go:171","msg":"trace[1479272761] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1750; }","duration":"224.975912ms","start":"2026-02-20T18:34:05.790418Z","end":"2026-02-20T18:34:06.015394Z","steps":["trace[1479272761] 'read index received' (duration: 224.732318ms)","trace[1479272761] 'applied index is now lower than readState.Index' (duration: 242.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:06.015642Z","caller":"traceutil/trace.go:171","msg":"trace[634713634] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"314.904162ms","start":"2026-02-20T18:34:05.700546Z","end":"2026-02-20T18:34:06.01545Z","steps":["trace[634713634] 'process raft request' (duration: 314.678428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:06.015746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.287507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:34:06.015863Z","caller":"traceutil/trace.go:171","msg":"trace[1943214061] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1672; }","duration":"225.484692ms","start":"2026-02-20T18:34:05.790349Z","end":"2026-02-20T18:34:06.015834Z","steps":["trace[1943214061] 'agreement among raft nodes before linearized reading' (duration: 225.131176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:06.016021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:05.700529Z","time spent":"315.274468ms","remote":"127.0.0.1:48412","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-20T18:34:06.163632Z","caller":"traceutil/trace.go:171","msg":"trace[276334545] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1751; }","duration":"116.69501ms","start":"2026-02-20T18:34:06.046909Z","end":"2026-02-20T18:34:06.163604Z","steps":["trace[276334545] 'read index received' (duration: 8.626601ms)","trace[276334545] 'applied index is now lower than readState.Index' (duration: 108.066969ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:06.163659Z","caller":"traceutil/trace.go:171","msg":"trace[1935699278] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"143.011842ms","start":"2026-02-20T18:34:06.020621Z","end":"2026-02-20T18:34:06.163632Z","steps":["trace[1935699278] 'process raft request' (duration: 85.416155ms)","trace[1935699278] 'compare' (duration: 57.405522ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:06.163859Z","caller":"traceutil/trace.go:171","msg":"trace[950342366] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"142.763577ms","start":"2026-02-20T18:34:06.020993Z","end":"2026-02-20T18:34:06.163757Z","steps":["trace[950342366] 'process raft request' (duration: 142.561053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:06.164044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.764752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:34:06.164124Z","caller":"traceutil/trace.go:171","msg":"trace[157298011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1675; }","duration":"116.905164ms","start":"2026-02-20T18:34:06.0472Z","end":"2026-02-20T18:34:06.164105Z","steps":["trace[157298011] 'agreement among raft nodes before linearized reading' (duration: 116.6743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:06.164117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.179339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:34:06.164229Z","caller":"traceutil/trace.go:171","msg":"trace[904730247] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1675; }","duration":"117.328582ms","start":"2026-02-20T18:34:06.046874Z","end":"2026-02-20T18:34:06.164202Z","steps":["trace[904730247] 'agreement among raft nodes before linearized reading' (duration: 117.049387ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:06.656338Z","caller":"traceutil/trace.go:171","msg":"trace[1877526029] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"246.79246ms","start":"2026-02-20T18:34:06.409521Z","end":"2026-02-20T18:34:06.656314Z","steps":["trace[1877526029] 'process raft request' (duration: 246.635936ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:06.677786Z","caller":"traceutil/trace.go:171","msg":"trace[1274723956] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"267.347103ms","start":"2026-02-20T18:34:06.410404Z","end":"2026-02-20T18:34:06.677751Z","steps":["trace[1274723956] 'process raft request' (duration: 267.14521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.274735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.151271ms","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-20T18:34:08.274842Z","caller":"traceutil/trace.go:171","msg":"trace[338904812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1682; }","duration":"106.283604ms","start":"2026-02-20T18:34:08.168535Z","end":"2026-02-20T18:34:08.274818Z","steps":["trace[338904812] 'range keys from in-memory index tree' (duration: 106.005629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.274922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.214507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:34:08.27498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.396537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:08.275027Z","caller":"traceutil/trace.go:171","msg":"trace[316377368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1682; }","duration":"189.904218ms","start":"2026-02-20T18:34:08.085112Z","end":"2026-02-20T18:34:08.275016Z","steps":["trace[316377368] 'range keys from in-memory index tree' (duration: 189.291926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.27501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.767495ms","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-20T18:34:08.275037Z","caller":"traceutil/trace.go:171","msg":"trace[1359638922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1682; }","duration":"255.383771ms","start":"2026-02-20T18:34:08.019628Z","end":"2026-02-20T18:34:08.275012Z","steps":["trace[1359638922] 'range keys from in-memory index tree' (duration: 254.973913ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.2751Z","caller":"traceutil/trace.go:171","msg":"trace[1544666384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1682; }","duration":"106.202314ms","start":"2026-02-20T18:34:08.168861Z","end":"2026-02-20T18:34:08.275064Z","steps":["trace[1544666384] 'range keys from in-memory index tree' (duration: 105.575862ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.739955Z","caller":"traceutil/trace.go:171","msg":"trace[561301572] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"460.737015ms","start":"2026-02-20T18:34:08.279193Z","end":"2026-02-20T18:34:08.73993Z","steps":["trace[561301572] 'process raft request' (duration: 460.532582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.740123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.279171Z","time spent":"460.855707ms","remote":"127.0.0.1:48412","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-20T18:34:08.925862Z","caller":"traceutil/trace.go:171","msg":"trace[1117512035] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"644.839824ms","start":"2026-02-20T18:34:08.28098Z","end":"2026-02-20T18:34:08.92582Z","steps":["trace[1117512035] 'process raft request' (duration: 635.141203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.926026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.280949Z","time spent":"644.995188ms","remote":"127.0.0.1:48412","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-20T18:34:08.926206Z","caller":"traceutil/trace.go:171","msg":"trace[771350957] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"643.808885ms","start":"2026-02-20T18:34:08.282368Z","end":"2026-02-20T18:34:08.926177Z","steps":["trace[771350957] 'process raft request' (duration: 643.411137ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.926248Z","caller":"traceutil/trace.go:171","msg":"trace[1840874950] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"469.539581ms","start":"2026-02-20T18:34:08.456662Z","end":"2026-02-20T18:34:08.926202Z","steps":["trace[1840874950] 'process raft request' (duration: 469.49515ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.926321Z","caller":"traceutil/trace.go:171","msg":"trace[1277357598] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"643.712863ms","start":"2026-02-20T18:34:08.282525Z","end":"2026-02-20T18:34:08.926238Z","steps":["trace[1277357598] 'process raft request' (duration: 643.497139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.926375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.282356Z","time spent":"643.939307ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:34:08.926429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.456625Z","time spent":"469.731444ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:34:08.926299Z","caller":"traceutil/trace.go:171","msg":"trace[1355875836] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"642.229016ms","start":"2026-02-20T18:34:08.284017Z","end":"2026-02-20T18:34:08.926246Z","steps":["trace[1355875836] 'process raft request' (duration: 642.102684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.926548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.28251Z","time spent":"643.893397ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:34:08.926574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.28401Z","time spent":"642.507251ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:34:09.215652Z","caller":"traceutil/trace.go:171","msg":"trace[2146678840] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"257.780085ms","start":"2026-02-20T18:34:08.957663Z","end":"2026-02-20T18:34:09.215443Z","steps":["trace[2146678840] 'process raft request' (duration: 212.108022ms)","trace[2146678840] 'compare' (duration: 45.5123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:15.212414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.645984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:15.212541Z","caller":"traceutil/trace.go:171","msg":"trace[720166824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1711; }","duration":"127.843337ms","start":"2026-02-20T18:34:15.084671Z","end":"2026-02-20T18:34:15.212514Z","steps":["trace[720166824] 'range keys from in-memory index tree' (duration: 127.492731ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:16.745605Z","caller":"traceutil/trace.go:171","msg":"trace[349349001] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"216.408573ms","start":"2026-02-20T18:34:16.529171Z","end":"2026-02-20T18:34:16.74558Z","steps":["trace[349349001] 'process raft request' (duration: 208.18883ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:16.745591Z","caller":"traceutil/trace.go:171","msg":"trace[1123461725] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1801; }","duration":"138.578939ms","start":"2026-02-20T18:34:16.606961Z","end":"2026-02-20T18:34:16.74554Z","steps":["trace[1123461725] 'read index received' (duration: 130.445838ms)","trace[1123461725] 'applied index is now lower than readState.Index' (duration: 8.127141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:16.745872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.773923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:16.746017Z","caller":"traceutil/trace.go:171","msg":"trace[726960848] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1720; }","duration":"139.045348ms","start":"2026-02-20T18:34:16.606908Z","end":"2026-02-20T18:34:16.745953Z","steps":["trace[726960848] 'agreement among raft nodes before linearized reading' (duration: 138.755912ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:18.287258Z","caller":"traceutil/trace.go:171","msg":"trace[716836324] linearizableReadLoop","detail":"{readStateIndex:1812; appliedIndex:1811; }","duration":"202.800828ms","start":"2026-02-20T18:34:18.084432Z","end":"2026-02-20T18:34:18.287233Z","steps":["trace[716836324] 'read index received' (duration: 202.555933ms)","trace[716836324] 'applied index is now lower than readState.Index' (duration: 243.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:18.287392Z","caller":"traceutil/trace.go:171","msg":"trace[772750971] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"314.402232ms","start":"2026-02-20T18:34:17.972942Z","end":"2026-02-20T18:34:18.287344Z","steps":["trace[772750971] 'process raft request' (duration: 314.125337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:18.287422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.976641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:18.287481Z","caller":"traceutil/trace.go:171","msg":"trace[1658940029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1730; }","duration":"203.099014ms","start":"2026-02-20T18:34:18.084366Z","end":"2026-02-20T18:34:18.287465Z","steps":["trace[1658940029] 'agreement among raft nodes before linearized reading' (duration: 202.965921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:18.287592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:17.972912Z","time spent":"314.555026ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:34:31.97856Z","caller":"traceutil/trace.go:171","msg":"trace[713719419] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"174.589091ms","start":"2026-02-20T18:34:31.803945Z","end":"2026-02-20T18:34:31.978534Z","steps":["trace[713719419] 'read index received' (duration: 174.415348ms)","trace[713719419] 'applied index is now lower than readState.Index' (duration: 172.433µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:31.978643Z","caller":"traceutil/trace.go:171","msg":"trace[589816381] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"185.023216ms","start":"2026-02-20T18:34:31.793565Z","end":"2026-02-20T18:34:31.978588Z","steps":["trace[589816381] 'process raft request' (duration: 184.721381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:31.978786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.826786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-20T18:34:31.978839Z","caller":"traceutil/trace.go:171","msg":"trace[89077742] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1804; }","duration":"174.927147ms","start":"2026-02-20T18:34:31.803896Z","end":"2026-02-20T18:34:31.978823Z","steps":["trace[89077742] 'agreement among raft nodes before linearized reading' (duration: 174.743014ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:38.661695Z","caller":"traceutil/trace.go:171","msg":"trace[1999938326] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"329.82472ms","start":"2026-02-20T18:34:38.331841Z","end":"2026-02-20T18:34:38.661665Z","steps":["trace[1999938326] 'process raft request' (duration: 329.665677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:38.661913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:38.331806Z","time spent":"330.007243ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}