{"level":"warn","ts":"2026-02-27T01:23:30.017215Z","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-27T01:23:30.01729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-27T01:23:30.017352Z","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-27T01:23:30.017363Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-27T01:23:30.017377Z","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-27T01:23:30.017957Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-27T01:23:30.018027Z","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://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-27T01:23:30.023341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.118893ms"} {"level":"info","ts":"2026-02-27T01:23:30.028678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-27T01:23:30.028765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T01:23:30.028837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-27T01:23:30.028852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T01:23:30.028962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-27T01:23:30.029062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-27T01:23:30.041519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T01:23:30.044103Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T01:23:30.04527Z","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-27T01:23:30.048947Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T01:23:30.053084Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T01:23:30.053313Z","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-27T01:23:30.053478Z","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-27T01:23:30.053558Z","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-27T01:23:30.054093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-27T01:23:30.054259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-27T01:23:30.056699Z","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-27T01:23:30.056891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-27T01:23:30.056957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-27T01:23:30.05704Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T01:23:30.057118Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T01:23:30.530451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T01:23:30.530547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T01:23:30.530578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-27T01:23:30.530616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-27T01:23:30.530634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-27T01:23:30.530652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-27T01:23:30.530673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-27T01:23:31.255086Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-27T01:23:32.301523Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0464668s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:23:32.301976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T01:23:32.30198Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:23:32.302018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:23:32.302344Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T01:23:32.302529Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T01:23:32.306375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-02-27T01:23:32.307433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T01:23:32.432792Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:23:32.432926Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:23:32.432981Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:24:01.350239Z","caller":"traceutil/trace.go:171","msg":"trace[1277599971] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"201.435353ms","start":"2026-02-27T01:24:01.148786Z","end":"2026-02-27T01:24:01.350221Z","steps":["trace[1277599971] 'process raft request' (duration: 201.32365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:01.913795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.386552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-02-27T01:24:01.913891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.195004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T01:24:01.914067Z","caller":"traceutil/trace.go:171","msg":"trace[1029745772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"456.417569ms","start":"2026-02-27T01:24:01.457627Z","end":"2026-02-27T01:24:01.914044Z","steps":["trace[1029745772] 'range keys from in-memory index tree' (duration: 456.02182ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:01.913917Z","caller":"traceutil/trace.go:171","msg":"trace[689337608] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"372.619857ms","start":"2026-02-27T01:24:01.541273Z","end":"2026-02-27T01:24:01.913893Z","steps":["trace[689337608] 'range keys from in-memory index tree' (duration: 372.287919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:01.914135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:01.4576Z","time spent":"456.522511ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:24:01.914313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:01.541252Z","time spent":"372.975605ms","remote":"127.0.0.1:56126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T01:24:04.091104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.644131ms","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-27T01:24:04.091104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.914731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T01:24:04.091157Z","caller":"traceutil/trace.go:171","msg":"trace[948281437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"101.739414ms","start":"2026-02-27T01:24:03.989404Z","end":"2026-02-27T01:24:04.091144Z","steps":["trace[948281437] 'range keys from in-memory index tree' (duration: 101.453878ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:04.091167Z","caller":"traceutil/trace.go:171","msg":"trace[926313055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"152.011833ms","start":"2026-02-27T01:24:03.939137Z","end":"2026-02-27T01:24:04.091148Z","steps":["trace[926313055] 'range keys from in-memory index tree' (duration: 151.799888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:04.091261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.597894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:24:04.091361Z","caller":"traceutil/trace.go:171","msg":"trace[1236493037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"139.758517ms","start":"2026-02-27T01:24:03.951576Z","end":"2026-02-27T01:24:04.091334Z","steps":["trace[1236493037] 'range keys from in-memory index tree' (duration: 139.45632ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:06.27626Z","caller":"traceutil/trace.go:171","msg":"trace[1171654752] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"117.726064ms","start":"2026-02-27T01:24:06.158509Z","end":"2026-02-27T01:24:06.276235Z","steps":["trace[1171654752] 'process raft request' (duration: 117.5658ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:06.530104Z","caller":"traceutil/trace.go:171","msg":"trace[1198783177] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"370.342755ms","start":"2026-02-27T01:24:06.159732Z","end":"2026-02-27T01:24:06.530075Z","steps":["trace[1198783177] 'process raft request' (duration: 369.916935ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:06.530159Z","caller":"traceutil/trace.go:171","msg":"trace[1589665781] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"370.262494ms","start":"2026-02-27T01:24:06.159861Z","end":"2026-02-27T01:24:06.530123Z","steps":["trace[1589665781] 'process raft request' (duration: 370.118771ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:06.530069Z","caller":"traceutil/trace.go:171","msg":"trace[495384333] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"229.535644ms","start":"2026-02-27T01:24:06.300511Z","end":"2026-02-27T01:24:06.530047Z","steps":["trace[495384333] 'read index received' (duration: 229.085494ms)","trace[495384333] 'applied index is now lower than readState.Index' (duration: 426.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:06.530323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.793229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T01:24:06.530359Z","caller":"traceutil/trace.go:171","msg":"trace[1294565085] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:521; }","duration":"229.887112ms","start":"2026-02-27T01:24:06.300459Z","end":"2026-02-27T01:24:06.530346Z","steps":["trace[1294565085] 'agreement among raft nodes before linearized reading' (duration: 229.79753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:06.530695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:06.15972Z","time spent":"370.457058ms","remote":"127.0.0.1:56230","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-27T01:24:06.530841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:06.159836Z","time spent":"370.392936ms","remote":"127.0.0.1:56230","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-27T01:24:10.015668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:10.015738Z","caller":"traceutil/trace.go:171","msg":"trace[1080001847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"207.49389ms","start":"2026-02-27T01:24:09.808229Z","end":"2026-02-27T01:24:10.015723Z","steps":["trace[1080001847] 'range keys from in-memory index tree' (duration: 207.249925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:11.052917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227774855,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:24:11.553316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227774855,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:24:11.898739Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.60555753s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:24:11.899128Z","caller":"traceutil/trace.go:171","msg":"trace[623358908] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"1.34626168s","start":"2026-02-27T01:24:10.552843Z","end":"2026-02-27T01:24:11.899105Z","steps":["trace[623358908] 'read index received' (duration: 1.346057874s)","trace[623358908] 'applied index is now lower than readState.Index' (duration: 202.666µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:11.899212Z","caller":"traceutil/trace.go:171","msg":"trace[1722335252] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.606132454s","start":"2026-02-27T01:24:10.293049Z","end":"2026-02-27T01:24:11.899181Z","steps":["trace[1722335252] 'process raft request' (duration: 1.605881698s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:11.899302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346443293s","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-27T01:24:11.899338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:10.293021Z","time spent":"1.606244997s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:24:11.899361Z","caller":"traceutil/trace.go:171","msg":"trace[1233259065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"1.346545416s","start":"2026-02-27T01:24:10.552794Z","end":"2026-02-27T01:24:11.899339Z","steps":["trace[1233259065] 'agreement among raft nodes before linearized reading' (duration: 1.346443003s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:11.89942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:10.552774Z","time spent":"1.346629968s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T01:24:11.913446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105402821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:24:11.913469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.164221ms","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-27T01:24:11.913454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341508723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:24:11.913505Z","caller":"traceutil/trace.go:171","msg":"trace[893958461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"496.236191ms","start":"2026-02-27T01:24:11.417257Z","end":"2026-02-27T01:24:11.913493Z","steps":["trace[893958461] 'agreement among raft nodes before linearized reading' (duration: 496.126399ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:11.913503Z","caller":"traceutil/trace.go:171","msg":"trace[814186604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"1.105519444s","start":"2026-02-27T01:24:10.807967Z","end":"2026-02-27T01:24:11.913487Z","steps":["trace[814186604] 'agreement among raft nodes before linearized reading' (duration: 1.105437142s)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:11.913524Z","caller":"traceutil/trace.go:171","msg":"trace[352684136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"1.341608215s","start":"2026-02-27T01:24:10.571899Z","end":"2026-02-27T01:24:11.913507Z","steps":["trace[352684136] 'agreement among raft nodes before linearized reading' (duration: 1.341448311s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:11.913528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:11.417229Z","time spent":"496.293373ms","remote":"127.0.0.1:56230","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-27T01:24:11.913537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:10.80794Z","time spent":"1.105589185s","remote":"127.0.0.1:55954","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-27T01:24:11.913557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:10.571888Z","time spent":"1.341661837s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T01:24:17.277355Z","caller":"traceutil/trace.go:171","msg":"trace[1813506227] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"209.261428ms","start":"2026-02-27T01:24:17.068073Z","end":"2026-02-27T01:24:17.277334Z","steps":["trace[1813506227] 'process raft request' (duration: 208.87411ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:17.285935Z","caller":"traceutil/trace.go:171","msg":"trace[1288305742] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"104.65265ms","start":"2026-02-27T01:24:17.181258Z","end":"2026-02-27T01:24:17.28591Z","steps":["trace[1288305742] 'process raft request' (duration: 104.361743ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:29.962582Z","caller":"traceutil/trace.go:171","msg":"trace[678688437] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"153.894965ms","start":"2026-02-27T01:24:29.808661Z","end":"2026-02-27T01:24:29.962556Z","steps":["trace[678688437] 'read index received' (duration: 153.65721ms)","trace[678688437] 'applied index is now lower than readState.Index' (duration: 236.725µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:29.962711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.032518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:29.962682Z","caller":"traceutil/trace.go:171","msg":"trace[1728597116] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"351.233706ms","start":"2026-02-27T01:24:29.611398Z","end":"2026-02-27T01:24:29.962632Z","steps":["trace[1728597116] 'process raft request' (duration: 351.002451ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:29.962746Z","caller":"traceutil/trace.go:171","msg":"trace[2075844895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"154.12482ms","start":"2026-02-27T01:24:29.80861Z","end":"2026-02-27T01:24:29.962735Z","steps":["trace[2075844895] 'agreement among raft nodes before linearized reading' (duration: 154.048099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:29.962917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:29.611385Z","time spent":"351.39163ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:24:30.647853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.196543ms","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-27T01:24:30.647975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.580646ms","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-27T01:24:30.647877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.306822ms","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-27T01:24:30.64805Z","caller":"traceutil/trace.go:171","msg":"trace[768128089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:710; }","duration":"576.686889ms","start":"2026-02-27T01:24:30.071347Z","end":"2026-02-27T01:24:30.648034Z","steps":["trace[768128089] 'range keys from in-memory index tree' (duration: 576.430753ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:30.648052Z","caller":"traceutil/trace.go:171","msg":"trace[345890211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:710; }","duration":"577.524557ms","start":"2026-02-27T01:24:30.070515Z","end":"2026-02-27T01:24:30.64804Z","steps":["trace[345890211] 'range keys from in-memory index tree' (duration: 577.151758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:30.648085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.071337Z","time spent":"576.7413ms","remote":"127.0.0.1:56230","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-27T01:24:30.648104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.070505Z","time spent":"577.583438ms","remote":"127.0.0.1:56230","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-27T01:24:30.647983Z","caller":"traceutil/trace.go:171","msg":"trace[1927722355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:710; }","duration":"582.358316ms","start":"2026-02-27T01:24:30.065581Z","end":"2026-02-27T01:24:30.64794Z","steps":["trace[1927722355] 'range keys from in-memory index tree' (duration: 581.994858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:30.648326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.065571Z","time spent":"582.738764ms","remote":"127.0.0.1:56230","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-27T01:24:30.647999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.949781ms","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-27T01:24:30.648385Z","caller":"traceutil/trace.go:171","msg":"trace[1331818802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"588.35616ms","start":"2026-02-27T01:24:30.060006Z","end":"2026-02-27T01:24:30.648362Z","steps":["trace[1331818802] 'range keys from in-memory index tree' (duration: 587.836449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:30.648422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.059986Z","time spent":"588.425572ms","remote":"127.0.0.1:56230","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-27T01:24:31.174278Z","caller":"traceutil/trace.go:171","msg":"trace[664337338] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"521.767465ms","start":"2026-02-27T01:24:30.652478Z","end":"2026-02-27T01:24:31.174246Z","steps":["trace[664337338] 'process raft request' (duration: 521.717294ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:31.174369Z","caller":"traceutil/trace.go:171","msg":"trace[1087484141] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"521.888708ms","start":"2026-02-27T01:24:30.65244Z","end":"2026-02-27T01:24:31.174329Z","steps":["trace[1087484141] 'process raft request' (duration: 521.56186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:31.174484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.652451Z","time spent":"521.921719ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:24:31.174608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.652411Z","time spent":"522.054362ms","remote":"127.0.0.1:56230","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-27T01:24:31.479261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.345273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:24:31.479434Z","caller":"traceutil/trace.go:171","msg":"trace[2078150678] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"826.094498ms","start":"2026-02-27T01:24:30.653309Z","end":"2026-02-27T01:24:31.479404Z","steps":["trace[2078150678] 'process raft request' (duration: 826.031607ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:31.479451Z","caller":"traceutil/trace.go:171","msg":"trace[257403682] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"826.224561ms","start":"2026-02-27T01:24:30.653195Z","end":"2026-02-27T01:24:31.47942Z","steps":["trace[257403682] 'process raft request' (duration: 721.49863ms)","trace[257403682] 'compare' (duration: 104.26205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:31.479505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.653288Z","time spent":"826.19019ms","remote":"127.0.0.1:56230","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-27T01:24:31.479569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:30.653179Z","time spent":"826.321183ms","remote":"127.0.0.1:56230","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-27T01:24:32.965301Z","caller":"traceutil/trace.go:171","msg":"trace[979200696] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"158.940738ms","start":"2026-02-27T01:24:32.806337Z","end":"2026-02-27T01:24:32.965278Z","steps":["trace[979200696] 'read index received' (duration: 158.679792ms)","trace[979200696] 'applied index is now lower than readState.Index' (duration: 259.956µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:32.965433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.078832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:32.965465Z","caller":"traceutil/trace.go:171","msg":"trace[1658986954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"159.154454ms","start":"2026-02-27T01:24:32.806302Z","end":"2026-02-27T01:24:32.965457Z","steps":["trace[1658986954] 'agreement among raft nodes before linearized reading' (duration: 159.063412ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:32.965691Z","caller":"traceutil/trace.go:171","msg":"trace[1128139574] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"287.304191ms","start":"2026-02-27T01:24:32.678348Z","end":"2026-02-27T01:24:32.965652Z","steps":["trace[1128139574] 'process raft request' (duration: 286.728437ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:33.482381Z","caller":"traceutil/trace.go:171","msg":"trace[835148621] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"231.674532ms","start":"2026-02-27T01:24:33.250687Z","end":"2026-02-27T01:24:33.482362Z","steps":["trace[835148621] 'process raft request' (duration: 154.985111ms)","trace[835148621] 'compare' (duration: 76.586439ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:35.647875Z","caller":"traceutil/trace.go:171","msg":"trace[2024467584] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"136.72084ms","start":"2026-02-27T01:24:35.511132Z","end":"2026-02-27T01:24:35.647852Z","steps":["trace[2024467584] 'read index received' (duration: 136.527825ms)","trace[2024467584] 'applied index is now lower than readState.Index' (duration: 192.055µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:35.648067Z","caller":"traceutil/trace.go:171","msg":"trace[623343104] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"139.787609ms","start":"2026-02-27T01:24:35.508252Z","end":"2026-02-27T01:24:35.64804Z","steps":["trace[623343104] 'process raft request' (duration: 139.468512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:35.648113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.952026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:24:35.648162Z","caller":"traceutil/trace.go:171","msg":"trace[642091131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"137.047167ms","start":"2026-02-27T01:24:35.511098Z","end":"2026-02-27T01:24:35.648146Z","steps":["trace[642091131] 'agreement among raft nodes before linearized reading' (duration: 136.863233ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:39.782158Z","caller":"traceutil/trace.go:171","msg":"trace[467318441] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"114.059591ms","start":"2026-02-27T01:24:39.668073Z","end":"2026-02-27T01:24:39.782132Z","steps":["trace[467318441] 'process raft request' (duration: 113.907058ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:39.78981Z","caller":"traceutil/trace.go:171","msg":"trace[839882912] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"117.60747ms","start":"2026-02-27T01:24:39.672189Z","end":"2026-02-27T01:24:39.789796Z","steps":["trace[839882912] 'process raft request' (duration: 117.526648ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:41.71069Z","caller":"traceutil/trace.go:171","msg":"trace[2041650199] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"182.581569ms","start":"2026-02-27T01:24:41.52807Z","end":"2026-02-27T01:24:41.710652Z","steps":["trace[2041650199] 'process raft request' (duration: 152.695589ms)","trace[2041650199] 'compare' (duration: 29.680906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:42.034622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.032006ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-27T01:24:42.034724Z","caller":"traceutil/trace.go:171","msg":"trace[1756932870] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"248.314805ms","start":"2026-02-27T01:24:41.786391Z","end":"2026-02-27T01:24:42.034706Z","steps":["trace[1756932870] 'read index received' (duration: 33.7µs)","trace[1756932870] 'applied index is now lower than readState.Index' (duration: 248.279775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:42.034857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.454349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T01:24:42.034882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.256345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:42.034913Z","caller":"traceutil/trace.go:171","msg":"trace[2021951976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"248.543741ms","start":"2026-02-27T01:24:41.786356Z","end":"2026-02-27T01:24:42.0349Z","steps":["trace[2021951976] 'agreement among raft nodes before linearized reading' (duration: 248.395027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:42.034927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.673469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:24:42.034929Z","caller":"traceutil/trace.go:171","msg":"trace[1405501047] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"187.317986ms","start":"2026-02-27T01:24:41.847597Z","end":"2026-02-27T01:24:42.034915Z","steps":["trace[1405501047] 'agreement among raft nodes before linearized reading' (duration: 187.236294ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:42.034973Z","caller":"traceutil/trace.go:171","msg":"trace[764216875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"242.722081ms","start":"2026-02-27T01:24:41.792226Z","end":"2026-02-27T01:24:42.034948Z","steps":["trace[764216875] 'agreement among raft nodes before linearized reading' (duration: 242.580088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:42.035096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.819087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:42.035123Z","caller":"traceutil/trace.go:171","msg":"trace[1362878236] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"187.844537ms","start":"2026-02-27T01:24:41.847271Z","end":"2026-02-27T01:24:42.035115Z","steps":["trace[1362878236] 'agreement among raft nodes before linearized reading' (duration: 187.808107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:42.03511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.764436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:42.035247Z","caller":"traceutil/trace.go:171","msg":"trace[797254338] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:747; }","duration":"187.941309ms","start":"2026-02-27T01:24:41.847269Z","end":"2026-02-27T01:24:42.035211Z","steps":["trace[797254338] 'agreement among raft nodes before linearized reading' (duration: 187.720835ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:42.214129Z","caller":"traceutil/trace.go:171","msg":"trace[1477006746] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"175.94472ms","start":"2026-02-27T01:24:42.038168Z","end":"2026-02-27T01:24:42.214112Z","steps":["trace[1477006746] 'process raft request' (duration: 175.840818ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:42.222573Z","caller":"traceutil/trace.go:171","msg":"trace[1788459652] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"139.669626ms","start":"2026-02-27T01:24:42.082889Z","end":"2026-02-27T01:24:42.222559Z","steps":["trace[1788459652] 'read index received' (duration: 131.187316ms)","trace[1788459652] 'applied index is now lower than readState.Index' (duration: 8.48151ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:42.222651Z","caller":"traceutil/trace.go:171","msg":"trace[1474179111] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"184.034863ms","start":"2026-02-27T01:24:42.038608Z","end":"2026-02-27T01:24:42.222643Z","steps":["trace[1474179111] 'process raft request' (duration: 183.857279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:42.223052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.149846ms","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-27T01:24:42.22309Z","caller":"traceutil/trace.go:171","msg":"trace[1932766747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"140.240619ms","start":"2026-02-27T01:24:42.082842Z","end":"2026-02-27T01:24:42.223083Z","steps":["trace[1932766747] 'agreement among raft nodes before linearized reading' (duration: 140.122516ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:43.755398Z","caller":"traceutil/trace.go:171","msg":"trace[288265911] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"223.031717ms","start":"2026-02-27T01:24:43.532338Z","end":"2026-02-27T01:24:43.75537Z","steps":["trace[288265911] 'process raft request' (duration: 222.701769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:43.916115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.687645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:24:43.91623Z","caller":"traceutil/trace.go:171","msg":"trace[33402692] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"154.295995ms","start":"2026-02-27T01:24:43.761914Z","end":"2026-02-27T01:24:43.91621Z","steps":["trace[33402692] 'process raft request' (duration: 31.415745ms)","trace[33402692] 'compare' (duration: 122.583742ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:44.361286Z","caller":"traceutil/trace.go:171","msg":"trace[569774184] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"133.552408ms","start":"2026-02-27T01:24:44.227686Z","end":"2026-02-27T01:24:44.361239Z","steps":["trace[569774184] 'read index received' (duration: 133.316033ms)","trace[569774184] 'applied index is now lower than readState.Index' (duration: 235.265µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:44.361352Z","caller":"traceutil/trace.go:171","msg":"trace[260475163] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"139.972182ms","start":"2026-02-27T01:24:44.22136Z","end":"2026-02-27T01:24:44.361332Z","steps":["trace[260475163] 'process raft request' (duration: 139.720287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:44.361548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.839615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T01:24:44.361624Z","caller":"traceutil/trace.go:171","msg":"trace[1374163221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"133.961648ms","start":"2026-02-27T01:24:44.227645Z","end":"2026-02-27T01:24:44.361606Z","steps":["trace[1374163221] 'agreement among raft nodes before linearized reading' (duration: 133.715803ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:46.458411Z","caller":"traceutil/trace.go:171","msg":"trace[874260280] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"128.189435ms","start":"2026-02-27T01:24:46.330208Z","end":"2026-02-27T01:24:46.458397Z","steps":["trace[874260280] 'process raft request' (duration: 128.072762ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:54.076723Z","caller":"traceutil/trace.go:171","msg":"trace[1686846637] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"103.513705ms","start":"2026-02-27T01:24:53.973185Z","end":"2026-02-27T01:24:54.076699Z","steps":["trace[1686846637] 'read index received' (duration: 103.386002ms)","trace[1686846637] 'applied index is now lower than readState.Index' (duration: 126.133µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:54.076809Z","caller":"traceutil/trace.go:171","msg":"trace[818579917] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"254.354494ms","start":"2026-02-27T01:24:53.822413Z","end":"2026-02-27T01:24:54.076767Z","steps":["trace[818579917] 'process raft request' (duration: 254.14574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:54.076969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.775011ms","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-27T01:24:54.077087Z","caller":"traceutil/trace.go:171","msg":"trace[1114477458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"103.961335ms","start":"2026-02-27T01:24:53.973083Z","end":"2026-02-27T01:24:54.077045Z","steps":["trace[1114477458] 'agreement among raft nodes before linearized reading' (duration: 103.706829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:54.487879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.336391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:24:54.48802Z","caller":"traceutil/trace.go:171","msg":"trace[1347509165] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"406.459532ms","start":"2026-02-27T01:24:54.081542Z","end":"2026-02-27T01:24:54.488001Z","steps":["trace[1347509165] 'process raft request' (duration: 192.902776ms)","trace[1347509165] 'compare' (duration: 213.239099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:24:54.48808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:24:54.081519Z","time spent":"406.532973ms","remote":"127.0.0.1:56230","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-27T01:24:54.656298Z","caller":"traceutil/trace.go:171","msg":"trace[1131465250] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"142.889882ms","start":"2026-02-27T01:24:54.513378Z","end":"2026-02-27T01:24:54.656268Z","steps":["trace[1131465250] 'process raft request' (duration: 121.749262ms)","trace[1131465250] 'compare' (duration: 20.941475ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:54.656582Z","caller":"traceutil/trace.go:171","msg":"trace[1040753431] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"142.956874ms","start":"2026-02-27T01:24:54.51358Z","end":"2026-02-27T01:24:54.656537Z","steps":["trace[1040753431] 'process raft request' (duration: 142.640416ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:55.608452Z","caller":"traceutil/trace.go:171","msg":"trace[1338067633] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"199.147355ms","start":"2026-02-27T01:24:55.409276Z","end":"2026-02-27T01:24:55.608423Z","steps":["trace[1338067633] 'read index received' (duration: 198.90577ms)","trace[1338067633] 'applied index is now lower than readState.Index' (duration: 240.185µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:24:55.608502Z","caller":"traceutil/trace.go:171","msg":"trace[1940774452] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"229.338197ms","start":"2026-02-27T01:24:55.379128Z","end":"2026-02-27T01:24:55.608466Z","steps":["trace[1940774452] 'process raft request' (duration: 229.089881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:55.608646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.357809ms","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-27T01:24:55.608684Z","caller":"traceutil/trace.go:171","msg":"trace[1199702687] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:784; }","duration":"199.475502ms","start":"2026-02-27T01:24:55.409197Z","end":"2026-02-27T01:24:55.608672Z","steps":["trace[1199702687] 'agreement among raft nodes before linearized reading' (duration: 199.330689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:24:55.92465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.546154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:24:55.924678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.419033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:24:55.924765Z","caller":"traceutil/trace.go:171","msg":"trace[1126657861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"116.566905ms","start":"2026-02-27T01:24:55.808178Z","end":"2026-02-27T01:24:55.924745Z","steps":["trace[1126657861] 'range keys from in-memory index tree' (duration: 116.201628ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:55.924792Z","caller":"traceutil/trace.go:171","msg":"trace[138514355] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:784; }","duration":"142.737218ms","start":"2026-02-27T01:24:55.781982Z","end":"2026-02-27T01:24:55.92472Z","steps":["trace[138514355] 'count revisions from in-memory index tree' (duration: 142.415842ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:56.323451Z","caller":"traceutil/trace.go:171","msg":"trace[1068193025] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"238.38323ms","start":"2026-02-27T01:24:56.08503Z","end":"2026-02-27T01:24:56.323413Z","steps":["trace[1068193025] 'process raft request' (duration: 238.207456ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:56.839068Z","caller":"traceutil/trace.go:171","msg":"trace[1176476617] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"174.827684ms","start":"2026-02-27T01:24:56.664132Z","end":"2026-02-27T01:24:56.83896Z","steps":["trace[1176476617] 'process raft request' (duration: 174.632439ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:24:56.854331Z","caller":"traceutil/trace.go:171","msg":"trace[225500922] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"189.004869ms","start":"2026-02-27T01:24:56.665308Z","end":"2026-02-27T01:24:56.854313Z","steps":["trace[225500922] 'process raft request' (duration: 188.718023ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:10.723893Z","caller":"traceutil/trace.go:171","msg":"trace[1902297588] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"325.804695ms","start":"2026-02-27T01:25:10.39806Z","end":"2026-02-27T01:25:10.723865Z","steps":["trace[1902297588] 'process raft request' (duration: 325.669072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:10.724104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:10.398043Z","time spent":"325.976569ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:11.176066Z","caller":"traceutil/trace.go:171","msg":"trace[714004254] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"390.801742ms","start":"2026-02-27T01:25:10.785243Z","end":"2026-02-27T01:25:11.176045Z","steps":["trace[714004254] 'read index received' (duration: 325.689602ms)","trace[714004254] 'applied index is now lower than readState.Index' (duration: 65.11114ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:11.176169Z","caller":"traceutil/trace.go:171","msg":"trace[1009596171] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"431.91647ms","start":"2026-02-27T01:25:10.744219Z","end":"2026-02-27T01:25:11.176136Z","steps":["trace[1009596171] 'process raft request' (duration: 366.696887ms)","trace[1009596171] 'compare' (duration: 65.035458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:11.176305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.039079ms","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-27T01:25:11.176324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:10.744195Z","time spent":"432.044582ms","remote":"127.0.0.1:56230","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-27T01:25:11.176355Z","caller":"traceutil/trace.go:171","msg":"trace[890257025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"391.12005ms","start":"2026-02-27T01:25:10.785211Z","end":"2026-02-27T01:25:11.176331Z","steps":["trace[890257025] 'agreement among raft nodes before linearized reading' (duration: 390.952347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:11.176392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:10.7852Z","time spent":"391.183631ms","remote":"127.0.0.1:56230","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-27T01:25:11.176517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.256973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:11.176549Z","caller":"traceutil/trace.go:171","msg":"trace[510074770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"369.325535ms","start":"2026-02-27T01:25:10.807215Z","end":"2026-02-27T01:25:11.176541Z","steps":["trace[510074770] 'agreement among raft nodes before linearized reading' (duration: 369.282194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:11.176572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:10.807191Z","time spent":"369.376086ms","remote":"127.0.0.1:55954","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-27T01:25:11.176935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.592344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T01:25:11.177012Z","caller":"traceutil/trace.go:171","msg":"trace[494198272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"282.720446ms","start":"2026-02-27T01:25:10.894271Z","end":"2026-02-27T01:25:11.176991Z","steps":["trace[494198272] 'agreement among raft nodes before linearized reading' (duration: 282.429149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:11.176998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.9167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T01:25:11.177076Z","caller":"traceutil/trace.go:171","msg":"trace[2101909328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"265.040283ms","start":"2026-02-27T01:25:10.912014Z","end":"2026-02-27T01:25:11.177055Z","steps":["trace[2101909328] 'agreement among raft nodes before linearized reading' (duration: 264.738927ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:11.283336Z","caller":"traceutil/trace.go:171","msg":"trace[817446739] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"102.289679ms","start":"2026-02-27T01:25:11.181029Z","end":"2026-02-27T01:25:11.283319Z","steps":["trace[817446739] 'process raft request' (duration: 102.245888ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:11.283643Z","caller":"traceutil/trace.go:171","msg":"trace[754196148] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"102.624706ms","start":"2026-02-27T01:25:11.180982Z","end":"2026-02-27T01:25:11.283607Z","steps":["trace[754196148] 'process raft request' (duration: 101.710776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:17.245509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227775657,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:25:17.388786Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083682793s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T01:25:17.389117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:16.304986Z","time spent":"1.084125832s","remote":"127.0.0.1:55990","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T01:25:17.389133Z","caller":"traceutil/trace.go:171","msg":"trace[1993004077] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"644.542183ms","start":"2026-02-27T01:25:16.744563Z","end":"2026-02-27T01:25:17.389106Z","steps":["trace[1993004077] 'read index received' (duration: 644.43133ms)","trace[1993004077] 'applied index is now lower than readState.Index' (duration: 109.393µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:17.389299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.719987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T01:25:17.389362Z","caller":"traceutil/trace.go:171","msg":"trace[877804547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"644.816469ms","start":"2026-02-27T01:25:16.744528Z","end":"2026-02-27T01:25:17.389344Z","steps":["trace[877804547] 'agreement among raft nodes before linearized reading' (duration: 644.634835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:17.389375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.275066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:17.389397Z","caller":"traceutil/trace.go:171","msg":"trace[1131847880] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:891; }","duration":"128.322297ms","start":"2026-02-27T01:25:17.261068Z","end":"2026-02-27T01:25:17.38939Z","steps":["trace[1131847880] 'agreement among raft nodes before linearized reading' (duration: 128.278816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:17.3894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:16.744511Z","time spent":"644.88133ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T01:25:17.389445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.003429ms","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-27T01:25:17.389504Z","caller":"traceutil/trace.go:171","msg":"trace[507558566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"189.09961ms","start":"2026-02-27T01:25:17.200384Z","end":"2026-02-27T01:25:17.389484Z","steps":["trace[507558566] 'agreement among raft nodes before linearized reading' (duration: 188.897336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:17.389612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.004214ms","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-27T01:25:17.389628Z","caller":"traceutil/trace.go:171","msg":"trace[2040834876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"147.066015ms","start":"2026-02-27T01:25:17.242557Z","end":"2026-02-27T01:25:17.389623Z","steps":["trace[2040834876] 'agreement among raft nodes before linearized reading' (duration: 147.027184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.140826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.652778ms","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-27T01:25:18.140941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.817542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:18.141032Z","caller":"traceutil/trace.go:171","msg":"trace[262951314] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:891; }","duration":"103.835572ms","start":"2026-02-27T01:25:18.037139Z","end":"2026-02-27T01:25:18.140975Z","steps":["trace[262951314] 'range keys from in-memory index tree' (duration: 103.614688ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:18.141291Z","caller":"traceutil/trace.go:171","msg":"trace[856578966] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"747.587399ms","start":"2026-02-27T01:25:17.393687Z","end":"2026-02-27T01:25:18.141275Z","steps":["trace[856578966] 'process raft request' (duration: 747.515747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.141394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.39368Z","time spent":"747.67032ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:18.14148Z","caller":"traceutil/trace.go:171","msg":"trace[1731274438] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"751.069546ms","start":"2026-02-27T01:25:17.390392Z","end":"2026-02-27T01:25:18.141461Z","steps":["trace[1731274438] 'process raft request' (duration: 144.61064ms)","trace[1731274438] 'compare' (duration: 605.520075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:18.141577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.39023Z","time spent":"751.28358ms","remote":"127.0.0.1:55990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:18.141568Z","caller":"traceutil/trace.go:171","msg":"trace[143412239] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"747.917705ms","start":"2026-02-27T01:25:17.393617Z","end":"2026-02-27T01:25:18.141535Z","steps":["trace[143412239] 'process raft request' (duration: 747.467696ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:18.141658Z","caller":"traceutil/trace.go:171","msg":"trace[377420455] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"747.587849ms","start":"2026-02-27T01:25:17.394053Z","end":"2026-02-27T01:25:18.141641Z","steps":["trace[377420455] 'process raft request' (duration: 747.18562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.141725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.393602Z","time spent":"748.036718ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:18.14177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.394042Z","time spent":"747.6669ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:18.142013Z","caller":"traceutil/trace.go:171","msg":"trace[126208996] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"747.889155ms","start":"2026-02-27T01:25:17.394117Z","end":"2026-02-27T01:25:18.142006Z","steps":["trace[126208996] 'process raft request' (duration: 747.443735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.142051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.39411Z","time spent":"747.922445ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:18.142055Z","caller":"traceutil/trace.go:171","msg":"trace[677138774] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:926; }","duration":"334.007608ms","start":"2026-02-27T01:25:17.808019Z","end":"2026-02-27T01:25:18.142027Z","steps":["trace[677138774] 'read index received' (duration: 329.873767ms)","trace[677138774] 'applied index is now lower than readState.Index' (duration: 4.132571ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:18.142155Z","caller":"traceutil/trace.go:171","msg":"trace[382691992] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"747.332342ms","start":"2026-02-27T01:25:17.394792Z","end":"2026-02-27T01:25:18.142124Z","steps":["trace[382691992] 'process raft request' (duration: 747.098167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.142307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.394764Z","time spent":"747.451485ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:18.1423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.258614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:18.142368Z","caller":"traceutil/trace.go:171","msg":"trace[194316294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"334.370186ms","start":"2026-02-27T01:25:17.807982Z","end":"2026-02-27T01:25:18.142352Z","steps":["trace[194316294] 'agreement among raft nodes before linearized reading' (duration: 334.142411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:18.142413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:17.807964Z","time spent":"334.437648ms","remote":"127.0.0.1:55954","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-27T01:25:28.511937Z","caller":"traceutil/trace.go:171","msg":"trace[484450450] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"219.875347ms","start":"2026-02-27T01:25:28.292041Z","end":"2026-02-27T01:25:28.511916Z","steps":["trace[484450450] 'process raft request' (duration: 219.764545ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:28.532641Z","caller":"traceutil/trace.go:171","msg":"trace[719038506] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"239.959473ms","start":"2026-02-27T01:25:28.29267Z","end":"2026-02-27T01:25:28.532629Z","steps":["trace[719038506] 'process raft request' (duration: 239.914632ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:28.532679Z","caller":"traceutil/trace.go:171","msg":"trace[1703423243] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"240.458955ms","start":"2026-02-27T01:25:28.292189Z","end":"2026-02-27T01:25:28.532648Z","steps":["trace[1703423243] 'process raft request' (duration: 240.333973ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:28.53283Z","caller":"traceutil/trace.go:171","msg":"trace[936772259] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"239.205016ms","start":"2026-02-27T01:25:28.293579Z","end":"2026-02-27T01:25:28.532784Z","steps":["trace[936772259] 'process raft request' (duration: 239.028693ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:30.768548Z","caller":"traceutil/trace.go:171","msg":"trace[2135423056] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"252.987573ms","start":"2026-02-27T01:25:30.515528Z","end":"2026-02-27T01:25:30.768515Z","steps":["trace[2135423056] 'read index received' (duration: 252.772319ms)","trace[2135423056] 'applied index is now lower than readState.Index' (duration: 214.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:30.768822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.05821ms","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-27T01:25:30.768862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.199791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T01:25:30.768892Z","caller":"traceutil/trace.go:171","msg":"trace[1363992363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"232.248922ms","start":"2026-02-27T01:25:30.536635Z","end":"2026-02-27T01:25:30.768884Z","steps":["trace[1363992363] 'agreement among raft nodes before linearized reading' (duration: 232.182509ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:30.768915Z","caller":"traceutil/trace.go:171","msg":"trace[1983186382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"233.157123ms","start":"2026-02-27T01:25:30.535719Z","end":"2026-02-27T01:25:30.768876Z","steps":["trace[1983186382] 'agreement among raft nodes before linearized reading' (duration: 233.009789ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:30.76883Z","caller":"traceutil/trace.go:171","msg":"trace[1043332905] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"261.679577ms","start":"2026-02-27T01:25:30.506927Z","end":"2026-02-27T01:25:30.768607Z","steps":["trace[1043332905] 'process raft request' (duration: 261.425132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:30.768987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.995826ms","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-27T01:25:30.769066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.45066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:30.769091Z","caller":"traceutil/trace.go:171","msg":"trace[1534859983] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:927; }","duration":"185.529072ms","start":"2026-02-27T01:25:30.583556Z","end":"2026-02-27T01:25:30.769085Z","steps":["trace[1534859983] 'agreement among raft nodes before linearized reading' (duration: 185.473621ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:30.769071Z","caller":"traceutil/trace.go:171","msg":"trace[355198589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"232.112829ms","start":"2026-02-27T01:25:30.536939Z","end":"2026-02-27T01:25:30.769052Z","steps":["trace[355198589] 'agreement among raft nodes before linearized reading' (duration: 231.859843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:30.768793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.244839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T01:25:30.769491Z","caller":"traceutil/trace.go:171","msg":"trace[1679666476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"253.992426ms","start":"2026-02-27T01:25:30.515471Z","end":"2026-02-27T01:25:30.769464Z","steps":["trace[1679666476] 'agreement among raft nodes before linearized reading' (duration: 253.154217ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:31.168043Z","caller":"traceutil/trace.go:171","msg":"trace[748784810] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"393.223396ms","start":"2026-02-27T01:25:30.774787Z","end":"2026-02-27T01:25:31.168011Z","steps":["trace[748784810] 'process raft request' (duration: 392.985501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:31.16819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:30.774758Z","time spent":"393.35853ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:31.231545Z","caller":"traceutil/trace.go:171","msg":"trace[1800131889] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"456.167219ms","start":"2026-02-27T01:25:30.775342Z","end":"2026-02-27T01:25:31.23151Z","steps":["trace[1800131889] 'process raft request' (duration: 455.974974ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:31.231831Z","caller":"traceutil/trace.go:171","msg":"trace[93891286] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"456.495775ms","start":"2026-02-27T01:25:30.775317Z","end":"2026-02-27T01:25:31.231813Z","steps":["trace[93891286] 'process raft request' (duration: 456.147698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:31.231876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:30.775317Z","time spent":"456.324452ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:31.231942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:30.775278Z","time spent":"456.595308ms","remote":"127.0.0.1:56230","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-27T01:25:31.231865Z","caller":"traceutil/trace.go:171","msg":"trace[281508854] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"456.412954ms","start":"2026-02-27T01:25:30.775426Z","end":"2026-02-27T01:25:31.231839Z","steps":["trace[281508854] 'process raft request' (duration: 456.272671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:31.232488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:30.775392Z","time spent":"457.068759ms","remote":"127.0.0.1:56230","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-27T01:25:35.359057Z","caller":"traceutil/trace.go:171","msg":"trace[812146167] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"114.002539ms","start":"2026-02-27T01:25:35.245028Z","end":"2026-02-27T01:25:35.35903Z","steps":["trace[812146167] 'read index received' (duration: 113.786685ms)","trace[812146167] 'applied index is now lower than readState.Index' (duration: 214.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:35.359143Z","caller":"traceutil/trace.go:171","msg":"trace[325681792] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"172.182204ms","start":"2026-02-27T01:25:35.186938Z","end":"2026-02-27T01:25:35.35912Z","steps":["trace[325681792] 'process raft request' (duration: 171.917628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:35.359369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.985556ms","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-27T01:25:35.359419Z","caller":"traceutil/trace.go:171","msg":"trace[771665426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"113.041338ms","start":"2026-02-27T01:25:35.246365Z","end":"2026-02-27T01:25:35.359406Z","steps":["trace[771665426] 'agreement among raft nodes before linearized reading' (duration: 112.907395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:35.359369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.320416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-27T01:25:35.359452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.092448ms","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-27T01:25:35.359469Z","caller":"traceutil/trace.go:171","msg":"trace[2002172449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"114.46978ms","start":"2026-02-27T01:25:35.244984Z","end":"2026-02-27T01:25:35.359453Z","steps":["trace[2002172449] 'agreement among raft nodes before linearized reading' (duration: 114.193863ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:35.359546Z","caller":"traceutil/trace.go:171","msg":"trace[1052135263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"113.240831ms","start":"2026-02-27T01:25:35.246284Z","end":"2026-02-27T01:25:35.359525Z","steps":["trace[1052135263] 'agreement among raft nodes before linearized reading' (duration: 112.968645ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:36.035033Z","caller":"traceutil/trace.go:171","msg":"trace[690203785] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"668.876074ms","start":"2026-02-27T01:25:35.366136Z","end":"2026-02-27T01:25:36.035012Z","steps":["trace[690203785] 'process raft request' (duration: 668.827173ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:36.035091Z","caller":"traceutil/trace.go:171","msg":"trace[1797490974] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"670.293446ms","start":"2026-02-27T01:25:35.364757Z","end":"2026-02-27T01:25:36.03505Z","steps":["trace[1797490974] 'process raft request' (duration: 670.02023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:36.035192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:35.36612Z","time spent":"668.994957ms","remote":"127.0.0.1:56230","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-27T01:25:36.035291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:35.364737Z","time spent":"670.44159ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:36.809575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227775818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:25:37.07276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:36.306606Z","time spent":"766.149332ms","remote":"127.0.0.1:55990","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T01:25:37.196576Z","caller":"traceutil/trace.go:171","msg":"trace[336655214] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"712.6611ms","start":"2026-02-27T01:25:36.48389Z","end":"2026-02-27T01:25:37.196551Z","steps":["trace[336655214] 'process raft request' (duration: 711.471203ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:37.196594Z","caller":"traceutil/trace.go:171","msg":"trace[92764230] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"887.31631ms","start":"2026-02-27T01:25:36.309256Z","end":"2026-02-27T01:25:37.196573Z","steps":["trace[92764230] 'read index received' (duration: 763.398241ms)","trace[92764230] 'applied index is now lower than readState.Index' (duration: 123.917119ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:37.197665Z","caller":"traceutil/trace.go:171","msg":"trace[2084897797] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"123.711825ms","start":"2026-02-27T01:25:37.073909Z","end":"2026-02-27T01:25:37.197621Z","steps":["trace[2084897797] 'process raft request' (duration: 122.614411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:37.198013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:36.483869Z","time spent":"713.788415ms","remote":"127.0.0.1:56230","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-27T01:25:37.199018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.391126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:37.199102Z","caller":"traceutil/trace.go:171","msg":"trace[430177106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"391.493039ms","start":"2026-02-27T01:25:36.807572Z","end":"2026-02-27T01:25:37.199065Z","steps":["trace[430177106] 'agreement among raft nodes before linearized reading' (duration: 391.386476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:37.199126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.797945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T01:25:37.199158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:36.807551Z","time spent":"391.59487ms","remote":"127.0.0.1:55940","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-27T01:25:37.199185Z","caller":"traceutil/trace.go:171","msg":"trace[1541887594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"889.962359ms","start":"2026-02-27T01:25:36.309207Z","end":"2026-02-27T01:25:37.199169Z","steps":["trace[1541887594] 'agreement among raft nodes before linearized reading' (duration: 887.471863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:37.199241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:36.30919Z","time spent":"890.03945ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:25:37.199235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.351382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:37.199372Z","caller":"traceutil/trace.go:171","msg":"trace[45524499] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:963; }","duration":"672.601658ms","start":"2026-02-27T01:25:36.526709Z","end":"2026-02-27T01:25:37.199311Z","steps":["trace[45524499] 'agreement among raft nodes before linearized reading' (duration: 670.998782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:37.199419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:36.526688Z","time spent":"672.718411ms","remote":"127.0.0.1:56128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-27T01:25:37.617298Z","caller":"traceutil/trace.go:171","msg":"trace[1416246615] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"105.458778ms","start":"2026-02-27T01:25:37.511812Z","end":"2026-02-27T01:25:37.617271Z","steps":["trace[1416246615] 'process raft request' (duration: 99.792721ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:38.159024Z","caller":"traceutil/trace.go:171","msg":"trace[1522401275] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"115.103414ms","start":"2026-02-27T01:25:38.043897Z","end":"2026-02-27T01:25:38.159001Z","steps":["trace[1522401275] 'process raft request' (duration: 86.545587ms)","trace[1522401275] 'compare' (duration: 28.465045ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:39.891574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.480634ms","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-27T01:25:39.891901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.646779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T01:25:39.891901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425817612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:39.891998Z","caller":"traceutil/trace.go:171","msg":"trace[1581269617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"663.750971ms","start":"2026-02-27T01:25:39.228211Z","end":"2026-02-27T01:25:39.891962Z","steps":["trace[1581269617] 'range keys from in-memory index tree' (duration: 663.496456ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:39.892008Z","caller":"traceutil/trace.go:171","msg":"trace[53453697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"376.921354ms","start":"2026-02-27T01:25:39.515011Z","end":"2026-02-27T01:25:39.891932Z","steps":["trace[53453697] 'range keys from in-memory index tree' (duration: 376.232588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:39.892067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.228191Z","time spent":"663.863883ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:25:39.892157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.514991Z","time spent":"377.149778ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T01:25:39.892269Z","caller":"traceutil/trace.go:171","msg":"trace[2146975125] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:975; }","duration":"1.425960836s","start":"2026-02-27T01:25:38.466018Z","end":"2026-02-27T01:25:39.891979Z","steps":["trace[2146975125] 'count revisions from in-memory index tree' (duration: 1.425700999s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:39.891716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.386806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T01:25:39.892401Z","caller":"traceutil/trace.go:171","msg":"trace[211144275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"520.129782ms","start":"2026-02-27T01:25:39.372257Z","end":"2026-02-27T01:25:39.892387Z","steps":["trace[211144275] 'range keys from in-memory index tree' (duration: 519.078629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:39.892469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.372199Z","time spent":"520.256765ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T01:25:39.89164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082763071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:39.892607Z","caller":"traceutil/trace.go:171","msg":"trace[246853545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"1.083763453s","start":"2026-02-27T01:25:38.808832Z","end":"2026-02-27T01:25:39.892596Z","steps":["trace[246853545] 'range keys from in-memory index tree' (duration: 1.082596888s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:39.89277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:38.808771Z","time spent":"1.08398684s","remote":"127.0.0.1:55954","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-27T01:25:39.892426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:38.465994Z","time spent":"1.426341953s","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-27T01:25:39.89174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.280747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:39.89362Z","caller":"traceutil/trace.go:171","msg":"trace[451167266] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:975; }","duration":"232.18483ms","start":"2026-02-27T01:25:39.661381Z","end":"2026-02-27T01:25:39.893566Z","steps":["trace[451167266] 'count revisions from in-memory index tree' (duration: 230.176895ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:40.310462Z","caller":"traceutil/trace.go:171","msg":"trace[1682193591] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"413.242873ms","start":"2026-02-27T01:25:39.897193Z","end":"2026-02-27T01:25:40.310436Z","steps":["trace[1682193591] 'process raft request' (duration: 412.974106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:40.310676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.897177Z","time spent":"413.402336ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:41.098058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.196639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:41.098315Z","caller":"traceutil/trace.go:171","msg":"trace[1035940832] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1014; }","duration":"1.197087229s","start":"2026-02-27T01:25:39.901204Z","end":"2026-02-27T01:25:41.098292Z","steps":["trace[1035940832] 'read index received' (duration: 408.975497ms)","trace[1035940832] 'applied index is now lower than readState.Index' (duration: 788.110582ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:41.098423Z","caller":"traceutil/trace.go:171","msg":"trace[1558265907] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.199933782s","start":"2026-02-27T01:25:39.898473Z","end":"2026-02-27T01:25:41.098407Z","steps":["trace[1558265907] 'process raft request' (duration: 1.199708947s)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.098402Z","caller":"traceutil/trace.go:171","msg":"trace[1858538348] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.201082207s","start":"2026-02-27T01:25:39.897286Z","end":"2026-02-27T01:25:41.098368Z","steps":["trace[1858538348] 'process raft request' (duration: 586.46172ms)","trace[1858538348] 'compare' (duration: 613.999604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:41.098523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.89846Z","time spent":"1.200012294s","remote":"127.0.0.1:56230","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-27T01:25:41.098538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197282693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:41.098623Z","caller":"traceutil/trace.go:171","msg":"trace[466733305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"1.197431687s","start":"2026-02-27T01:25:39.901172Z","end":"2026-02-27T01:25:41.098604Z","steps":["trace[466733305] 'agreement among raft nodes before linearized reading' (duration: 1.197265573s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.098622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.897263Z","time spent":"1.201292202s","remote":"127.0.0.1:56230","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-27T01:25:41.098919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:39.901163Z","time spent":"1.197488398s","remote":"127.0.0.1:55954","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-27T01:25:41.471963Z","caller":"traceutil/trace.go:171","msg":"trace[776032323] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"373.565019ms","start":"2026-02-27T01:25:41.098369Z","end":"2026-02-27T01:25:41.471934Z","steps":["trace[776032323] 'read index received' (duration: 318.636697ms)","trace[776032323] 'applied index is now lower than readState.Index' (duration: 54.926952ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:41.47203Z","caller":"traceutil/trace.go:171","msg":"trace[2038447897] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"997.982494ms","start":"2026-02-27T01:25:40.47402Z","end":"2026-02-27T01:25:41.472002Z","steps":["trace[2038447897] 'process raft request' (duration: 942.623871ms)","trace[2038447897] 'compare' (duration: 55.064816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:41.472159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.473985Z","time spent":"998.106377ms","remote":"127.0.0.1:56126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:41.472192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309263016s","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-27T01:25:41.472364Z","caller":"traceutil/trace.go:171","msg":"trace[33054557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.309502961s","start":"2026-02-27T01:25:40.162847Z","end":"2026-02-27T01:25:41.47235Z","steps":["trace[33054557] 'agreement among raft nodes before linearized reading' (duration: 1.309216545s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.472411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.162822Z","time spent":"1.309578052s","remote":"127.0.0.1:56230","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-27T01:25:41.472205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408283001s","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-27T01:25:41.472519Z","caller":"traceutil/trace.go:171","msg":"trace[840229090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.408620898s","start":"2026-02-27T01:25:40.063858Z","end":"2026-02-27T01:25:41.472478Z","steps":["trace[840229090] 'agreement among raft nodes before linearized reading' (duration: 1.40825817s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.472612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:40.063833Z","time spent":"1.408749971s","remote":"127.0.0.1:56230","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-27T01:25:41.667437Z","caller":"traceutil/trace.go:171","msg":"trace[1840054433] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"542.88804ms","start":"2026-02-27T01:25:41.124517Z","end":"2026-02-27T01:25:41.667405Z","steps":["trace[1840054433] 'process raft request' (duration: 490.960814ms)","trace[1840054433] 'compare' (duration: 51.724702ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:41.667491Z","caller":"traceutil/trace.go:171","msg":"trace[2108927371] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"189.070061ms","start":"2026-02-27T01:25:41.478393Z","end":"2026-02-27T01:25:41.667463Z","steps":["trace[2108927371] 'process raft request' (duration: 188.954018ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:41.667528Z","caller":"traceutil/trace.go:171","msg":"trace[155473261] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"189.118211ms","start":"2026-02-27T01:25:41.478389Z","end":"2026-02-27T01:25:41.667507Z","steps":["trace[155473261] 'process raft request' (duration: 189.023089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.667573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:41.124497Z","time spent":"543.001862ms","remote":"127.0.0.1:56230","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-27T01:25:41.667677Z","caller":"traceutil/trace.go:171","msg":"trace[1897942264] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"195.638707ms","start":"2026-02-27T01:25:41.472029Z","end":"2026-02-27T01:25:41.667668Z","steps":["trace[1897942264] 'read index received' (duration: 143.418884ms)","trace[1897942264] 'applied index is now lower than readState.Index' (duration: 52.218473ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:41.667747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.607991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:41.667771Z","caller":"traceutil/trace.go:171","msg":"trace[28810945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"567.686572ms","start":"2026-02-27T01:25:41.100078Z","end":"2026-02-27T01:25:41.667764Z","steps":["trace[28810945] 'agreement among raft nodes before linearized reading' (duration: 567.615421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:41.667795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:41.100061Z","time spent":"567.728503ms","remote":"127.0.0.1:55940","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-27T01:25:41.842296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.839098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-27T01:25:43.168811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227775869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:25:43.610908Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292390631s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:25:43.611385Z","caller":"traceutil/trace.go:171","msg":"trace[1344615723] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"942.591231ms","start":"2026-02-27T01:25:42.668769Z","end":"2026-02-27T01:25:43.61136Z","steps":["trace[1344615723] 'read index received' (duration: 942.331955ms)","trace[1344615723] 'applied index is now lower than readState.Index' (duration: 258.276µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:43.611446Z","caller":"traceutil/trace.go:171","msg":"trace[1616456556] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.293019565s","start":"2026-02-27T01:25:42.318402Z","end":"2026-02-27T01:25:43.611421Z","steps":["trace[1616456556] 'process raft request' (duration: 1.29278686s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:43.611573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.057824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:25:43.611607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.828887ms","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-27T01:25:43.611645Z","caller":"traceutil/trace.go:171","msg":"trace[1202603841] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:983; }","duration":"942.907758ms","start":"2026-02-27T01:25:42.668728Z","end":"2026-02-27T01:25:43.611635Z","steps":["trace[1202603841] 'agreement among raft nodes before linearized reading' (duration: 942.766785ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:43.61165Z","caller":"traceutil/trace.go:171","msg":"trace[1344976784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"803.144176ms","start":"2026-02-27T01:25:42.808457Z","end":"2026-02-27T01:25:43.611601Z","steps":["trace[1344976784] 'agreement among raft nodes before linearized reading' (duration: 803.067725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:43.611683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:42.80844Z","time spent":"803.237048ms","remote":"127.0.0.1:55954","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-27T01:25:43.611687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:42.66871Z","time spent":"942.96745ms","remote":"127.0.0.1:56230","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":"warn","ts":"2026-02-27T01:25:43.611735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:42.318384Z","time spent":"1.293201999s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:43.611809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.76387ms","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-27T01:25:43.611889Z","caller":"traceutil/trace.go:171","msg":"trace[1575467496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"508.915574ms","start":"2026-02-27T01:25:43.102954Z","end":"2026-02-27T01:25:43.611869Z","steps":["trace[1575467496] 'agreement among raft nodes before linearized reading' (duration: 508.663229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:43.611939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.102913Z","time spent":"509.017165ms","remote":"127.0.0.1:56230","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-27T01:25:43.612093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.990986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:25:43.612174Z","caller":"traceutil/trace.go:171","msg":"trace[1186721392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"509.123789ms","start":"2026-02-27T01:25:43.103037Z","end":"2026-02-27T01:25:43.612161Z","steps":["trace[1186721392] 'agreement among raft nodes before linearized reading' (duration: 508.565026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:43.612211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.103009Z","time spent":"509.192249ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T01:25:43.907585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.9385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:43.907697Z","caller":"traceutil/trace.go:171","msg":"trace[2033482812] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"290.130522ms","start":"2026-02-27T01:25:43.617548Z","end":"2026-02-27T01:25:43.907679Z","steps":["trace[2033482812] 'process raft request' (duration: 127.010059ms)","trace[2033482812] 'compare' (duration: 162.798106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:44.146154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.661036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:44.146302Z","caller":"traceutil/trace.go:171","msg":"trace[2066589351] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1023; }","duration":"474.408235ms","start":"2026-02-27T01:25:43.671879Z","end":"2026-02-27T01:25:44.146287Z","steps":["trace[2066589351] 'read index received' (duration: 72.684209ms)","trace[2066589351] 'applied index is now lower than readState.Index' (duration: 401.723116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:44.146616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.721522ms","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-27T01:25:44.146661Z","caller":"traceutil/trace.go:171","msg":"trace[613598353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"474.785403ms","start":"2026-02-27T01:25:43.671861Z","end":"2026-02-27T01:25:44.146646Z","steps":["trace[613598353] 'agreement among raft nodes before linearized reading' (duration: 474.63925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:44.146694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.671844Z","time spent":"474.842335ms","remote":"127.0.0.1:56230","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-27T01:25:44.146786Z","caller":"traceutil/trace.go:171","msg":"trace[2134634649] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"528.607502ms","start":"2026-02-27T01:25:43.618024Z","end":"2026-02-27T01:25:44.146631Z","steps":["trace[2134634649] 'process raft request' (duration: 394.391103ms)","trace[2134634649] 'compare' (duration: 133.553834ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:44.147015Z","caller":"traceutil/trace.go:171","msg":"trace[1398780416] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"528.906639ms","start":"2026-02-27T01:25:43.618098Z","end":"2026-02-27T01:25:44.147004Z","steps":["trace[1398780416] 'process raft request' (duration: 528.140922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:44.147089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.149821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-27T01:25:44.1472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.618089Z","time spent":"529.060942ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:44.147241Z","caller":"traceutil/trace.go:171","msg":"trace[2076469251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"475.350436ms","start":"2026-02-27T01:25:43.67184Z","end":"2026-02-27T01:25:44.14719Z","steps":["trace[2076469251] 'agreement among raft nodes before linearized reading' (duration: 474.62934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:44.147332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.671819Z","time spent":"475.489598ms","remote":"127.0.0.1:56230","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-27T01:25:44.147427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:43.618009Z","time spent":"528.948ms","remote":"127.0.0.1:56230","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-27T01:25:44.787506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.248797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:44.787752Z","caller":"traceutil/trace.go:171","msg":"trace[258541667] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1026; }","duration":"229.241515ms","start":"2026-02-27T01:25:44.558483Z","end":"2026-02-27T01:25:44.787725Z","steps":["trace[258541667] 'read index received' (duration: 54.201µs)","trace[258541667] 'applied index is now lower than readState.Index' (duration: 229.185914ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:44.787911Z","caller":"traceutil/trace.go:171","msg":"trace[1657970371] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"636.538115ms","start":"2026-02-27T01:25:44.151351Z","end":"2026-02-27T01:25:44.787889Z","steps":["trace[1657970371] 'process raft request' (duration: 101.806427ms)","trace[1657970371] 'compare' (duration: 534.045333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:44.788043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:44.151327Z","time spent":"636.644647ms","remote":"127.0.0.1:56230","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-27T01:25:44.788192Z","caller":"traceutil/trace.go:171","msg":"trace[1097586371] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"635.947662ms","start":"2026-02-27T01:25:44.152196Z","end":"2026-02-27T01:25:44.788137Z","steps":["trace[1097586371] 'process raft request' (duration: 635.40635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:44.788204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.670195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:44.78837Z","caller":"traceutil/trace.go:171","msg":"trace[1076852433] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:988; }","duration":"229.89171ms","start":"2026-02-27T01:25:44.558446Z","end":"2026-02-27T01:25:44.788337Z","steps":["trace[1076852433] 'agreement among raft nodes before linearized reading' (duration: 229.44943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:44.788467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:44.152169Z","time spent":"636.111056ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:25:46.308447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.789091ms","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-27T01:25:46.308583Z","caller":"traceutil/trace.go:171","msg":"trace[1989930308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"156.971715ms","start":"2026-02-27T01:25:46.151592Z","end":"2026-02-27T01:25:46.308564Z","steps":["trace[1989930308] 'range keys from in-memory index tree' (duration: 156.589717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:46.308507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.098234ms","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-27T01:25:46.308714Z","caller":"traceutil/trace.go:171","msg":"trace[1409669467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"397.372239ms","start":"2026-02-27T01:25:45.911324Z","end":"2026-02-27T01:25:46.308696Z","steps":["trace[1409669467] 'range keys from in-memory index tree' (duration: 396.917879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:46.308807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:45.911288Z","time spent":"397.505793ms","remote":"127.0.0.1:56230","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-27T01:25:46.476672Z","caller":"traceutil/trace.go:171","msg":"trace[191697719] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"164.35687ms","start":"2026-02-27T01:25:46.31229Z","end":"2026-02-27T01:25:46.476647Z","steps":["trace[191697719] 'process raft request' (duration: 146.213286ms)","trace[191697719] 'compare' (duration: 17.95635ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:46.476802Z","caller":"traceutil/trace.go:171","msg":"trace[1423235643] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"163.137284ms","start":"2026-02-27T01:25:46.313642Z","end":"2026-02-27T01:25:46.476779Z","steps":["trace[1423235643] 'process raft request' (duration: 162.96133ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:46.518542Z","caller":"traceutil/trace.go:171","msg":"trace[992035346] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"132.103263ms","start":"2026-02-27T01:25:46.386417Z","end":"2026-02-27T01:25:46.51852Z","steps":["trace[992035346] 'process raft request' (duration: 131.941059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:47.026164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.196286ms","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-27T01:25:47.026237Z","caller":"traceutil/trace.go:171","msg":"trace[1984115842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"234.321149ms","start":"2026-02-27T01:25:46.791898Z","end":"2026-02-27T01:25:47.026219Z","steps":["trace[1984115842] 'range keys from in-memory index tree' (duration: 234.010491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:47.02624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.446785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:25:47.026343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.047739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T01:25:47.026578Z","caller":"traceutil/trace.go:171","msg":"trace[2029522778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"233.322355ms","start":"2026-02-27T01:25:46.793241Z","end":"2026-02-27T01:25:47.026563Z","steps":["trace[2029522778] 'range keys from in-memory index tree' (duration: 232.919767ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:47.026311Z","caller":"traceutil/trace.go:171","msg":"trace[228490403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"218.549577ms","start":"2026-02-27T01:25:46.807742Z","end":"2026-02-27T01:25:47.026291Z","steps":["trace[228490403] 'range keys from in-memory index tree' (duration: 218.371793ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:47.185853Z","caller":"traceutil/trace.go:171","msg":"trace[1907956936] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"155.047733ms","start":"2026-02-27T01:25:47.030772Z","end":"2026-02-27T01:25:47.18582Z","steps":["trace[1907956936] 'process raft request' (duration: 154.840489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:47.432975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.916161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:25:47.433158Z","caller":"traceutil/trace.go:171","msg":"trace[1013621808] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"402.186915ms","start":"2026-02-27T01:25:47.030949Z","end":"2026-02-27T01:25:47.433136Z","steps":["trace[1013621808] 'process raft request' (duration: 206.984218ms)","trace[1013621808] 'compare' (duration: 194.701456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:47.433235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:47.030939Z","time spent":"402.263307ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:47.433399Z","caller":"traceutil/trace.go:171","msg":"trace[405613636] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"193.011068ms","start":"2026-02-27T01:25:47.24036Z","end":"2026-02-27T01:25:47.433372Z","steps":["trace[405613636] 'read index received' (duration: 108.736122ms)","trace[405613636] 'applied index is now lower than readState.Index' (duration: 84.273766ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:47.433443Z","caller":"traceutil/trace.go:171","msg":"trace[336378449] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"224.510629ms","start":"2026-02-27T01:25:47.208904Z","end":"2026-02-27T01:25:47.433415Z","steps":["trace[336378449] 'process raft request' (duration: 224.183312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:47.43358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.975679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:47.433622Z","caller":"traceutil/trace.go:171","msg":"trace[1362808568] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:995; }","duration":"132.082091ms","start":"2026-02-27T01:25:47.301529Z","end":"2026-02-27T01:25:47.433611Z","steps":["trace[1362808568] 'agreement among raft nodes before linearized reading' (duration: 131.986119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:47.4336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.197702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T01:25:47.433757Z","caller":"traceutil/trace.go:171","msg":"trace[572947394] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:995; }","duration":"193.396456ms","start":"2026-02-27T01:25:47.240308Z","end":"2026-02-27T01:25:47.433705Z","steps":["trace[572947394] 'agreement among raft nodes before linearized reading' (duration: 193.173112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:48.425388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.722436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:25:48.425388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.415369ms","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-27T01:25:48.425499Z","caller":"traceutil/trace.go:171","msg":"trace[838402429] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:996; }","duration":"388.541242ms","start":"2026-02-27T01:25:48.036945Z","end":"2026-02-27T01:25:48.425486Z","steps":["trace[838402429] 'range keys from in-memory index tree' (duration: 388.403309ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:48.425459Z","caller":"traceutil/trace.go:171","msg":"trace[676020085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"617.804758ms","start":"2026-02-27T01:25:47.807637Z","end":"2026-02-27T01:25:48.425442Z","steps":["trace[676020085] 'range keys from in-memory index tree' (duration: 617.531472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:48.42571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:47.807562Z","time spent":"618.130275ms","remote":"127.0.0.1:55954","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-27T01:25:48.766949Z","caller":"traceutil/trace.go:171","msg":"trace[1962981813] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"283.132376ms","start":"2026-02-27T01:25:48.483794Z","end":"2026-02-27T01:25:48.766927Z","steps":["trace[1962981813] 'process raft request' (duration: 282.992683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.309364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227775912,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T01:25:49.545037Z","caller":"traceutil/trace.go:171","msg":"trace[13458843] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.061192742s","start":"2026-02-27T01:25:48.483821Z","end":"2026-02-27T01:25:49.545013Z","steps":["trace[13458843] 'process raft request' (duration: 1.061009258s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.545284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:48.483804Z","time spent":"1.061395056s","remote":"127.0.0.1:56230","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-27T01:25:49.545548Z","caller":"traceutil/trace.go:171","msg":"trace[1053131475] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1039; }","duration":"736.677655ms","start":"2026-02-27T01:25:48.808852Z","end":"2026-02-27T01:25:49.54553Z","steps":["trace[1053131475] 'read index received' (duration: 736.666884ms)","trace[1053131475] 'applied index is now lower than readState.Index' (duration: 9.011µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:49.545739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.862029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:25:49.545827Z","caller":"traceutil/trace.go:171","msg":"trace[1792703886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"736.966062ms","start":"2026-02-27T01:25:48.808838Z","end":"2026-02-27T01:25:49.545804Z","steps":["trace[1792703886] 'agreement among raft nodes before linearized reading' (duration: 736.798808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.545878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:48.808785Z","time spent":"737.084614ms","remote":"127.0.0.1:55954","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-27T01:25:49.546198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.357611ms","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-27T01:25:49.546298Z","caller":"traceutil/trace.go:171","msg":"trace[1193178615] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:998; }","duration":"394.484044ms","start":"2026-02-27T01:25:49.151756Z","end":"2026-02-27T01:25:49.54624Z","steps":["trace[1193178615] 'agreement among raft nodes before linearized reading' (duration: 394.28317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.546415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.151723Z","time spent":"394.678779ms","remote":"127.0.0.1:56230","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":"warn","ts":"2026-02-27T01:25:49.546458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.766192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T01:25:49.546492Z","caller":"traceutil/trace.go:171","msg":"trace[669419387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"108.835984ms","start":"2026-02-27T01:25:49.437646Z","end":"2026-02-27T01:25:49.546482Z","steps":["trace[669419387] 'agreement among raft nodes before linearized reading' (duration: 108.667919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.546618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.866245ms","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-27T01:25:49.546728Z","caller":"traceutil/trace.go:171","msg":"trace[1987808317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"355.987098ms","start":"2026-02-27T01:25:49.190722Z","end":"2026-02-27T01:25:49.546709Z","steps":["trace[1987808317] 'agreement among raft nodes before linearized reading' (duration: 355.649969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:49.546812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.190654Z","time spent":"356.143411ms","remote":"127.0.0.1:56230","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-27T01:25:50.270301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255408227775917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T01:25:50.650307Z","caller":"traceutil/trace.go:171","msg":"trace[613205720] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"880.245643ms","start":"2026-02-27T01:25:49.770032Z","end":"2026-02-27T01:25:50.650277Z","steps":["trace[613205720] 'read index received' (duration: 827.530989ms)","trace[613205720] 'applied index is now lower than readState.Index' (duration: 52.713214ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:50.650468Z","caller":"traceutil/trace.go:171","msg":"trace[999027802] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.09837481s","start":"2026-02-27T01:25:49.552078Z","end":"2026-02-27T01:25:50.650453Z","steps":["trace[999027802] 'process raft request' (duration: 1.098070743s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:50.650813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.773725ms","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-27T01:25:50.650868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.262168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T01:25:50.650895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.552062Z","time spent":"1.098436121s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:50.650951Z","caller":"traceutil/trace.go:171","msg":"trace[2074028563] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1001; }","duration":"851.656896ms","start":"2026-02-27T01:25:49.799275Z","end":"2026-02-27T01:25:50.650932Z","steps":["trace[2074028563] 'agreement among raft nodes before linearized reading' (duration: 851.238267ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:50.65097Z","caller":"traceutil/trace.go:171","msg":"trace[2083811053] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.099642598s","start":"2026-02-27T01:25:49.550906Z","end":"2026-02-27T01:25:50.650548Z","steps":["trace[2083811053] 'process raft request' (duration: 1.046705769s)","trace[2083811053] 'compare' (duration: 51.837714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:25:50.650998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.799263Z","time spent":"851.724178ms","remote":"127.0.0.1:56252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-27T01:25:50.65113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.55089Z","time spent":"1.10012938s","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:25:50.651436Z","caller":"traceutil/trace.go:171","msg":"trace[1494573515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"880.937859ms","start":"2026-02-27T01:25:49.769942Z","end":"2026-02-27T01:25:50.65088Z","steps":["trace[1494573515] 'agreement among raft nodes before linearized reading' (duration: 880.728814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:50.651487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:25:49.769906Z","time spent":"881.567183ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T01:25:52.878509Z","caller":"traceutil/trace.go:171","msg":"trace[1128444825] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"218.765072ms","start":"2026-02-27T01:25:52.659712Z","end":"2026-02-27T01:25:52.878477Z","steps":["trace[1128444825] 'process raft request' (duration: 217.948524ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:25:52.879371Z","caller":"traceutil/trace.go:171","msg":"trace[938229898] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"203.92566ms","start":"2026-02-27T01:25:52.675418Z","end":"2026-02-27T01:25:52.879343Z","steps":["trace[938229898] 'read index received' (duration: 202.121831ms)","trace[938229898] 'applied index is now lower than readState.Index' (duration: 1.802849ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:25:52.879576Z","caller":"traceutil/trace.go:171","msg":"trace[2041087946] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"218.180339ms","start":"2026-02-27T01:25:52.661385Z","end":"2026-02-27T01:25:52.879565Z","steps":["trace[2041087946] 'process raft request' (duration: 217.799381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:52.879843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.411732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T01:25:52.879871Z","caller":"traceutil/trace.go:171","msg":"trace[2065169539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"204.508504ms","start":"2026-02-27T01:25:52.675353Z","end":"2026-02-27T01:25:52.879861Z","steps":["trace[2065169539] 'agreement among raft nodes before linearized reading' (duration: 204.36777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:25:52.88004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.316952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T01:25:52.880075Z","caller":"traceutil/trace.go:171","msg":"trace[2122162246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"100.400254ms","start":"2026-02-27T01:25:52.779664Z","end":"2026-02-27T01:25:52.880064Z","steps":["trace[2122162246] 'agreement among raft nodes before linearized reading' (duration: 100.309082ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:05.611484Z","caller":"traceutil/trace.go:171","msg":"trace[575625258] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"106.220105ms","start":"2026-02-27T01:26:05.505232Z","end":"2026-02-27T01:26:05.611452Z","steps":["trace[575625258] 'process raft request' (duration: 106.035101ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:05.968999Z","caller":"traceutil/trace.go:171","msg":"trace[1686975800] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"160.281894ms","start":"2026-02-27T01:26:05.808693Z","end":"2026-02-27T01:26:05.968975Z","steps":["trace[1686975800] 'read index received' (duration: 157.540363ms)","trace[1686975800] 'applied index is now lower than readState.Index' (duration: 2.740971ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:05.969035Z","caller":"traceutil/trace.go:171","msg":"trace[1216276322] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"273.944147ms","start":"2026-02-27T01:26:05.69507Z","end":"2026-02-27T01:26:05.969014Z","steps":["trace[1216276322] 'process raft request' (duration: 271.208656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:05.969126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.423147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:26:05.969335Z","caller":"traceutil/trace.go:171","msg":"trace[536724641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"160.660872ms","start":"2026-02-27T01:26:05.80865Z","end":"2026-02-27T01:26:05.969311Z","steps":["trace[536724641] 'agreement among raft nodes before linearized reading' (duration: 160.393636ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:09.811969Z","caller":"traceutil/trace.go:171","msg":"trace[1554835924] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"118.067352ms","start":"2026-02-27T01:26:09.693886Z","end":"2026-02-27T01:26:09.811954Z","steps":["trace[1554835924] 'read index received' (duration: 117.97647ms)","trace[1554835924] 'applied index is now lower than readState.Index' (duration: 90.372µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:09.812029Z","caller":"traceutil/trace.go:171","msg":"trace[1087117623] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"185.167791ms","start":"2026-02-27T01:26:09.626837Z","end":"2026-02-27T01:26:09.812005Z","steps":["trace[1087117623] 'process raft request' (duration: 185.006567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:09.812224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.330737ms","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-27T01:26:09.812272Z","caller":"traceutil/trace.go:171","msg":"trace[1168874839] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1105; }","duration":"118.424369ms","start":"2026-02-27T01:26:09.693839Z","end":"2026-02-27T01:26:09.812264Z","steps":["trace[1168874839] 'agreement among raft nodes before linearized reading' (duration: 118.177504ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:29.297542Z","caller":"traceutil/trace.go:171","msg":"trace[165646042] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1232; }","duration":"241.126152ms","start":"2026-02-27T01:26:29.056388Z","end":"2026-02-27T01:26:29.297514Z","steps":["trace[165646042] 'read index received' (duration: 75.353315ms)","trace[165646042] 'applied index is now lower than readState.Index' (duration: 165.771257ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:26:29.297567Z","caller":"traceutil/trace.go:171","msg":"trace[1209138719] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"241.592792ms","start":"2026-02-27T01:26:29.055942Z","end":"2026-02-27T01:26:29.297535Z","steps":["trace[1209138719] 'process raft request' (duration: 235.173728ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:29.29775Z","caller":"traceutil/trace.go:171","msg":"trace[295579414] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"241.217574ms","start":"2026-02-27T01:26:29.056504Z","end":"2026-02-27T01:26:29.297722Z","steps":["trace[295579414] 'process raft request' (duration: 240.949068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:29.29779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.370838ms","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-27T01:26:29.297842Z","caller":"traceutil/trace.go:171","msg":"trace[1857785064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1186; }","duration":"241.465039ms","start":"2026-02-27T01:26:29.056363Z","end":"2026-02-27T01:26:29.297828Z","steps":["trace[1857785064] 'agreement among raft nodes before linearized reading' (duration: 241.273185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:26:36.005583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.412685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:26:36.005689Z","caller":"traceutil/trace.go:171","msg":"trace[558480567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"197.537688ms","start":"2026-02-27T01:26:35.808127Z","end":"2026-02-27T01:26:36.005665Z","steps":["trace[558480567] 'range keys from in-memory index tree' (duration: 197.21788ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:52.4136Z","caller":"traceutil/trace.go:171","msg":"trace[1380644641] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"144.809999ms","start":"2026-02-27T01:26:52.268762Z","end":"2026-02-27T01:26:52.413572Z","steps":["trace[1380644641] 'process raft request' (duration: 144.667656ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:26:55.615737Z","caller":"traceutil/trace.go:171","msg":"trace[1160015911] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"101.114748ms","start":"2026-02-27T01:26:55.514591Z","end":"2026-02-27T01:26:55.615705Z","steps":["trace[1160015911] 'process raft request' (duration: 60.224889ms)","trace[1160015911] 'compare' (duration: 40.665774ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:23.896075Z","caller":"traceutil/trace.go:171","msg":"trace[1597897832] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"110.461885ms","start":"2026-02-27T01:27:23.785587Z","end":"2026-02-27T01:27:23.896049Z","steps":["trace[1597897832] 'read index received' (duration: 110.304331ms)","trace[1597897832] 'applied index is now lower than readState.Index' (duration: 156.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:23.8962Z","caller":"traceutil/trace.go:171","msg":"trace[1193406787] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"114.037615ms","start":"2026-02-27T01:27:23.782141Z","end":"2026-02-27T01:27:23.896179Z","steps":["trace[1193406787] 'process raft request' (duration: 113.777239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:23.896553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.728699ms","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-27T01:27:23.896643Z","caller":"traceutil/trace.go:171","msg":"trace[517722671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1406; }","duration":"109.832981ms","start":"2026-02-27T01:27:23.786788Z","end":"2026-02-27T01:27:23.896621Z","steps":["trace[517722671] 'agreement among raft nodes before linearized reading' (duration: 109.481264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:23.89694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.279284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T01:27:23.897013Z","caller":"traceutil/trace.go:171","msg":"trace[985214825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1406; }","duration":"111.485059ms","start":"2026-02-27T01:27:23.785516Z","end":"2026-02-27T01:27:23.897001Z","steps":["trace[985214825] 'agreement among raft nodes before linearized reading' (duration: 110.68446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:27:24.120407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.892115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:27:24.120532Z","caller":"traceutil/trace.go:171","msg":"trace[989876743] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"136.35259ms","start":"2026-02-27T01:27:23.984161Z","end":"2026-02-27T01:27:24.120514Z","steps":["trace[989876743] 'read index received' (duration: 33.99µs)","trace[989876743] 'applied index is now lower than readState.Index' (duration: 136.31738ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:27:24.120638Z","caller":"traceutil/trace.go:171","msg":"trace[79686616] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"218.526168ms","start":"2026-02-27T01:27:23.902062Z","end":"2026-02-27T01:27:24.120588Z","steps":["trace[79686616] 'process raft request' (duration: 80.364347ms)","trace[79686616] 'compare' (duration: 137.756292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:27:24.120721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.544875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-27T01:27:24.120755Z","caller":"traceutil/trace.go:171","msg":"trace[825093138] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1408; }","duration":"136.640297ms","start":"2026-02-27T01:27:23.984101Z","end":"2026-02-27T01:27:24.120741Z","steps":["trace[825093138] 'agreement among raft nodes before linearized reading' (duration: 136.461193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:28:07.987238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.737328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:28:07.987329Z","caller":"traceutil/trace.go:171","msg":"trace[766299603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1586; }","duration":"179.873831ms","start":"2026-02-27T01:28:07.807436Z","end":"2026-02-27T01:28:07.98731Z","steps":["trace[766299603] 'range keys from in-memory index tree' (duration: 179.644186ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:28:54.630653Z","caller":"traceutil/trace.go:171","msg":"trace[545762800] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"180.115018ms","start":"2026-02-27T01:28:54.450514Z","end":"2026-02-27T01:28:54.630629Z","steps":["trace[545762800] 'read index received' (duration: 179.940274ms)","trace[545762800] 'applied index is now lower than readState.Index' (duration: 173.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:28:54.630758Z","caller":"traceutil/trace.go:171","msg":"trace[1428125383] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"233.138166ms","start":"2026-02-27T01:28:54.397587Z","end":"2026-02-27T01:28:54.630725Z","steps":["trace[1428125383] 'process raft request' (duration: 232.89655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:28:54.63088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.340872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-27T01:28:54.630917Z","caller":"traceutil/trace.go:171","msg":"trace[796980204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1805; }","duration":"180.420245ms","start":"2026-02-27T01:28:54.450486Z","end":"2026-02-27T01:28:54.630906Z","steps":["trace[796980204] 'agreement among raft nodes before linearized reading' (duration: 180.277401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:28:54.630987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.208281ms","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-27T01:28:54.63104Z","caller":"traceutil/trace.go:171","msg":"trace[140696984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1805; }","duration":"156.292882ms","start":"2026-02-27T01:28:54.474733Z","end":"2026-02-27T01:28:54.631025Z","steps":["trace[140696984] 'agreement among raft nodes before linearized reading' (duration: 156.120408ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:28:56.4818Z","caller":"traceutil/trace.go:171","msg":"trace[429671745] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"123.585522ms","start":"2026-02-27T01:28:56.35819Z","end":"2026-02-27T01:28:56.481775Z","steps":["trace[429671745] 'process raft request' (duration: 81.001864ms)","trace[429671745] 'compare' (duration: 42.464035ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:29:06.550375Z","caller":"traceutil/trace.go:171","msg":"trace[1895715336] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"174.830473ms","start":"2026-02-27T01:29:06.37552Z","end":"2026-02-27T01:29:06.55035Z","steps":["trace[1895715336] 'read index received' (duration: 82.127883ms)","trace[1895715336] 'applied index is now lower than readState.Index' (duration: 92.70173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:29:06.55064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.915174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-02-27T01:29:06.550687Z","caller":"traceutil/trace.go:171","msg":"trace[1567441421] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1863; }","duration":"174.998977ms","start":"2026-02-27T01:29:06.375674Z","end":"2026-02-27T01:29:06.550673Z","steps":["trace[1567441421] 'agreement among raft nodes before linearized reading' (duration: 174.876104ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:29:06.550432Z","caller":"traceutil/trace.go:171","msg":"trace[631477728] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"176.652963ms","start":"2026-02-27T01:29:06.373743Z","end":"2026-02-27T01:29:06.550396Z","steps":["trace[631477728] 'process raft request' (duration: 83.959415ms)","trace[631477728] 'compare' (duration: 92.540794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:29:06.55081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.261762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:coredns\" ","response":"range_response_count:1 size:388"} {"level":"info","ts":"2026-02-27T01:29:06.551183Z","caller":"traceutil/trace.go:171","msg":"trace[677632171] range","detail":"{range_begin:/registry/clusterroles/system:coredns; range_end:; response_count:1; response_revision:1863; }","duration":"175.64745ms","start":"2026-02-27T01:29:06.375494Z","end":"2026-02-27T01:29:06.551141Z","steps":["trace[677632171] 'agreement among raft nodes before linearized reading' (duration: 175.005976ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:29:06.930785Z","caller":"traceutil/trace.go:171","msg":"trace[1154982598] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"110.36804ms","start":"2026-02-27T01:29:06.820395Z","end":"2026-02-27T01:29:06.930763Z","steps":["trace[1154982598] 'process raft request' (duration: 110.192016ms)"],"step_count":1}