{"level":"warn","ts":"2026-02-24T22:01:57.287602Z","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-24T22:01:57.287755Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-24T22:01:57.287886Z","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-24T22:01:57.287912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-24T22:01:57.287952Z","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-24T22:01:57.289713Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-24T22:01:57.289949Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-24T22:01:57.295531Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.675988ms"} {"level":"info","ts":"2026-02-24T22:01:57.308025Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-24T22:01:57.308161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T22:01:57.308196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-24T22:01:57.308216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T22:01:57.308231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-24T22:01:57.30828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-24T22:01:57.323571Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T22:01:57.329574Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T22:01:57.330604Z","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-24T22:01:57.333962Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T22:01:57.334755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T22:01:57.335642Z","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-24T22:01:57.335736Z","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-24T22:01:57.335864Z","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-24T22:01:57.339304Z","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-24T22:01:57.33952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T22:01:57.339558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T22:01:57.339728Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-24T22:01:57.339826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-24T22:01:57.341497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-24T22:01:57.341771Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-24T22:01:58.209474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T22:01:58.209639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T22:01:58.209708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-24T22:01:58.209766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-24T22:01:58.209814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-24T22:01:58.209871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-24T22:01:58.209908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-24T22:01:58.211189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T22:01:58.21212Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T22:01:58.212123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T22:01:58.212174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T22:01:58.212751Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T22:01:58.212828Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T22:01:58.212876Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T22:01:58.213087Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T22:01:58.213135Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T22:01:58.215722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-24T22:01:58.216116Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T22:02:05.101869Z","caller":"traceutil/trace.go:171","msg":"trace[677056667] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"103.520547ms","start":"2026-02-24T22:02:04.99832Z","end":"2026-02-24T22:02:05.10184Z","steps":["trace[677056667] 'process raft request' (duration: 103.342071ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:10.34138Z","caller":"traceutil/trace.go:171","msg":"trace[1627948559] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"254.772005ms","start":"2026-02-24T22:02:10.086548Z","end":"2026-02-24T22:02:10.34132Z","steps":["trace[1627948559] 'process raft request' (duration: 254.561998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:12.005335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564641158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:02:12.919567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.63746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:02:12.919721Z","caller":"traceutil/trace.go:171","msg":"trace[1904895762] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"1.415015962s","start":"2026-02-24T22:02:11.504686Z","end":"2026-02-24T22:02:12.919702Z","steps":["trace[1904895762] 'read index received' (duration: 727.286766ms)","trace[1904895762] 'applied index is now lower than readState.Index' (duration: 687.727856ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:12.919844Z","caller":"traceutil/trace.go:171","msg":"trace[1892384570] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"1.416467619s","start":"2026-02-24T22:02:11.503326Z","end":"2026-02-24T22:02:12.919793Z","steps":["trace[1892384570] 'process raft request' (duration: 728.736093ms)","trace[1892384570] 'compare' (duration: 685.906037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:02:12.92011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:11.503304Z","time spent":"1.416649475s","remote":"127.0.0.1:51930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:02:12.920551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.798644ms","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-24T22:02:12.920542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415338922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:02:12.920603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021057515s","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-24T22:02:12.920596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.907353ms","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-24T22:02:12.920721Z","caller":"traceutil/trace.go:171","msg":"trace[94193747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"1.021480478s","start":"2026-02-24T22:02:11.899213Z","end":"2026-02-24T22:02:12.920693Z","steps":["trace[94193747] 'agreement among raft nodes before linearized reading' (duration: 1.020961472s)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:12.920767Z","caller":"traceutil/trace.go:171","msg":"trace[582851346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"919.416189ms","start":"2026-02-24T22:02:12.0013Z","end":"2026-02-24T22:02:12.920717Z","steps":["trace[582851346] 'agreement among raft nodes before linearized reading' (duration: 918.81151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:12.920794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:11.899186Z","time spent":"1.021598272s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T22:02:12.920663Z","caller":"traceutil/trace.go:171","msg":"trace[1207486793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"550.391373ms","start":"2026-02-24T22:02:12.370236Z","end":"2026-02-24T22:02:12.920627Z","steps":["trace[1207486793] 'agreement among raft nodes before linearized reading' (duration: 549.773683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:12.920853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:12.001275Z","time spent":"919.560894ms","remote":"127.0.0.1:52166","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-24T22:02:12.920582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132626389s","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-24T22:02:12.921205Z","caller":"traceutil/trace.go:171","msg":"trace[1576214532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"1.133303741s","start":"2026-02-24T22:02:11.787879Z","end":"2026-02-24T22:02:12.921182Z","steps":["trace[1576214532] 'agreement among raft nodes before linearized reading' (duration: 1.131952298s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:12.92127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:11.78785Z","time spent":"1.133403454s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T22:02:12.920664Z","caller":"traceutil/trace.go:171","msg":"trace[947168832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"1.416009193s","start":"2026-02-24T22:02:11.504625Z","end":"2026-02-24T22:02:12.920634Z","steps":["trace[947168832] 'agreement among raft nodes before linearized reading' (duration: 1.415179557s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:12.92142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:11.504605Z","time spent":"1.416799569s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:02:12.920899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:12.370209Z","time spent":"550.672892ms","remote":"127.0.0.1:52166","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-24T22:02:31.326411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.852387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:02:31.326667Z","caller":"traceutil/trace.go:171","msg":"trace[1444070456] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"175.807867ms","start":"2026-02-24T22:02:31.150824Z","end":"2026-02-24T22:02:31.326632Z","steps":["trace[1444070456] 'process raft request' (duration: 175.697514ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:31.326778Z","caller":"traceutil/trace.go:171","msg":"trace[343639901] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:532; }","duration":"158.219853ms","start":"2026-02-24T22:02:31.168356Z","end":"2026-02-24T22:02:31.326576Z","steps":["trace[343639901] 'read index received' (duration: 11.120877ms)","trace[343639901] 'applied index is now lower than readState.Index' (duration: 147.098165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:02:31.326877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.313315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T22:02:31.326934Z","caller":"traceutil/trace.go:171","msg":"trace[1952054730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"158.615455ms","start":"2026-02-24T22:02:31.168291Z","end":"2026-02-24T22:02:31.326907Z","steps":["trace[1952054730] 'agreement among raft nodes before linearized reading' (duration: 158.289655ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:31.326962Z","caller":"traceutil/trace.go:171","msg":"trace[1377533704] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"179.588648ms","start":"2026-02-24T22:02:31.146987Z","end":"2026-02-24T22:02:31.326576Z","steps":["trace[1377533704] 'process raft request' (duration: 32.470523ms)","trace[1377533704] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 146.704042ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:31.603886Z","caller":"traceutil/trace.go:171","msg":"trace[1425529935] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"271.142301ms","start":"2026-02-24T22:02:31.332712Z","end":"2026-02-24T22:02:31.603854Z","steps":["trace[1425529935] 'process raft request' (duration: 250.733805ms)","trace[1425529935] 'compare' (duration: 20.147718ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:31.603993Z","caller":"traceutil/trace.go:171","msg":"trace[24162610] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"185.130248ms","start":"2026-02-24T22:02:31.418849Z","end":"2026-02-24T22:02:31.603979Z","steps":["trace[24162610] 'process raft request' (duration: 184.948592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:33.225757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.546168535s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T22:02:33.226327Z","caller":"traceutil/trace.go:171","msg":"trace[1832616722] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"371.983121ms","start":"2026-02-24T22:02:32.854286Z","end":"2026-02-24T22:02:33.226269Z","steps":["trace[1832616722] 'read index received' (duration: 371.63952ms)","trace[1832616722] 'applied index is now lower than readState.Index' (duration: 342.121µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:33.226772Z","caller":"traceutil/trace.go:171","msg":"trace[853838338] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.547304042s","start":"2026-02-24T22:02:31.679455Z","end":"2026-02-24T22:02:33.226759Z","steps":["trace[853838338] 'process raft request' (duration: 1.546564498s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:33.22692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:31.67944Z","time spent":"1.547372235s","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:02:33.227083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.806028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:02:33.227128Z","caller":"traceutil/trace.go:171","msg":"trace[911085794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"372.89523ms","start":"2026-02-24T22:02:32.85422Z","end":"2026-02-24T22:02:33.227115Z","steps":["trace[911085794] 'agreement among raft nodes before linearized reading' (duration: 372.827238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:33.227158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:32.85419Z","time spent":"372.961703ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T22:02:33.532206Z","caller":"traceutil/trace.go:171","msg":"trace[507874846] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"201.811224ms","start":"2026-02-24T22:02:33.33036Z","end":"2026-02-24T22:02:33.532171Z","steps":["trace[507874846] 'read index received' (duration: 148.217662ms)","trace[507874846] 'applied index is now lower than readState.Index' (duration: 53.592282ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:33.532622Z","caller":"traceutil/trace.go:171","msg":"trace[1197412044] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"289.118209ms","start":"2026-02-24T22:02:33.243485Z","end":"2026-02-24T22:02:33.532603Z","steps":["trace[1197412044] 'process raft request' (duration: 235.176126ms)","trace[1197412044] 'compare' (duration: 53.309543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:02:33.533025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.63662ms","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-24T22:02:33.533086Z","caller":"traceutil/trace.go:171","msg":"trace[1711684599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"202.781875ms","start":"2026-02-24T22:02:33.330287Z","end":"2026-02-24T22:02:33.533069Z","steps":["trace[1711684599] 'agreement among raft nodes before linearized reading' (duration: 202.586869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:33.533336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.194526ms","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-24T22:02:33.533434Z","caller":"traceutil/trace.go:171","msg":"trace[314175297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"202.32012ms","start":"2026-02-24T22:02:33.331104Z","end":"2026-02-24T22:02:33.533424Z","steps":["trace[314175297] 'agreement among raft nodes before linearized reading' (duration: 202.155315ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:33.700792Z","caller":"traceutil/trace.go:171","msg":"trace[1045901920] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"162.732188ms","start":"2026-02-24T22:02:33.538008Z","end":"2026-02-24T22:02:33.70074Z","steps":["trace[1045901920] 'process raft request' (duration: 134.443699ms)","trace[1045901920] 'compare' (duration: 28.043641ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:33.700996Z","caller":"traceutil/trace.go:171","msg":"trace[52462851] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"162.530732ms","start":"2026-02-24T22:02:33.538431Z","end":"2026-02-24T22:02:33.700961Z","steps":["trace[52462851] 'process raft request' (duration: 162.220572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:37.823036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.983617ms","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-24T22:02:37.823131Z","caller":"traceutil/trace.go:171","msg":"trace[1518429431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"107.182834ms","start":"2026-02-24T22:02:37.715924Z","end":"2026-02-24T22:02:37.823107Z","steps":["trace[1518429431] 'range keys from in-memory index tree' (duration: 106.773ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:41.506694Z","caller":"traceutil/trace.go:171","msg":"trace[315011437] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"139.981267ms","start":"2026-02-24T22:02:41.366679Z","end":"2026-02-24T22:02:41.50666Z","steps":["trace[315011437] 'process raft request' (duration: 139.739109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:41.723497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.05891ms","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-24T22:02:41.723593Z","caller":"traceutil/trace.go:171","msg":"trace[191062396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"107.216115ms","start":"2026-02-24T22:02:41.616357Z","end":"2026-02-24T22:02:41.723573Z","steps":["trace[191062396] 'range keys from in-memory index tree' (duration: 106.789831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:42.432579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.04852ms","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-24T22:02:42.432713Z","caller":"traceutil/trace.go:171","msg":"trace[2009861999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"587.197575ms","start":"2026-02-24T22:02:41.845485Z","end":"2026-02-24T22:02:42.432682Z","steps":["trace[2009861999] 'range keys from in-memory index tree' (duration: 586.76193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:42.432774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:41.845402Z","time spent":"587.3558ms","remote":"127.0.0.1:52166","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-24T22:02:42.432765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.736272ms","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-24T22:02:42.432791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.619376ms","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-24T22:02:42.432882Z","caller":"traceutil/trace.go:171","msg":"trace[1308505544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"587.963709ms","start":"2026-02-24T22:02:41.844891Z","end":"2026-02-24T22:02:42.432855Z","steps":["trace[1308505544] 'range keys from in-memory index tree' (duration: 587.421611ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:42.432906Z","caller":"traceutil/trace.go:171","msg":"trace[1525850834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"586.767261ms","start":"2026-02-24T22:02:41.84611Z","end":"2026-02-24T22:02:42.432877Z","steps":["trace[1525850834] 'range keys from in-memory index tree' (duration: 586.364817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:42.432933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:41.844856Z","time spent":"588.066442ms","remote":"127.0.0.1:52166","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-24T22:02:42.432955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:41.846094Z","time spent":"586.851093ms","remote":"127.0.0.1:52166","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-24T22:02:42.433388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.8574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:02:42.433502Z","caller":"traceutil/trace.go:171","msg":"trace[1342552687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"562.048196ms","start":"2026-02-24T22:02:41.87143Z","end":"2026-02-24T22:02:42.433478Z","steps":["trace[1342552687] 'range keys from in-memory index tree' (duration: 561.773837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:42.433557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:41.871394Z","time spent":"562.152559ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:02:44.812954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07319658s","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-24T22:02:44.813033Z","caller":"traceutil/trace.go:171","msg":"trace[801015213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"1.073337114s","start":"2026-02-24T22:02:43.739676Z","end":"2026-02-24T22:02:44.813013Z","steps":["trace[801015213] 'range keys from in-memory index tree' (duration: 1.072922001s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:44.813065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:43.739647Z","time spent":"1.073409166s","remote":"127.0.0.1:52166","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-24T22:02:44.813274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.724752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:02:44.813324Z","caller":"traceutil/trace.go:171","msg":"trace[375064372] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"303.274764ms","start":"2026-02-24T22:02:44.510041Z","end":"2026-02-24T22:02:44.813316Z","steps":["trace[375064372] 'read index received' (duration: 277.872188ms)","trace[375064372] 'applied index is now lower than readState.Index' (duration: 25.401756ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:44.813409Z","caller":"traceutil/trace.go:171","msg":"trace[847272970] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"409.396182ms","start":"2026-02-24T22:02:44.404004Z","end":"2026-02-24T22:02:44.8134Z","steps":["trace[847272970] 'process raft request' (duration: 383.990096ms)","trace[847272970] 'compare' (duration: 25.009124ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:02:44.813417Z","caller":"traceutil/trace.go:171","msg":"trace[1037953521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"958.860406ms","start":"2026-02-24T22:02:43.854474Z","end":"2026-02-24T22:02:44.813335Z","steps":["trace[1037953521] 'range keys from in-memory index tree' (duration: 958.606579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:44.813463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:43.854453Z","time spent":"959.000121ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:02:44.813493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:44.403963Z","time spent":"409.461495ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:02:44.813538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.352852ms","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-24T22:02:44.813585Z","caller":"traceutil/trace.go:171","msg":"trace[329682644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"282.462295ms","start":"2026-02-24T22:02:44.531109Z","end":"2026-02-24T22:02:44.813571Z","steps":["trace[329682644] 'agreement among raft nodes before linearized reading' (duration: 282.346651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:44.813728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.487324ms","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-24T22:02:44.813749Z","caller":"traceutil/trace.go:171","msg":"trace[202823937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"267.532425ms","start":"2026-02-24T22:02:44.54621Z","end":"2026-02-24T22:02:44.813743Z","steps":["trace[202823937] 'agreement among raft nodes before linearized reading' (duration: 267.457243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:44.813722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.644185ms","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-24T22:02:44.813831Z","caller":"traceutil/trace.go:171","msg":"trace[1999618345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"303.81735ms","start":"2026-02-24T22:02:44.509985Z","end":"2026-02-24T22:02:44.813802Z","steps":["trace[1999618345] 'agreement among raft nodes before linearized reading' (duration: 303.48894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:44.813919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:44.509956Z","time spent":"303.949745ms","remote":"127.0.0.1:52166","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-24T22:02:46.961724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.749082ms","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-24T22:02:46.961785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.411539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:02:46.961823Z","caller":"traceutil/trace.go:171","msg":"trace[1444957913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"107.943188ms","start":"2026-02-24T22:02:46.853858Z","end":"2026-02-24T22:02:46.961802Z","steps":["trace[1444957913] 'range keys from in-memory index tree' (duration: 107.523234ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:46.962052Z","caller":"traceutil/trace.go:171","msg":"trace[970705679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"106.546213ms","start":"2026-02-24T22:02:46.855316Z","end":"2026-02-24T22:02:46.961862Z","steps":["trace[970705679] 'range keys from in-memory index tree' (duration: 106.343297ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:49.699485Z","caller":"traceutil/trace.go:171","msg":"trace[1570356299] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"129.595754ms","start":"2026-02-24T22:02:49.569862Z","end":"2026-02-24T22:02:49.699458Z","steps":["trace[1570356299] 'process raft request' (duration: 129.413038ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:53.10619Z","caller":"traceutil/trace.go:171","msg":"trace[446450474] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"101.228412ms","start":"2026-02-24T22:02:53.004933Z","end":"2026-02-24T22:02:53.106161Z","steps":["trace[446450474] 'process raft request' (duration: 100.986945ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:53.106277Z","caller":"traceutil/trace.go:171","msg":"trace[1250060871] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"102.3872ms","start":"2026-02-24T22:02:53.00388Z","end":"2026-02-24T22:02:53.106267Z","steps":["trace[1250060871] 'process raft request' (duration: 92.10708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:53.106193Z","caller":"traceutil/trace.go:171","msg":"trace[815404922] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"100.399946ms","start":"2026-02-24T22:02:53.005772Z","end":"2026-02-24T22:02:53.106172Z","steps":["trace[815404922] 'process raft request' (duration: 100.192029ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:55.870613Z","caller":"traceutil/trace.go:171","msg":"trace[1115652388] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"247.742619ms","start":"2026-02-24T22:02:55.62283Z","end":"2026-02-24T22:02:55.870573Z","steps":["trace[1115652388] 'process raft request' (duration: 247.488891ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:02:56.904781Z","caller":"traceutil/trace.go:171","msg":"trace[439527232] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"983.961872ms","start":"2026-02-24T22:02:55.920323Z","end":"2026-02-24T22:02:56.904285Z","steps":["trace[439527232] 'process raft request' (duration: 983.793637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:02:56.904925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:02:55.920291Z","time spent":"984.554741ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:01.744468Z","caller":"traceutil/trace.go:171","msg":"trace[1032027702] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"100.624893ms","start":"2026-02-24T22:03:01.64382Z","end":"2026-02-24T22:03:01.744445Z","steps":["trace[1032027702] 'process raft request' (duration: 100.458058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:03.645653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564641818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:04.148072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564641818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:04.423767Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.307765205s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T22:03:04.42422Z","caller":"traceutil/trace.go:171","msg":"trace[578303847] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"1.278847596s","start":"2026-02-24T22:03:03.145322Z","end":"2026-02-24T22:03:04.424169Z","steps":["trace[578303847] 'read index received' (duration: 1.278627759s)","trace[578303847] 'applied index is now lower than readState.Index' (duration: 218.477µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:04.424271Z","caller":"traceutil/trace.go:171","msg":"trace[1968691162] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.308381745s","start":"2026-02-24T22:03:03.115837Z","end":"2026-02-24T22:03:04.424219Z","steps":["trace[1968691162] 'process raft request' (duration: 1.308147357s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.42448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.11578Z","time spent":"1.308598142s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:04.424483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279123645s","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-24T22:03:04.424489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.037694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:04.424563Z","caller":"traceutil/trace.go:171","msg":"trace[312697340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.279221438s","start":"2026-02-24T22:03:03.145314Z","end":"2026-02-24T22:03:04.424535Z","steps":["trace[312697340] 'agreement among raft nodes before linearized reading' (duration: 1.278990021s)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:04.424577Z","caller":"traceutil/trace.go:171","msg":"trace[1706629694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"570.18783ms","start":"2026-02-24T22:03:03.854366Z","end":"2026-02-24T22:03:04.424554Z","steps":["trace[1706629694] 'agreement among raft nodes before linearized reading' (duration: 570.046005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.424589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270050473s","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-24T22:03:04.424607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.145245Z","time spent":"1.279353552s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:04.42462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.85434Z","time spent":"570.271911ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T22:03:04.424645Z","caller":"traceutil/trace.go:171","msg":"trace[1872712242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"1.270181618s","start":"2026-02-24T22:03:03.154444Z","end":"2026-02-24T22:03:04.424626Z","steps":["trace[1872712242] 'agreement among raft nodes before linearized reading' (duration: 1.26994549s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.424692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.154419Z","time spent":"1.270263491s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T22:03:04.424783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14977636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-24T22:03:04.424816Z","caller":"traceutil/trace.go:171","msg":"trace[29038112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.149858002s","start":"2026-02-24T22:03:03.274946Z","end":"2026-02-24T22:03:04.424804Z","steps":["trace[29038112] 'agreement among raft nodes before linearized reading' (duration: 1.149775599s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.42485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.274915Z","time spent":"1.149928904s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:04.424868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.874199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:04.424913Z","caller":"traceutil/trace.go:171","msg":"trace[801247184] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:735; }","duration":"311.964403ms","start":"2026-02-24T22:03:04.112938Z","end":"2026-02-24T22:03:04.424903Z","steps":["trace[801247184] 'agreement among raft nodes before linearized reading' (duration: 311.88315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.424924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270157997s","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-24T22:03:04.424954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.112905Z","time spent":"312.041135ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:04.42496Z","caller":"traceutil/trace.go:171","msg":"trace[1805517917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.270208718s","start":"2026-02-24T22:03:03.154742Z","end":"2026-02-24T22:03:04.42495Z","steps":["trace[1805517917] 'agreement among raft nodes before linearized reading' (duration: 1.270104555s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:04.424995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:03.154734Z","time spent":"1.27025409s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:05.560172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564641822,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:05.669072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.238944675s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T22:03:05.798733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.860883ms","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-24T22:03:05.79887Z","caller":"traceutil/trace.go:171","msg":"trace[463907146] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:735; }","duration":"746.025769ms","start":"2026-02-24T22:03:05.052826Z","end":"2026-02-24T22:03:05.798852Z","steps":["trace[463907146] 'range keys from in-memory index tree' (duration: 745.841893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:05.799155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.556382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:05.799281Z","caller":"traceutil/trace.go:171","msg":"trace[1409399302] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.369245002s","start":"2026-02-24T22:03:04.430014Z","end":"2026-02-24T22:03:05.799259Z","steps":["trace[1409399302] 'process raft request' (duration: 1.239473452s)","trace[1409399302] 'compare' (duration: 128.866671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:05.799376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.42999Z","time spent":"1.369354825s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:06.68229Z","caller":"traceutil/trace.go:171","msg":"trace[55996469] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"2.251837688s","start":"2026-02-24T22:03:04.430419Z","end":"2026-02-24T22:03:06.682257Z","steps":["trace[55996469] 'process raft request' (duration: 2.220055907s)","trace[55996469] 'compare' (duration: 31.217973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:06.68248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.430402Z","time spent":"2.251984392s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:06.682495Z","caller":"traceutil/trace.go:171","msg":"trace[378449028] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:762; }","duration":"1.622676134s","start":"2026-02-24T22:03:05.05979Z","end":"2026-02-24T22:03:06.682466Z","steps":["trace[378449028] 'read index received' (duration: 609.632926ms)","trace[378449028] 'applied index is now lower than readState.Index' (duration: 1.013041668s)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:06.682623Z","caller":"traceutil/trace.go:171","msg":"trace[1482279493] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.894792796s","start":"2026-02-24T22:03:04.787818Z","end":"2026-02-24T22:03:06.682611Z","steps":["trace[1482279493] 'process raft request' (duration: 1.894494017s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:06.682778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.787783Z","time spent":"1.89486949s","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:06.682779Z","caller":"traceutil/trace.go:171","msg":"trace[321065158] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.658475334s","start":"2026-02-24T22:03:05.024291Z","end":"2026-02-24T22:03:06.682767Z","steps":["trace[321065158] 'process raft request' (duration: 1.658112813s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:06.682854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:05.024261Z","time spent":"1.658556817s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:06.682932Z","caller":"traceutil/trace.go:171","msg":"trace[1403179942] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"2.251299081s","start":"2026-02-24T22:03:04.431599Z","end":"2026-02-24T22:03:06.682898Z","steps":["trace[1403179942] 'process raft request' (duration: 2.250536886s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:06.682973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623192181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:06.683023Z","caller":"traceutil/trace.go:171","msg":"trace[496290606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"1.623271793s","start":"2026-02-24T22:03:05.059739Z","end":"2026-02-24T22:03:06.68301Z","steps":["trace[496290606] 'agreement among raft nodes before linearized reading' (duration: 1.623195331s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:06.683051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:05.059715Z","time spent":"1.623330915s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:06.683103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.431591Z","time spent":"2.251418354s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:06.68316Z","caller":"traceutil/trace.go:171","msg":"trace[112731429] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"2.250990911s","start":"2026-02-24T22:03:04.432124Z","end":"2026-02-24T22:03:06.683115Z","steps":["trace[112731429] 'process raft request' (duration: 2.250116463s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:06.683355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:04.432102Z","time spent":"2.251136966s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:07.243315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.071024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T22:03:07.243468Z","caller":"traceutil/trace.go:171","msg":"trace[2118347312] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"560.878899ms","start":"2026-02-24T22:03:06.682565Z","end":"2026-02-24T22:03:07.243444Z","steps":["trace[2118347312] 'read index received' (duration: 150.65251ms)","trace[2118347312] 'applied index is now lower than readState.Index' (duration: 410.224619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:07.243629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.177131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:07.243669Z","caller":"traceutil/trace.go:171","msg":"trace[1647447181] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:741; }","duration":"661.253804ms","start":"2026-02-24T22:03:06.582402Z","end":"2026-02-24T22:03:07.243656Z","steps":["trace[1647447181] 'agreement among raft nodes before linearized reading' (duration: 661.167321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.243716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:06.582382Z","time spent":"661.324896ms","remote":"127.0.0.1:52208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:03:07.243782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.937445ms","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":"warn","ts":"2026-02-24T22:03:07.2438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.812393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.243791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886976754s","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-24T22:03:07.243844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389005245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.2439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.40529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.243924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.532842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:07.243937Z","caller":"traceutil/trace.go:171","msg":"trace[1069370803] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:741; }","duration":"130.461801ms","start":"2026-02-24T22:03:07.113467Z","end":"2026-02-24T22:03:07.243929Z","steps":["trace[1069370803] 'agreement among raft nodes before linearized reading' (duration: 130.398119ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.243943Z","caller":"traceutil/trace.go:171","msg":"trace[10078360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"1.38915755s","start":"2026-02-24T22:03:05.854761Z","end":"2026-02-24T22:03:07.243919Z","steps":["trace[10078360] 'agreement among raft nodes before linearized reading' (duration: 1.388989135s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.243993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.967348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.244044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.770662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.244053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.160964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:07.244056Z","caller":"traceutil/trace.go:171","msg":"trace[217324356] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:741; }","duration":"131.02577ms","start":"2026-02-24T22:03:07.113006Z","end":"2026-02-24T22:03:07.244031Z","steps":["trace[217324356] 'agreement among raft nodes before linearized reading' (duration: 130.948837ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.244078Z","caller":"traceutil/trace.go:171","msg":"trace[753307093] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:741; }","duration":"116.819063ms","start":"2026-02-24T22:03:07.12725Z","end":"2026-02-24T22:03:07.24407Z","steps":["trace[753307093] 'agreement among raft nodes before linearized reading' (duration: 116.771081ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.244094Z","caller":"traceutil/trace.go:171","msg":"trace[1036771409] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:741; }","duration":"131.237416ms","start":"2026-02-24T22:03:07.112841Z","end":"2026-02-24T22:03:07.244079Z","steps":["trace[1036771409] 'agreement among raft nodes before linearized reading' (duration: 131.187525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.244004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.825232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:03:07.2442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.314338ms","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-24T22:03:07.24423Z","caller":"traceutil/trace.go:171","msg":"trace[45251645] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:741; }","duration":"549.37964ms","start":"2026-02-24T22:03:06.69484Z","end":"2026-02-24T22:03:07.244219Z","steps":["trace[45251645] 'agreement among raft nodes before linearized reading' (duration: 549.340489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.244243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.025929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:07.244272Z","caller":"traceutil/trace.go:171","msg":"trace[693762955] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:741; }","duration":"117.094412ms","start":"2026-02-24T22:03:07.12717Z","end":"2026-02-24T22:03:07.244264Z","steps":["trace[693762955] 'agreement among raft nodes before linearized reading' (duration: 117.048641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.244273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:06.694819Z","time spent":"549.446191ms","remote":"127.0.0.1:40912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T22:03:07.243885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.092648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:07.244731Z","caller":"traceutil/trace.go:171","msg":"trace[162481042] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:741; }","duration":"101.951516ms","start":"2026-02-24T22:03:07.142769Z","end":"2026-02-24T22:03:07.244721Z","steps":["trace[162481042] 'agreement among raft nodes before linearized reading' (duration: 101.092268ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.243912Z","caller":"traceutil/trace.go:171","msg":"trace[324472159] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:741; }","duration":"1.887193101s","start":"2026-02-24T22:03:05.356693Z","end":"2026-02-24T22:03:07.243886Z","steps":["trace[324472159] 'agreement among raft nodes before linearized reading' (duration: 1.886936173s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.24482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:05.356661Z","time spent":"1.888139701s","remote":"127.0.0.1:52020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:03:07.244002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:05.854738Z","time spent":"1.389255274s","remote":"127.0.0.1:51922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T22:03:07.243962Z","caller":"traceutil/trace.go:171","msg":"trace[1395585939] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:741; }","duration":"101.586863ms","start":"2026-02-24T22:03:07.142367Z","end":"2026-02-24T22:03:07.243954Z","steps":["trace[1395585939] 'agreement among raft nodes before linearized reading' (duration: 101.536232ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.2442Z","caller":"traceutil/trace.go:171","msg":"trace[882046903] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:741; }","duration":"102.034988ms","start":"2026-02-24T22:03:07.142152Z","end":"2026-02-24T22:03:07.244187Z","steps":["trace[882046903] 'agreement among raft nodes before linearized reading' (duration: 101.825161ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:07.243841Z","caller":"traceutil/trace.go:171","msg":"trace[1845921605] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:741; }","duration":"549.003087ms","start":"2026-02-24T22:03:06.694824Z","end":"2026-02-24T22:03:07.243827Z","steps":["trace[1845921605] 'agreement among raft nodes before linearized reading' (duration: 548.888504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.245017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:06.694781Z","time spent":"550.225097ms","remote":"127.0.0.1:40912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:03:07.243842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.726515ms","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-24T22:03:07.245107Z","caller":"traceutil/trace.go:171","msg":"trace[1539076586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"816.052877ms","start":"2026-02-24T22:03:06.429044Z","end":"2026-02-24T22:03:07.245097Z","steps":["trace[1539076586] 'agreement among raft nodes before linearized reading' (duration: 814.60152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.245131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:06.429006Z","time spent":"816.118649ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T22:03:07.243844Z","caller":"traceutil/trace.go:171","msg":"trace[1877122616] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:741; }","duration":"116.876535ms","start":"2026-02-24T22:03:07.126958Z","end":"2026-02-24T22:03:07.243834Z","steps":["trace[1877122616] 'agreement among raft nodes before linearized reading' (duration: 116.793953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.244005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.100448ms","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-24T22:03:07.245222Z","caller":"traceutil/trace.go:171","msg":"trace[1144668095] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:741; }","duration":"144.358377ms","start":"2026-02-24T22:03:07.100845Z","end":"2026-02-24T22:03:07.245203Z","steps":["trace[1144668095] 'agreement among raft nodes before linearized reading' (duration: 143.087777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.24416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.890084ms","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-24T22:03:07.245297Z","caller":"traceutil/trace.go:171","msg":"trace[1984151789] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:741; }","duration":"550.062602ms","start":"2026-02-24T22:03:06.695215Z","end":"2026-02-24T22:03:07.245277Z","steps":["trace[1984151789] 'agreement among raft nodes before linearized reading' (duration: 548.916185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:07.245371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:06.695193Z","time spent":"550.138273ms","remote":"127.0.0.1:40912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-24T22:03:10.154947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466349151s","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-24T22:03:10.155064Z","caller":"traceutil/trace.go:171","msg":"trace[1629990250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.466518027s","start":"2026-02-24T22:03:08.688521Z","end":"2026-02-24T22:03:10.155039Z","steps":["trace[1629990250] 'range keys from in-memory index tree' (duration: 1.466136514s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.15512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:08.68851Z","time spent":"1.466597379s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:10.15519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.869783ms","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-24T22:03:10.155344Z","caller":"traceutil/trace.go:171","msg":"trace[851241071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"854.045238ms","start":"2026-02-24T22:03:09.301241Z","end":"2026-02-24T22:03:10.155287Z","steps":["trace[851241071] 'range keys from in-memory index tree' (duration: 853.607625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.155407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:09.301218Z","time spent":"854.173013ms","remote":"127.0.0.1:52166","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-24T22:03:10.155839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.038966ms","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-24T22:03:10.15593Z","caller":"traceutil/trace.go:171","msg":"trace[1263917774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"284.18951ms","start":"2026-02-24T22:03:09.871718Z","end":"2026-02-24T22:03:10.155907Z","steps":["trace[1263917774] 'range keys from in-memory index tree' (duration: 283.862499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.155977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301014359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:10.156072Z","caller":"traceutil/trace.go:171","msg":"trace[992525195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"1.301162614s","start":"2026-02-24T22:03:08.854883Z","end":"2026-02-24T22:03:10.156045Z","steps":["trace[992525195] 'range keys from in-memory index tree' (duration: 1.300848864s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.156091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469154831s","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-24T22:03:10.156129Z","caller":"traceutil/trace.go:171","msg":"trace[989366930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.469219783s","start":"2026-02-24T22:03:08.686898Z","end":"2026-02-24T22:03:10.156117Z","steps":["trace[989366930] 'range keys from in-memory index tree' (duration: 1.469060088s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.156112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:08.854858Z","time spent":"1.301246257s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:10.15615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:08.686875Z","time spent":"1.469269805s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:10.156313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468227841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T22:03:10.156333Z","caller":"traceutil/trace.go:171","msg":"trace[928637942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"1.468304024s","start":"2026-02-24T22:03:08.688022Z","end":"2026-02-24T22:03:10.156326Z","steps":["trace[928637942] 'range keys from in-memory index tree' (duration: 1.468111677s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:10.156351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:08.687991Z","time spent":"1.468355515s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T22:03:10.543302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.56929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:10.543417Z","caller":"traceutil/trace.go:171","msg":"trace[338114688] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:748; }","duration":"198.774346ms","start":"2026-02-24T22:03:10.34462Z","end":"2026-02-24T22:03:10.543395Z","steps":["trace[338114688] 'count revisions from in-memory index tree' (duration: 198.388804ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:11.887026Z","caller":"traceutil/trace.go:171","msg":"trace[466556661] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"277.32253ms","start":"2026-02-24T22:03:11.609677Z","end":"2026-02-24T22:03:11.886999Z","steps":["trace[466556661] 'read index received' (duration: 261.717328ms)","trace[466556661] 'applied index is now lower than readState.Index' (duration: 15.603442ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:11.887088Z","caller":"traceutil/trace.go:171","msg":"trace[158133801] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"447.796157ms","start":"2026-02-24T22:03:11.439239Z","end":"2026-02-24T22:03:11.887036Z","steps":["trace[158133801] 'process raft request' (duration: 432.136504ms)","trace[158133801] 'compare' (duration: 15.204358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:11.887297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:11.43918Z","time spent":"447.988883ms","remote":"127.0.0.1:51930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:11.887338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.653351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-vqpqb.18974ddb0d259738\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-02-24T22:03:11.887384Z","caller":"traceutil/trace.go:171","msg":"trace[629045676] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-vqpqb.18974ddb0d259738; range_end:; response_count:1; response_revision:749; }","duration":"277.763995ms","start":"2026-02-24T22:03:11.609604Z","end":"2026-02-24T22:03:11.887368Z","steps":["trace[629045676] 'agreement among raft nodes before linearized reading' (duration: 277.523207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:11.898333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.817951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:11.898477Z","caller":"traceutil/trace.go:171","msg":"trace[1203694283] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:750; }","duration":"201.000418ms","start":"2026-02-24T22:03:11.697437Z","end":"2026-02-24T22:03:11.898438Z","steps":["trace[1203694283] 'agreement among raft nodes before linearized reading' (duration: 200.75292ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:12.304839Z","caller":"traceutil/trace.go:171","msg":"trace[542733253] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"119.736518ms","start":"2026-02-24T22:03:12.185081Z","end":"2026-02-24T22:03:12.304818Z","steps":["trace[542733253] 'process raft request' (duration: 119.567272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:12.763772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.918347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:12.764045Z","caller":"traceutil/trace.go:171","msg":"trace[1491196192] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:781; }","duration":"275.956236ms","start":"2026-02-24T22:03:12.488064Z","end":"2026-02-24T22:03:12.76402Z","steps":["trace[1491196192] 'read index received' (duration: 93.647368ms)","trace[1491196192] 'applied index is now lower than readState.Index' (duration: 182.307598ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:12.764238Z","caller":"traceutil/trace.go:171","msg":"trace[5910665] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"455.465034ms","start":"2026-02-24T22:03:12.308739Z","end":"2026-02-24T22:03:12.764204Z","steps":["trace[5910665] 'process raft request' (duration: 61.025381ms)","trace[5910665] 'compare' (duration: 393.789032ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:12.764381Z","caller":"traceutil/trace.go:171","msg":"trace[112809452] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"454.442301ms","start":"2026-02-24T22:03:12.309924Z","end":"2026-02-24T22:03:12.764366Z","steps":["trace[112809452] 'process raft request' (duration: 454.060789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:12.764436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:12.309915Z","time spent":"454.489492ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:12.764464Z","caller":"traceutil/trace.go:171","msg":"trace[346859269] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"454.662998ms","start":"2026-02-24T22:03:12.309577Z","end":"2026-02-24T22:03:12.76424Z","steps":["trace[346859269] 'process raft request' (duration: 454.364018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:12.764569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:12.308705Z","time spent":"455.578607ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:12.764583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.509334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:12.764554Z","caller":"traceutil/trace.go:171","msg":"trace[1665323282] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"454.668708ms","start":"2026-02-24T22:03:12.309545Z","end":"2026-02-24T22:03:12.764214Z","steps":["trace[1665323282] 'process raft request' (duration: 454.339428ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:12.764626Z","caller":"traceutil/trace.go:171","msg":"trace[729840418] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:756; }","duration":"276.560606ms","start":"2026-02-24T22:03:12.48805Z","end":"2026-02-24T22:03:12.76461Z","steps":["trace[729840418] 'agreement among raft nodes before linearized reading' (duration: 276.028979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:12.764625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:12.309559Z","time spent":"454.965857ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:12.764771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:12.309533Z","time spent":"455.120042ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:20.952094Z","caller":"traceutil/trace.go:171","msg":"trace[1254003674] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"115.867083ms","start":"2026-02-24T22:03:20.836201Z","end":"2026-02-24T22:03:20.952068Z","steps":["trace[1254003674] 'process raft request' (duration: 115.674687ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:21.105995Z","caller":"traceutil/trace.go:171","msg":"trace[467441399] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"148.641036ms","start":"2026-02-24T22:03:20.957327Z","end":"2026-02-24T22:03:21.105968Z","steps":["trace[467441399] 'process raft request' (duration: 100.613803ms)","trace[467441399] 'compare' (duration: 47.878128ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:21.106137Z","caller":"traceutil/trace.go:171","msg":"trace[85264809] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"148.48425ms","start":"2026-02-24T22:03:20.957629Z","end":"2026-02-24T22:03:21.106114Z","steps":["trace[85264809] 'process raft request' (duration: 148.297874ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:29.275248Z","caller":"traceutil/trace.go:171","msg":"trace[2094575647] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"108.264759ms","start":"2026-02-24T22:03:29.166955Z","end":"2026-02-24T22:03:29.27522Z","steps":["trace[2094575647] 'read index received' (duration: 108.078933ms)","trace[2094575647] 'applied index is now lower than readState.Index' (duration: 184.866µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:29.275376Z","caller":"traceutil/trace.go:171","msg":"trace[1669256561] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"127.725204ms","start":"2026-02-24T22:03:29.147643Z","end":"2026-02-24T22:03:29.275368Z","steps":["trace[1669256561] 'process raft request' (duration: 127.414954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:29.275651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.682211ms","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-24T22:03:29.275737Z","caller":"traceutil/trace.go:171","msg":"trace[2022292691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"108.832927ms","start":"2026-02-24T22:03:29.166881Z","end":"2026-02-24T22:03:29.275714Z","steps":["trace[2022292691] 'agreement among raft nodes before linearized reading' (duration: 108.550498ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:31.499742Z","caller":"traceutil/trace.go:171","msg":"trace[558527196] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"161.52215ms","start":"2026-02-24T22:03:31.338179Z","end":"2026-02-24T22:03:31.499702Z","steps":["trace[558527196] 'process raft request' (duration: 161.447637ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:31.499756Z","caller":"traceutil/trace.go:171","msg":"trace[1682559928] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"163.09182ms","start":"2026-02-24T22:03:31.336621Z","end":"2026-02-24T22:03:31.499713Z","steps":["trace[1682559928] 'process raft request' (duration: 143.199341ms)","trace[1682559928] 'compare' (duration: 19.583699ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:31.507181Z","caller":"traceutil/trace.go:171","msg":"trace[980517959] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"155.055582ms","start":"2026-02-24T22:03:31.352087Z","end":"2026-02-24T22:03:31.507142Z","steps":["trace[980517959] 'process raft request' (duration: 154.857575ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:32.534427Z","caller":"traceutil/trace.go:171","msg":"trace[1298067542] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"114.718065ms","start":"2026-02-24T22:03:32.419676Z","end":"2026-02-24T22:03:32.534394Z","steps":["trace[1298067542] 'process raft request' (duration: 114.532179ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:32.935202Z","caller":"traceutil/trace.go:171","msg":"trace[2120825724] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"326.102727ms","start":"2026-02-24T22:03:32.609056Z","end":"2026-02-24T22:03:32.935159Z","steps":["trace[2120825724] 'process raft request' (duration: 290.721001ms)","trace[2120825724] 'compare' (duration: 34.764016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:32.935417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:32.609032Z","time spent":"326.269113ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:33.619123Z","caller":"traceutil/trace.go:171","msg":"trace[793046167] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"110.676286ms","start":"2026-02-24T22:03:33.508413Z","end":"2026-02-24T22:03:33.619089Z","steps":["trace[793046167] 'process raft request' (duration: 94.990802ms)","trace[793046167] 'compare' (duration: 15.540569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:33.619143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.847198ms","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-24T22:03:33.619263Z","caller":"traceutil/trace.go:171","msg":"trace[1891341552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"113.999452ms","start":"2026-02-24T22:03:33.505236Z","end":"2026-02-24T22:03:33.619236Z","steps":["trace[1891341552] 'range keys from in-memory index tree' (duration: 113.488867ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:35.427502Z","caller":"traceutil/trace.go:171","msg":"trace[1071636433] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"191.674548ms","start":"2026-02-24T22:03:35.235792Z","end":"2026-02-24T22:03:35.427466Z","steps":["trace[1071636433] 'process raft request' (duration: 191.430971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:36.259823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.454062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:36.259956Z","caller":"traceutil/trace.go:171","msg":"trace[957221006] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"635.975201ms","start":"2026-02-24T22:03:35.623959Z","end":"2026-02-24T22:03:36.259935Z","steps":["trace[957221006] 'read index received' (duration: 270.321823ms)","trace[957221006] 'applied index is now lower than readState.Index' (duration: 365.652108ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:36.260207Z","caller":"traceutil/trace.go:171","msg":"trace[1432397384] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"814.754044ms","start":"2026-02-24T22:03:35.445439Z","end":"2026-02-24T22:03:36.260193Z","steps":["trace[1432397384] 'process raft request' (duration: 448.821357ms)","trace[1432397384] 'compare' (duration: 365.004958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:36.260281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:35.445394Z","time spent":"814.853338ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:36.260583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.602602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T22:03:36.260619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.924339ms","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-24T22:03:36.260669Z","caller":"traceutil/trace.go:171","msg":"trace[1325551913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"636.758496ms","start":"2026-02-24T22:03:35.623888Z","end":"2026-02-24T22:03:36.260647Z","steps":["trace[1325551913] 'agreement among raft nodes before linearized reading' (duration: 636.503988ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:36.260703Z","caller":"traceutil/trace.go:171","msg":"trace[1891437744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"617.022892ms","start":"2026-02-24T22:03:35.64366Z","end":"2026-02-24T22:03:36.260683Z","steps":["trace[1891437744] 'agreement among raft nodes before linearized reading' (duration: 616.700772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:36.260726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:35.623859Z","time spent":"636.851839ms","remote":"127.0.0.1:52166","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-24T22:03:36.260753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:35.643605Z","time spent":"617.138446ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:36.260849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.46187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:36.260892Z","caller":"traceutil/trace.go:171","msg":"trace[556200012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"406.550783ms","start":"2026-02-24T22:03:35.85433Z","end":"2026-02-24T22:03:36.26088Z","steps":["trace[556200012] 'agreement among raft nodes before linearized reading' (duration: 406.46964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:36.260961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:35.854304Z","time spent":"406.651986ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:36.884924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:37.385696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:37.886736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:38.387588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:38.856021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000479182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T22:03:38.85617Z","caller":"traceutil/trace.go:171","msg":"trace[1170670909] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000685629s","start":"2026-02-24T22:03:36.855458Z","end":"2026-02-24T22:03:38.856144Z","steps":["trace[1170670909] 'agreement among raft nodes before linearized reading' (duration: 2.000508483s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:38.856251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:36.855434Z","time spent":"2.000804853s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 22:03:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T22:03:38.888797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:39.33075Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.065107187s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T22:03:39.618975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.483586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:39.619116Z","caller":"traceutil/trace.go:171","msg":"trace[2106390268] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"3.353607586s","start":"2026-02-24T22:03:36.265484Z","end":"2026-02-24T22:03:39.619092Z","steps":["trace[2106390268] 'process raft request' (duration: 3.065909263s)","trace[2106390268] 'compare' (duration: 286.998801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:39.619187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:36.265448Z","time spent":"3.353703919s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:40.009776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.0835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:40.010072Z","caller":"traceutil/trace.go:171","msg":"trace[147063400] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"3.744400071s","start":"2026-02-24T22:03:36.265625Z","end":"2026-02-24T22:03:40.010025Z","steps":["trace[147063400] 'process raft request' (duration: 3.505942849s)","trace[147063400] 'compare' (duration: 237.281245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:40.010356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:36.265606Z","time spent":"3.74463836s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:40.391282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.360132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T22:03:40.391712Z","caller":"traceutil/trace.go:171","msg":"trace[2008134310] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:853; }","duration":"4.007909557s","start":"2026-02-24T22:03:36.383778Z","end":"2026-02-24T22:03:40.391688Z","steps":["trace[2008134310] 'read index received' (duration: 2.947565181s)","trace[2008134310] 'applied index is now lower than readState.Index' (duration: 1.060343106s)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:40.391775Z","caller":"traceutil/trace.go:171","msg":"trace[2108114500] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"3.019057646s","start":"2026-02-24T22:03:37.372679Z","end":"2026-02-24T22:03:40.391737Z","steps":["trace[2108114500] 'process raft request' (duration: 3.018824048s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.392526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:37.372643Z","time spent":"3.019689396s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:40.392654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.413285272s","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-24T22:03:40.392632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536017329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:40.392728Z","caller":"traceutil/trace.go:171","msg":"trace[170989665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"3.413427767s","start":"2026-02-24T22:03:36.979282Z","end":"2026-02-24T22:03:40.39271Z","steps":["trace[170989665] 'agreement among raft nodes before linearized reading' (duration: 3.413143168s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.392932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:36.97925Z","time spent":"3.413666435s","remote":"127.0.0.1:52166","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-24T22:03:40.392961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.305261ms","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":"warn","ts":"2026-02-24T22:03:40.393007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.126235671s","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-24T22:03:40.393033Z","caller":"traceutil/trace.go:171","msg":"trace[1397919732] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:823; }","duration":"193.424334ms","start":"2026-02-24T22:03:40.199598Z","end":"2026-02-24T22:03:40.393022Z","steps":["trace[1397919732] 'agreement among raft nodes before linearized reading' (duration: 193.264019ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:40.39309Z","caller":"traceutil/trace.go:171","msg":"trace[1636693808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"2.126346705s","start":"2026-02-24T22:03:38.266708Z","end":"2026-02-24T22:03:40.393055Z","steps":["trace[1636693808] 'agreement among raft nodes before linearized reading' (duration: 2.126104387s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:38.266676Z","time spent":"2.126481419s","remote":"127.0.0.1:52166","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-24T22:03:40.393234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960773073s","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-24T22:03:40.393337Z","caller":"traceutil/trace.go:171","msg":"trace[792619381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"2.960941608s","start":"2026-02-24T22:03:37.432369Z","end":"2026-02-24T22:03:40.393311Z","steps":["trace[792619381] 'agreement among raft nodes before linearized reading' (duration: 2.960664099s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:37.432343Z","time spent":"2.961081513s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:40.392092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.008227397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:40.393556Z","caller":"traceutil/trace.go:171","msg":"trace[1547657498] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:823; }","duration":"4.009828759s","start":"2026-02-24T22:03:36.383711Z","end":"2026-02-24T22:03:40.393539Z","steps":["trace[1547657498] 'agreement among raft nodes before linearized reading' (duration: 4.008083523s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113515662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T22:03:40.393623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:36.383681Z","time spent":"4.009923472s","remote":"127.0.0.1:52094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:40.393646Z","caller":"traceutil/trace.go:171","msg":"trace[2096970985] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:823; }","duration":"2.113654806s","start":"2026-02-24T22:03:38.27997Z","end":"2026-02-24T22:03:40.393625Z","steps":["trace[2096970985] 'agreement among raft nodes before linearized reading' (duration: 2.113473981s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:38.279954Z","time spent":"2.113727239s","remote":"127.0.0.1:52152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:40.392763Z","caller":"traceutil/trace.go:171","msg":"trace[413038438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"1.536247946s","start":"2026-02-24T22:03:38.856493Z","end":"2026-02-24T22:03:40.392741Z","steps":["trace[413038438] 'agreement among raft nodes before linearized reading' (duration: 1.536019099s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:38.856463Z","time spent":"1.537268009s","remote":"127.0.0.1:51922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:40.393703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.641577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:40.393796Z","caller":"traceutil/trace.go:171","msg":"trace[1659634555] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:823; }","duration":"553.785531ms","start":"2026-02-24T22:03:39.839998Z","end":"2026-02-24T22:03:40.393784Z","steps":["trace[1659634555] 'agreement among raft nodes before linearized reading' (duration: 553.661707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.393864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:39.839976Z","time spent":"553.875475ms","remote":"127.0.0.1:52110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:40.556064Z","caller":"traceutil/trace.go:171","msg":"trace[845870220] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"156.23355ms","start":"2026-02-24T22:03:40.399788Z","end":"2026-02-24T22:03:40.556022Z","steps":["trace[845870220] 'process raft request' (duration: 110.911534ms)","trace[845870220] 'compare' (duration: 44.81299ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:40.811624Z","caller":"traceutil/trace.go:171","msg":"trace[427221108] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"411.221032ms","start":"2026-02-24T22:03:40.400372Z","end":"2026-02-24T22:03:40.811593Z","steps":["trace[427221108] 'process raft request' (duration: 410.918062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.81183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:40.400332Z","time spent":"411.369157ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:40.811432Z","caller":"traceutil/trace.go:171","msg":"trace[893045479] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"411.213412ms","start":"2026-02-24T22:03:40.400189Z","end":"2026-02-24T22:03:40.811402Z","steps":["trace[893045479] 'process raft request' (duration: 365.844895ms)","trace[893045479] 'compare' (duration: 45.124509ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:40.812243Z","caller":"traceutil/trace.go:171","msg":"trace[277812500] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"410.405036ms","start":"2026-02-24T22:03:40.401767Z","end":"2026-02-24T22:03:40.812172Z","steps":["trace[277812500] 'process raft request' (duration: 410.278572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.81243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:40.400177Z","time spent":"411.908394ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:40.812358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.112277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:40.812643Z","caller":"traceutil/trace.go:171","msg":"trace[104483869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"410.375305ms","start":"2026-02-24T22:03:40.402209Z","end":"2026-02-24T22:03:40.812585Z","steps":["trace[104483869] 'agreement among raft nodes before linearized reading' (duration: 410.019054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:40.812681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:40.402201Z","time spent":"410.469628ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:40.812658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:40.401744Z","time spent":"410.690495ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T22:03:40.812124Z","caller":"traceutil/trace.go:171","msg":"trace[282079402] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:857; }","duration":"409.861789ms","start":"2026-02-24T22:03:40.402249Z","end":"2026-02-24T22:03:40.812111Z","steps":["trace[282079402] 'read index received' (duration: 108.528987ms)","trace[282079402] 'applied index is now lower than readState.Index' (duration: 301.331922ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:41.610619Z","caller":"traceutil/trace.go:171","msg":"trace[589428625] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"190.670106ms","start":"2026-02-24T22:03:41.419911Z","end":"2026-02-24T22:03:41.610581Z","steps":["trace[589428625] 'process raft request' (duration: 147.106176ms)","trace[589428625] 'compare' (duration: 43.373984ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:41.774425Z","caller":"traceutil/trace.go:171","msg":"trace[580638969] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"137.920981ms","start":"2026-02-24T22:03:41.636478Z","end":"2026-02-24T22:03:41.774398Z","steps":["trace[580638969] 'process raft request' (duration: 88.956098ms)","trace[580638969] 'compare' (duration: 48.824429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:42.836969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.284553ms","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-24T22:03:42.837024Z","caller":"traceutil/trace.go:171","msg":"trace[1637217007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"275.404177ms","start":"2026-02-24T22:03:42.561606Z","end":"2026-02-24T22:03:42.837011Z","steps":["trace[1637217007] 'range keys from in-memory index tree' (duration: 275.120167ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:42.837368Z","caller":"traceutil/trace.go:171","msg":"trace[1758383695] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"209.165308ms","start":"2026-02-24T22:03:42.628173Z","end":"2026-02-24T22:03:42.837339Z","steps":["trace[1758383695] 'process raft request' (duration: 206.831814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:43.447074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:44.171011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.893929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:44.171138Z","caller":"traceutil/trace.go:171","msg":"trace[1455012668] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"1.330140985s","start":"2026-02-24T22:03:42.840976Z","end":"2026-02-24T22:03:44.171117Z","steps":["trace[1455012668] 'process raft request' (duration: 854.031148ms)","trace[1455012668] 'compare' (duration: 475.380103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:44.171203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:42.840956Z","time spent":"1.330215818s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:44.18811Z","caller":"traceutil/trace.go:171","msg":"trace[1346436695] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"1.34646579s","start":"2026-02-24T22:03:42.841623Z","end":"2026-02-24T22:03:44.188088Z","steps":["trace[1346436695] 'process raft request' (duration: 1.346238013s)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:03:44.188221Z","caller":"traceutil/trace.go:171","msg":"trace[1288225305] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"1.346407088s","start":"2026-02-24T22:03:42.841769Z","end":"2026-02-24T22:03:44.188176Z","steps":["trace[1288225305] 'process raft request' (duration: 1.346241502s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:44.188277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:42.841616Z","time spent":"1.346571373s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:44.188268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241964953s","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-24T22:03:44.188326Z","caller":"traceutil/trace.go:171","msg":"trace[1179276212] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:834; }","duration":"1.242104087s","start":"2026-02-24T22:03:42.94621Z","end":"2026-02-24T22:03:44.188314Z","steps":["trace[1179276212] 'agreement among raft nodes before linearized reading' (duration: 1.241956442s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:44.188364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:42.946184Z","time spent":"1.242169338s","remote":"127.0.0.1:51986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:44.188112Z","caller":"traceutil/trace.go:171","msg":"trace[42253484] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:866; }","duration":"1.241804377s","start":"2026-02-24T22:03:42.946277Z","end":"2026-02-24T22:03:44.188081Z","steps":["trace[42253484] 'read index received' (duration: 748.706885ms)","trace[42253484] 'applied index is now lower than readState.Index' (duration: 493.095732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:44.188408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:42.841734Z","time spent":"1.346576643s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:44.188483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.393077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:44.188506Z","caller":"traceutil/trace.go:171","msg":"trace[630213690] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:834; }","duration":"721.470909ms","start":"2026-02-24T22:03:43.467027Z","end":"2026-02-24T22:03:44.188498Z","steps":["trace[630213690] 'agreement among raft nodes before linearized reading' (duration: 721.419607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:44.188526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:43.466997Z","time spent":"721.524311ms","remote":"127.0.0.1:52222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:03:44.188516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.737213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:44.188566Z","caller":"traceutil/trace.go:171","msg":"trace[1874734881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"329.826257ms","start":"2026-02-24T22:03:43.85873Z","end":"2026-02-24T22:03:44.188556Z","steps":["trace[1874734881] 'agreement among raft nodes before linearized reading' (duration: 329.755555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:44.188593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:43.858701Z","time spent":"329.885809ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:03:44.188698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.251168ms","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-24T22:03:44.188797Z","caller":"traceutil/trace.go:171","msg":"trace[141355867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"409.418144ms","start":"2026-02-24T22:03:43.779355Z","end":"2026-02-24T22:03:44.188773Z","steps":["trace[141355867] 'agreement among raft nodes before linearized reading' (duration: 409.233108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:44.188838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:43.779324Z","time spent":"409.504247ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T22:03:45.108922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:44.270813Z","time spent":"838.100357ms","remote":"127.0.0.1:36922","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T22:03:45.109845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.105278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:03:45.109982Z","caller":"traceutil/trace.go:171","msg":"trace[1789898674] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"911.9571ms","start":"2026-02-24T22:03:44.197997Z","end":"2026-02-24T22:03:45.109954Z","steps":["trace[1789898674] 'process raft request' (duration: 660.623995ms)","trace[1789898674] 'compare' (duration: 250.87632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:03:45.110059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:44.197973Z","time spent":"912.049333ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:03:45.138811Z","caller":"traceutil/trace.go:171","msg":"trace[332921010] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:869; }","duration":"571.232962ms","start":"2026-02-24T22:03:44.567546Z","end":"2026-02-24T22:03:45.138779Z","steps":["trace[332921010] 'read index received' (duration: 291.114092ms)","trace[332921010] 'applied index is now lower than readState.Index' (duration: 280.11755ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:45.138937Z","caller":"traceutil/trace.go:171","msg":"trace[812027279] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"938.925406ms","start":"2026-02-24T22:03:44.199965Z","end":"2026-02-24T22:03:45.13889Z","steps":["trace[812027279] 'process raft request' (duration: 938.657907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:45.139011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.44431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:45.139051Z","caller":"traceutil/trace.go:171","msg":"trace[870182471] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:836; }","duration":"571.542093ms","start":"2026-02-24T22:03:44.567498Z","end":"2026-02-24T22:03:45.13904Z","steps":["trace[870182471] 'agreement among raft nodes before linearized reading' (duration: 571.44706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:45.139091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:44.567459Z","time spent":"571.621045ms","remote":"127.0.0.1:51994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:03:45.139178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:44.199936Z","time spent":"939.118042ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:03:45.13925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.308513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:03:45.139365Z","caller":"traceutil/trace.go:171","msg":"trace[583189347] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:836; }","duration":"528.481729ms","start":"2026-02-24T22:03:44.610851Z","end":"2026-02-24T22:03:45.139333Z","steps":["trace[583189347] 'agreement among raft nodes before linearized reading' (duration: 528.270722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:45.140033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:44.61083Z","time spent":"529.183722ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-02-24T22:03:54.524207Z","caller":"traceutil/trace.go:171","msg":"trace[1762842773] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"115.581743ms","start":"2026-02-24T22:03:54.408597Z","end":"2026-02-24T22:03:54.524179Z","steps":["trace[1762842773] 'read index received' (duration: 115.415578ms)","trace[1762842773] 'applied index is now lower than readState.Index' (duration: 164.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:03:54.524293Z","caller":"traceutil/trace.go:171","msg":"trace[841798026] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"251.8026ms","start":"2026-02-24T22:03:54.272444Z","end":"2026-02-24T22:03:54.524247Z","steps":["trace[841798026] 'process raft request' (duration: 251.573153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:54.524379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.758449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:03:54.524462Z","caller":"traceutil/trace.go:171","msg":"trace[2117393738] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:0; response_revision:862; }","duration":"115.903474ms","start":"2026-02-24T22:03:54.408539Z","end":"2026-02-24T22:03:54.524442Z","steps":["trace[2117393738] 'agreement among raft nodes before linearized reading' (duration: 115.757769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:03:58.892775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:59.393555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:03:59.894699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:04:00.395078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:04:00.895451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:04:00.903185Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.520028322s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T22:04:00.904001Z","caller":"traceutil/trace.go:171","msg":"trace[1443786400] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"2.521009314s","start":"2026-02-24T22:03:58.382959Z","end":"2026-02-24T22:04:00.903968Z","steps":["trace[1443786400] 'process raft request' (duration: 2.520552819s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.904145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:58.382935Z","time spent":"2.521114827s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:00.913124Z","caller":"traceutil/trace.go:171","msg":"trace[303329007] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:959; }","duration":"2.520771996s","start":"2026-02-24T22:03:58.392323Z","end":"2026-02-24T22:04:00.913095Z","steps":["trace[303329007] 'read index received' (duration: 2.511094775s)","trace[303329007] 'applied index is now lower than readState.Index' (duration: 9.675781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:00.913531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.52120374s","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-24T22:04:00.913587Z","caller":"traceutil/trace.go:171","msg":"trace[1115424348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"2.521326194s","start":"2026-02-24T22:03:58.392245Z","end":"2026-02-24T22:04:00.913572Z","steps":["trace[1115424348] 'agreement among raft nodes before linearized reading' (duration: 2.521133018s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.913628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:58.392213Z","time spent":"2.521407007s","remote":"127.0.0.1:52166","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-24T22:04:00.91376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375780868s","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-24T22:04:00.91388Z","caller":"traceutil/trace.go:171","msg":"trace[1851885893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"2.375972144s","start":"2026-02-24T22:03:58.537878Z","end":"2026-02-24T22:04:00.913851Z","steps":["trace[1851885893] 'agreement among raft nodes before linearized reading' (duration: 2.375602202s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.91394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:58.537853Z","time spent":"2.376078668s","remote":"127.0.0.1:52166","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-24T22:04:00.913918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059090755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:04:00.913951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.263051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:00.914057Z","caller":"traceutil/trace.go:171","msg":"trace[1046893061] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:922; }","duration":"260.457977ms","start":"2026-02-24T22:04:00.653575Z","end":"2026-02-24T22:04:00.914033Z","steps":["trace[1046893061] 'agreement among raft nodes before linearized reading' (duration: 260.22535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.914174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97185664s","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-24T22:04:00.914214Z","caller":"traceutil/trace.go:171","msg":"trace[1184462269] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:922; }","duration":"1.971944734s","start":"2026-02-24T22:03:58.94226Z","end":"2026-02-24T22:04:00.914205Z","steps":["trace[1184462269] 'agreement among raft nodes before linearized reading' (duration: 1.971867301s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.914253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:58.942235Z","time spent":"1.972010096s","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:04:00.914268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.730721473s","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-24T22:04:00.914214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818607587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:04:00.914304Z","caller":"traceutil/trace.go:171","msg":"trace[1499935347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"1.730797105s","start":"2026-02-24T22:03:59.183496Z","end":"2026-02-24T22:04:00.914293Z","steps":["trace[1499935347] 'agreement among raft nodes before linearized reading' (duration: 1.730544317s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.914339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:59.183466Z","time spent":"1.730865928s","remote":"127.0.0.1:52166","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-24T22:04:00.914364Z","caller":"traceutil/trace.go:171","msg":"trace[1237988810] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:922; }","duration":"1.818830325s","start":"2026-02-24T22:03:59.0955Z","end":"2026-02-24T22:04:00.91433Z","steps":["trace[1237988810] 'agreement among raft nodes before linearized reading' (duration: 1.818449732s)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:00.913998Z","caller":"traceutil/trace.go:171","msg":"trace[1573459619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"1.059212859s","start":"2026-02-24T22:03:59.854768Z","end":"2026-02-24T22:04:00.913981Z","steps":["trace[1573459619] 'agreement among raft nodes before linearized reading' (duration: 1.059107416s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.91443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:59.095477Z","time spent":"1.818940957s","remote":"127.0.0.1:52224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:04:00.914442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:59.854738Z","time spent":"1.059697265s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:04:00.915174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711828197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-24T22:04:00.915789Z","caller":"traceutil/trace.go:171","msg":"trace[2130747275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"1.712477277s","start":"2026-02-24T22:03:59.203291Z","end":"2026-02-24T22:04:00.915769Z","steps":["trace[2130747275] 'agreement among raft nodes before linearized reading' (duration: 1.710264506s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:00.916773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:03:59.203282Z","time spent":"1.712575681s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T22:04:17.287285Z","caller":"traceutil/trace.go:171","msg":"trace[2108215247] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"433.376133ms","start":"2026-02-24T22:04:16.853882Z","end":"2026-02-24T22:04:17.287258Z","steps":["trace[2108215247] 'read index received' (duration: 430.008655ms)","trace[2108215247] 'applied index is now lower than readState.Index' (duration: 3.365818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:17.287491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.362784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T22:04:17.287487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.567339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:17.287534Z","caller":"traceutil/trace.go:171","msg":"trace[1224545308] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:966; }","duration":"433.482217ms","start":"2026-02-24T22:04:16.854042Z","end":"2026-02-24T22:04:17.287524Z","steps":["trace[1224545308] 'agreement among raft nodes before linearized reading' (duration: 433.373123ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:17.287573Z","caller":"traceutil/trace.go:171","msg":"trace[915458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"433.724045ms","start":"2026-02-24T22:04:16.853827Z","end":"2026-02-24T22:04:17.287551Z","steps":["trace[915458] 'agreement among raft nodes before linearized reading' (duration: 433.547159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:17.287633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:16.853805Z","time spent":"433.818758ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:04:17.287802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.684804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T22:04:17.287846Z","caller":"traceutil/trace.go:171","msg":"trace[1281987712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"184.740585ms","start":"2026-02-24T22:04:17.103096Z","end":"2026-02-24T22:04:17.287836Z","steps":["trace[1281987712] 'agreement among raft nodes before linearized reading' (duration: 184.510708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:17.287583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:16.854012Z","time spent":"433.56041ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:04:17.287917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.372431ms","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-24T22:04:17.287973Z","caller":"traceutil/trace.go:171","msg":"trace[2103293886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"183.431024ms","start":"2026-02-24T22:04:17.104518Z","end":"2026-02-24T22:04:17.287949Z","steps":["trace[2103293886] 'agreement among raft nodes before linearized reading' (duration: 183.33018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:17.288165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.961471ms","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-24T22:04:17.287764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.447554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-24T22:04:17.2882Z","caller":"traceutil/trace.go:171","msg":"trace[1748398083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"189.025853ms","start":"2026-02-24T22:04:17.099165Z","end":"2026-02-24T22:04:17.288191Z","steps":["trace[1748398083] 'agreement among raft nodes before linearized reading' (duration: 188.939281ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:17.28822Z","caller":"traceutil/trace.go:171","msg":"trace[1584346053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"183.971121ms","start":"2026-02-24T22:04:17.104237Z","end":"2026-02-24T22:04:17.288208Z","steps":["trace[1584346053] 'agreement among raft nodes before linearized reading' (duration: 183.31104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:17.288369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.606013ms","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-24T22:04:17.288516Z","caller":"traceutil/trace.go:171","msg":"trace[1761471005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"312.819961ms","start":"2026-02-24T22:04:16.97567Z","end":"2026-02-24T22:04:17.28849Z","steps":["trace[1761471005] 'agreement among raft nodes before linearized reading' (duration: 312.325995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:17.288652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:16.975644Z","time spent":"312.971775ms","remote":"127.0.0.1:52166","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-24T22:04:21.837184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642461,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T22:04:22.253096Z","caller":"traceutil/trace.go:171","msg":"trace[260062312] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"927.91ms","start":"2026-02-24T22:04:21.325161Z","end":"2026-02-24T22:04:22.253071Z","steps":["trace[260062312] 'process raft request' (duration: 927.739485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:22.253276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.325152Z","time spent":"928.027725ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:23.147806Z","caller":"traceutil/trace.go:171","msg":"trace[1643545003] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.822454911s","start":"2026-02-24T22:04:21.325307Z","end":"2026-02-24T22:04:23.147762Z","steps":["trace[1643545003] 'process raft request' (duration: 1.792892432s)","trace[1643545003] 'compare' (duration: 29.005512ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:23.148003Z","caller":"traceutil/trace.go:171","msg":"trace[337356067] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.822301216s","start":"2026-02-24T22:04:21.325672Z","end":"2026-02-24T22:04:23.147973Z","steps":["trace[337356067] 'process raft request' (duration: 1.822190383s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:23.148026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.325303Z","time spent":"1.822608096s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:23.148033Z","caller":"traceutil/trace.go:171","msg":"trace[976422848] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.822369288s","start":"2026-02-24T22:04:21.325631Z","end":"2026-02-24T22:04:23.148Z","steps":["trace[976422848] 'process raft request' (duration: 1.822039537s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:23.148111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.325645Z","time spent":"1.82240837s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:23.148202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.325625Z","time spent":"1.822482022s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:23.148423Z","caller":"traceutil/trace.go:171","msg":"trace[844311329] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.821115908s","start":"2026-02-24T22:04:21.327291Z","end":"2026-02-24T22:04:23.148407Z","steps":["trace[844311329] 'process raft request' (duration: 1.820638993s)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:23.148514Z","caller":"traceutil/trace.go:171","msg":"trace[2143619814] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1040; }","duration":"1.812396018s","start":"2026-02-24T22:04:21.336106Z","end":"2026-02-24T22:04:23.148502Z","steps":["trace[2143619814] 'read index received' (duration: 916.835475ms)","trace[2143619814] 'applied index is now lower than readState.Index' (duration: 895.559433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:23.148516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.327282Z","time spent":"1.82118334s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:23.148596Z","caller":"traceutil/trace.go:171","msg":"trace[395649302] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.619258084s","start":"2026-02-24T22:04:21.529284Z","end":"2026-02-24T22:04:23.148542Z","steps":["trace[395649302] 'process raft request' (duration: 1.619076638s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:23.148733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812616435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T22:04:23.148777Z","caller":"traceutil/trace.go:171","msg":"trace[1043954666] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:1002; }","duration":"1.812684438s","start":"2026-02-24T22:04:21.33608Z","end":"2026-02-24T22:04:23.148764Z","steps":["trace[1043954666] 'agreement among raft nodes before linearized reading' (duration: 1.812514962s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:23.148804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.529248Z","time spent":"1.619446539s","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:23.148841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294009854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:23.148965Z","caller":"traceutil/trace.go:171","msg":"trace[980239473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"1.294170218s","start":"2026-02-24T22:04:21.854772Z","end":"2026-02-24T22:04:23.148943Z","steps":["trace[980239473] 'agreement among raft nodes before linearized reading' (duration: 1.294019134s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:23.149017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.85475Z","time spent":"1.294254591s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:04:23.148875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:21.336069Z","time spent":"1.812732099s","remote":"127.0.0.1:51930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-02-24T22:04:24.141214Z","caller":"traceutil/trace.go:171","msg":"trace[575543680] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"137.557389ms","start":"2026-02-24T22:04:24.003588Z","end":"2026-02-24T22:04:24.141146Z","steps":["trace[575543680] 'process raft request' (duration: 137.393514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:24.758526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T22:04:25.069743Z","caller":"traceutil/trace.go:171","msg":"trace[2008615144] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"811.597385ms","start":"2026-02-24T22:04:24.258115Z","end":"2026-02-24T22:04:25.069713Z","steps":["trace[2008615144] 'read index received' (duration: 811.314076ms)","trace[2008615144] 'applied index is now lower than readState.Index' (duration: 282.229µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:25.070088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.946637ms","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-24T22:04:25.070079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.38698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:25.070119Z","caller":"traceutil/trace.go:171","msg":"trace[827110046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"812.05619ms","start":"2026-02-24T22:04:24.258055Z","end":"2026-02-24T22:04:25.070111Z","steps":["trace[827110046] 'agreement among raft nodes before linearized reading' (duration: 811.840623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.070163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:24.258024Z","time spent":"812.132772ms","remote":"127.0.0.1:52166","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-24T22:04:25.070166Z","caller":"traceutil/trace.go:171","msg":"trace[2121497330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"215.582526ms","start":"2026-02-24T22:04:24.854561Z","end":"2026-02-24T22:04:25.070143Z","steps":["trace[2121497330] 'agreement among raft nodes before linearized reading' (duration: 215.357629ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:25.070286Z","caller":"traceutil/trace.go:171","msg":"trace[338927463] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"922.568531ms","start":"2026-02-24T22:04:24.147289Z","end":"2026-02-24T22:04:25.069858Z","steps":["trace[338927463] 'process raft request' (duration: 922.23101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.070354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:24.147273Z","time spent":"923.035625ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T22:04:25.070243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.847085ms","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-24T22:04:25.07052Z","caller":"traceutil/trace.go:171","msg":"trace[1211788046] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1007; }","duration":"733.164815ms","start":"2026-02-24T22:04:24.33732Z","end":"2026-02-24T22:04:25.070484Z","steps":["trace[1211788046] 'agreement among raft nodes before linearized reading' (duration: 732.728821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.070664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:24.337278Z","time spent":"733.33604ms","remote":"127.0.0.1:40912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:04:25.654839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T22:04:25.939121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.588997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:04:25.939271Z","caller":"traceutil/trace.go:171","msg":"trace[1722714864] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"862.2798ms","start":"2026-02-24T22:04:25.076964Z","end":"2026-02-24T22:04:25.939244Z","steps":["trace[1722714864] 'process raft request' (duration: 623.426506ms)","trace[1722714864] 'compare' (duration: 238.277599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:25.939343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.076945Z","time spent":"862.363903ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:25.957191Z","caller":"traceutil/trace.go:171","msg":"trace[1707080275] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1052; }","duration":"803.298789ms","start":"2026-02-24T22:04:25.153856Z","end":"2026-02-24T22:04:25.957155Z","steps":["trace[1707080275] 'read index received' (duration: 546.527895ms)","trace[1707080275] 'applied index is now lower than readState.Index' (duration: 256.769904ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:25.957339Z","caller":"traceutil/trace.go:171","msg":"trace[2027503078] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"876.263432ms","start":"2026-02-24T22:04:25.081063Z","end":"2026-02-24T22:04:25.957327Z","steps":["trace[2027503078] 'process raft request' (duration: 876.031696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.957479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.081053Z","time spent":"876.366736ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:25.957605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.827009ms","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-24T22:04:25.957208Z","caller":"traceutil/trace.go:171","msg":"trace[1534605645] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"877.576355ms","start":"2026-02-24T22:04:25.079605Z","end":"2026-02-24T22:04:25.957182Z","steps":["trace[1534605645] 'process raft request' (duration: 877.451802ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:25.95766Z","caller":"traceutil/trace.go:171","msg":"trace[1734642246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"801.905972ms","start":"2026-02-24T22:04:25.155741Z","end":"2026-02-24T22:04:25.957647Z","steps":["trace[1734642246] 'agreement among raft nodes before linearized reading' (duration: 801.684186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.957702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.155729Z","time spent":"801.965143ms","remote":"127.0.0.1:52166","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-24T22:04:25.957685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.923922ms","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-24T22:04:25.957771Z","caller":"traceutil/trace.go:171","msg":"trace[623179385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"802.073376ms","start":"2026-02-24T22:04:25.155672Z","end":"2026-02-24T22:04:25.957745Z","steps":["trace[623179385] 'agreement among raft nodes before linearized reading' (duration: 801.85017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.957823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.155638Z","time spent":"802.176609ms","remote":"127.0.0.1:52166","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-24T22:04:25.957849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.730002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T22:04:25.957698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.079587Z","time spent":"878.078829ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T22:04:25.957884Z","caller":"traceutil/trace.go:171","msg":"trace[795397738] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1011; }","duration":"259.841854ms","start":"2026-02-24T22:04:25.698031Z","end":"2026-02-24T22:04:25.957873Z","steps":["trace[795397738] 'agreement among raft nodes before linearized reading' (duration: 259.770893ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:25.957267Z","caller":"traceutil/trace.go:171","msg":"trace[1611848250] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"878.758173ms","start":"2026-02-24T22:04:25.078497Z","end":"2026-02-24T22:04:25.957255Z","steps":["trace[1611848250] 'process raft request' (duration: 878.425345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.958086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.078482Z","time spent":"879.565514ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T22:04:25.958103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.144882ms","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-24T22:04:25.958189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.232021ms","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-24T22:04:25.958324Z","caller":"traceutil/trace.go:171","msg":"trace[411366507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"803.380345ms","start":"2026-02-24T22:04:25.154927Z","end":"2026-02-24T22:04:25.958307Z","steps":["trace[411366507] 'agreement among raft nodes before linearized reading' (duration: 802.518432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.958408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.154893Z","time spent":"803.501478ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T22:04:25.958471Z","caller":"traceutil/trace.go:171","msg":"trace[1947310905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"804.421929ms","start":"2026-02-24T22:04:25.153791Z","end":"2026-02-24T22:04:25.958213Z","steps":["trace[1947310905] 'agreement among raft nodes before linearized reading' (duration: 803.846074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:25.959324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:25.153757Z","time spent":"805.512578ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T22:04:26.231767Z","caller":"traceutil/trace.go:171","msg":"trace[152295656] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"268.858542ms","start":"2026-02-24T22:04:25.962874Z","end":"2026-02-24T22:04:26.231732Z","steps":["trace[152295656] 'process raft request' (duration: 268.629836ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:26.249937Z","caller":"traceutil/trace.go:171","msg":"trace[664805011] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"286.955946ms","start":"2026-02-24T22:04:25.962956Z","end":"2026-02-24T22:04:26.249912Z","steps":["trace[664805011] 'process raft request' (duration: 286.663039ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:26.250039Z","caller":"traceutil/trace.go:171","msg":"trace[429136737] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"286.7214ms","start":"2026-02-24T22:04:25.963274Z","end":"2026-02-24T22:04:26.249996Z","steps":["trace[429136737] 'process raft request' (duration: 286.529855ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:26.250113Z","caller":"traceutil/trace.go:171","msg":"trace[1773477992] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"285.718994ms","start":"2026-02-24T22:04:25.964368Z","end":"2026-02-24T22:04:26.250087Z","steps":["trace[1773477992] 'process raft request' (duration: 285.549669ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:26.250131Z","caller":"traceutil/trace.go:171","msg":"trace[45285090] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"286.596497ms","start":"2026-02-24T22:04:25.963509Z","end":"2026-02-24T22:04:26.250106Z","steps":["trace[45285090] 'process raft request' (duration: 286.369601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:26.656187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.350254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T22:04:28.282318Z","caller":"traceutil/trace.go:171","msg":"trace[1299971998] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"333.712602ms","start":"2026-02-24T22:04:27.948581Z","end":"2026-02-24T22:04:28.282293Z","steps":["trace[1299971998] 'process raft request' (duration: 333.24232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:28.282473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:27.948556Z","time spent":"333.835196ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:28.735342Z","caller":"traceutil/trace.go:171","msg":"trace[217344184] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"447.358563ms","start":"2026-02-24T22:04:28.287952Z","end":"2026-02-24T22:04:28.735311Z","steps":["trace[217344184] 'process raft request' (duration: 447.130007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:28.735527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:28.287935Z","time spent":"447.493137ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:29.063509Z","caller":"traceutil/trace.go:171","msg":"trace[381428588] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"774.138094ms","start":"2026-02-24T22:04:28.289336Z","end":"2026-02-24T22:04:29.063475Z","steps":["trace[381428588] 'process raft request' (duration: 732.316168ms)","trace[381428588] 'compare' (duration: 41.368275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:04:29.063658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:28.289313Z","time spent":"774.263278ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:29.063794Z","caller":"traceutil/trace.go:171","msg":"trace[511024521] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1063; }","duration":"440.670187ms","start":"2026-02-24T22:04:28.6231Z","end":"2026-02-24T22:04:29.06377Z","steps":["trace[511024521] 'read index received' (duration: 112.033638ms)","trace[511024521] 'applied index is now lower than readState.Index' (duration: 328.635519ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:29.063861Z","caller":"traceutil/trace.go:171","msg":"trace[661988674] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"772.43422ms","start":"2026-02-24T22:04:28.291401Z","end":"2026-02-24T22:04:29.063835Z","steps":["trace[661988674] 'process raft request' (duration: 772.254905ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:29.064011Z","caller":"traceutil/trace.go:171","msg":"trace[1094266715] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"770.811757ms","start":"2026-02-24T22:04:28.293186Z","end":"2026-02-24T22:04:29.063998Z","steps":["trace[1094266715] 'process raft request' (duration: 770.54088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:29.064061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:28.293175Z","time spent":"770.857548ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:29.064071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.977746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T22:04:29.064429Z","caller":"traceutil/trace.go:171","msg":"trace[1422976697] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1021; }","duration":"441.397237ms","start":"2026-02-24T22:04:28.623018Z","end":"2026-02-24T22:04:29.064416Z","steps":["trace[1422976697] 'agreement among raft nodes before linearized reading' (duration: 440.834282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:29.064476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:28.62299Z","time spent":"441.471438ms","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T22:04:29.0641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:28.291369Z","time spent":"772.547793ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:31.255969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.784538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:31.256086Z","caller":"traceutil/trace.go:171","msg":"trace[1153559780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"401.966933ms","start":"2026-02-24T22:04:30.854091Z","end":"2026-02-24T22:04:31.256058Z","steps":["trace[1153559780] 'range keys from in-memory index tree' (duration: 401.637885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.256082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.969287ms","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-24T22:04:31.256082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.872224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-02-24T22:04:31.256147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:30.854059Z","time spent":"402.072946ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T22:04:31.256152Z","caller":"traceutil/trace.go:171","msg":"trace[1939073792] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1023; }","duration":"295.005248ms","start":"2026-02-24T22:04:30.961134Z","end":"2026-02-24T22:04:31.256139Z","steps":["trace[1939073792] 'range keys from in-memory index tree' (duration: 294.7ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.25626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.60067ms","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-24T22:04:31.256151Z","caller":"traceutil/trace.go:171","msg":"trace[637034694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"189.073229ms","start":"2026-02-24T22:04:31.067058Z","end":"2026-02-24T22:04:31.256132Z","steps":["trace[637034694] 'range keys from in-memory index tree' (duration: 188.852443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.256374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.36023ms","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-24T22:04:31.256376Z","caller":"traceutil/trace.go:171","msg":"trace[546172809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"187.775265ms","start":"2026-02-24T22:04:31.068575Z","end":"2026-02-24T22:04:31.256351Z","steps":["trace[546172809] 'range keys from in-memory index tree' (duration: 187.309432ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:31.25641Z","caller":"traceutil/trace.go:171","msg":"trace[685550100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"188.415522ms","start":"2026-02-24T22:04:31.067984Z","end":"2026-02-24T22:04:31.2564Z","steps":["trace[685550100] 'range keys from in-memory index tree' (duration: 188.235077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.837307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132973564642523,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T22:04:31.872441Z","caller":"traceutil/trace.go:171","msg":"trace[1430515309] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"610.909113ms","start":"2026-02-24T22:04:31.261508Z","end":"2026-02-24T22:04:31.872417Z","steps":["trace[1430515309] 'process raft request' (duration: 610.724178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.872616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:31.26148Z","time spent":"611.027007ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:31.884003Z","caller":"traceutil/trace.go:171","msg":"trace[86183754] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1069; }","duration":"546.71992ms","start":"2026-02-24T22:04:31.337259Z","end":"2026-02-24T22:04:31.883979Z","steps":["trace[86183754] 'read index received' (duration: 534.275324ms)","trace[86183754] 'applied index is now lower than readState.Index' (duration: 12.443246ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:31.884058Z","caller":"traceutil/trace.go:171","msg":"trace[1814981377] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"621.533792ms","start":"2026-02-24T22:04:31.262492Z","end":"2026-02-24T22:04:31.884026Z","steps":["trace[1814981377] 'process raft request' (duration: 621.395808ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:31.884094Z","caller":"traceutil/trace.go:171","msg":"trace[622938110] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"621.034779ms","start":"2026-02-24T22:04:31.26304Z","end":"2026-02-24T22:04:31.884075Z","steps":["trace[622938110] 'process raft request' (duration: 620.904595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.884183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:31.263011Z","time spent":"621.125631ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T22:04:31.884022Z","caller":"traceutil/trace.go:171","msg":"trace[540407757] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"621.663056ms","start":"2026-02-24T22:04:31.262325Z","end":"2026-02-24T22:04:31.883988Z","steps":["trace[540407757] 'process raft request' (duration: 621.418239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.88425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:31.262481Z","time spent":"621.648475ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:31.884298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:31.262311Z","time spent":"621.930132ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:31.884348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.983027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T22:04:31.884418Z","caller":"traceutil/trace.go:171","msg":"trace[2105561656] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:1027; }","duration":"547.153102ms","start":"2026-02-24T22:04:31.337253Z","end":"2026-02-24T22:04:31.884406Z","steps":["trace[2105561656] 'agreement among raft nodes before linearized reading' (duration: 546.859294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:31.884484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:31.337215Z","time spent":"547.258464ms","remote":"127.0.0.1:51930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-02-24T22:04:38.308929Z","caller":"traceutil/trace.go:171","msg":"trace[109914560] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"454.813449ms","start":"2026-02-24T22:04:37.854093Z","end":"2026-02-24T22:04:38.308906Z","steps":["trace[109914560] 'read index received' (duration: 454.544352ms)","trace[109914560] 'applied index is now lower than readState.Index' (duration: 268.037µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:38.30933Z","caller":"traceutil/trace.go:171","msg":"trace[1449278670] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"534.135509ms","start":"2026-02-24T22:04:37.775184Z","end":"2026-02-24T22:04:38.30932Z","steps":["trace[1449278670] 'process raft request' (duration: 533.532163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:38.309451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:37.775155Z","time spent":"534.217002ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T22:04:38.309614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.533258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:04:38.309638Z","caller":"traceutil/trace.go:171","msg":"trace[1709306245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"455.603739ms","start":"2026-02-24T22:04:37.854027Z","end":"2026-02-24T22:04:38.309631Z","steps":["trace[1709306245] 'agreement among raft nodes before linearized reading' (duration: 455.559408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:38.309657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:37.853998Z","time spent":"455.654951ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T22:04:38.309817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.642003ms","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-24T22:04:38.309835Z","caller":"traceutil/trace.go:171","msg":"trace[1288368329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"327.701565ms","start":"2026-02-24T22:04:37.982127Z","end":"2026-02-24T22:04:38.309829Z","steps":["trace[1288368329] 'agreement among raft nodes before linearized reading' (duration: 327.622953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:38.30986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:37.982098Z","time spent":"327.753176ms","remote":"127.0.0.1:52166","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-24T22:04:38.310104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.816962ms","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-24T22:04:38.310131Z","caller":"traceutil/trace.go:171","msg":"trace[482415981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"326.906794ms","start":"2026-02-24T22:04:37.983216Z","end":"2026-02-24T22:04:38.310123Z","steps":["trace[482415981] 'agreement among raft nodes before linearized reading' (duration: 326.806732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:38.31015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:37.983172Z","time spent":"326.973996ms","remote":"127.0.0.1:52166","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-24T22:04:38.310278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.112759ms","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-24T22:04:38.310296Z","caller":"traceutil/trace.go:171","msg":"trace[188492861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"327.185172ms","start":"2026-02-24T22:04:37.983104Z","end":"2026-02-24T22:04:38.310289Z","steps":["trace[188492861] 'agreement among raft nodes before linearized reading' (duration: 327.12974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:04:38.310313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T22:04:37.983079Z","time spent":"327.229682ms","remote":"127.0.0.1:52166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T22:04:38.577297Z","caller":"traceutil/trace.go:171","msg":"trace[1623278363] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"110.151489ms","start":"2026-02-24T22:04:38.467108Z","end":"2026-02-24T22:04:38.57726Z","steps":["trace[1623278363] 'process raft request' (duration: 91.88533ms)","trace[1623278363] 'compare' (duration: 17.956651ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:04:38.577754Z","caller":"traceutil/trace.go:171","msg":"trace[1278265568] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"110.007266ms","start":"2026-02-24T22:04:38.467734Z","end":"2026-02-24T22:04:38.577742Z","steps":["trace[1278265568] 'process raft request' (duration: 109.850332ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:48.486335Z","caller":"traceutil/trace.go:171","msg":"trace[1828222725] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"139.834238ms","start":"2026-02-24T22:04:48.346472Z","end":"2026-02-24T22:04:48.486306Z","steps":["trace[1828222725] 'process raft request' (duration: 139.487569ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:04:53.687029Z","caller":"traceutil/trace.go:171","msg":"trace[1855777323] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"137.615599ms","start":"2026-02-24T22:04:53.549379Z","end":"2026-02-24T22:04:53.686995Z","steps":["trace[1855777323] 'process raft request' (duration: 48.758809ms)","trace[1855777323] 'compare' (duration: 88.696466ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:05:26.908985Z","caller":"traceutil/trace.go:171","msg":"trace[1756083178] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"104.179983ms","start":"2026-02-24T22:05:26.804765Z","end":"2026-02-24T22:05:26.908945Z","steps":["trace[1756083178] 'process raft request' (duration: 98.218217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:05:27.186496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.699372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T22:05:27.18699Z","caller":"traceutil/trace.go:171","msg":"trace[1958727562] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"178.80814ms","start":"2026-02-24T22:05:27.008123Z","end":"2026-02-24T22:05:27.186931Z","steps":["trace[1958727562] 'process raft request' (duration: 19.566094ms)","trace[1958727562] 'compare' (duration: 158.484326ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:06:07.489792Z","caller":"traceutil/trace.go:171","msg":"trace[868171597] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"148.999496ms","start":"2026-02-24T22:06:07.340749Z","end":"2026-02-24T22:06:07.489748Z","steps":["trace[868171597] 'process raft request' (duration: 130.092281ms)","trace[868171597] 'compare' (duration: 18.674439ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:06:07.489851Z","caller":"traceutil/trace.go:171","msg":"trace[441110279] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"147.595649ms","start":"2026-02-24T22:06:07.342198Z","end":"2026-02-24T22:06:07.489794Z","steps":["trace[441110279] 'process raft request' (duration: 147.424715ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:07:14.034091Z","caller":"traceutil/trace.go:171","msg":"trace[1830459745] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1789; }","duration":"110.809224ms","start":"2026-02-24T22:07:13.92325Z","end":"2026-02-24T22:07:14.034059Z","steps":["trace[1830459745] 'read index received' (duration: 76.722373ms)","trace[1830459745] 'applied index is now lower than readState.Index' (duration: 34.085571ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:07:14.034234Z","caller":"traceutil/trace.go:171","msg":"trace[1043486580] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"124.087451ms","start":"2026-02-24T22:07:13.910102Z","end":"2026-02-24T22:07:14.03419Z","steps":["trace[1043486580] 'process raft request' (duration: 89.939759ms)","trace[1043486580] 'compare' (duration: 33.875095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:07:14.034338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.073211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-24T22:07:14.034381Z","caller":"traceutil/trace.go:171","msg":"trace[1542539008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1711; }","duration":"111.185364ms","start":"2026-02-24T22:07:13.923184Z","end":"2026-02-24T22:07:14.034369Z","steps":["trace[1542539008] 'agreement among raft nodes before linearized reading' (duration: 111.001989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:07:57.131706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.233921ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T22:07:57.131972Z","caller":"traceutil/trace.go:171","msg":"trace[907747548] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"278.118965ms","start":"2026-02-24T22:07:56.853819Z","end":"2026-02-24T22:07:57.131938Z","steps":["trace[907747548] 'read index received' (duration: 54.431µs)","trace[907747548] 'applied index is now lower than readState.Index' (duration: 278.058724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T22:07:57.132127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.289569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T22:07:57.132199Z","caller":"traceutil/trace.go:171","msg":"trace[1880696099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"278.409603ms","start":"2026-02-24T22:07:56.853761Z","end":"2026-02-24T22:07:57.13217Z","steps":["trace[1880696099] 'agreement among raft nodes before linearized reading' (duration: 278.273069ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:07:57.132697Z","caller":"traceutil/trace.go:171","msg":"trace[1555824529] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"102.605041ms","start":"2026-02-24T22:07:57.030047Z","end":"2026-02-24T22:07:57.132652Z","steps":["trace[1555824529] 'process raft request' (duration: 101.80108ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T22:08:06.329593Z","caller":"traceutil/trace.go:171","msg":"trace[1320445647] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2041; }","duration":"216.421825ms","start":"2026-02-24T22:08:06.113132Z","end":"2026-02-24T22:08:06.329554Z","steps":["trace[1320445647] 'read index received' (duration: 216.352993ms)","trace[1320445647] 'applied index is now lower than readState.Index' (duration: 67.272µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T22:08:06.329656Z","caller":"traceutil/trace.go:171","msg":"trace[1214448785] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"267.935381ms","start":"2026-02-24T22:08:06.061694Z","end":"2026-02-24T22:08:06.329629Z","steps":["trace[1214448785] 'process raft request' (duration: 267.568122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T22:08:06.329879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.720723ms","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-24T22:08:06.329928Z","caller":"traceutil/trace.go:171","msg":"trace[261446283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1952; }","duration":"216.849906ms","start":"2026-02-24T22:08:06.113061Z","end":"2026-02-24T22:08:06.329911Z","steps":["trace[261446283] 'agreement among raft nodes before linearized reading' (duration: 216.636031ms)"],"step_count":1}