{"level":"warn","ts":"2026-02-20T18:28:41.68284Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:28:41.682939Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-20T18:28:41.683022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:28:41.683035Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-20T18:28:41.683055Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:28:41.683663Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-20T18:28:41.683746Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-20T18:28:41.688394Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.409005ms"} {"level":"info","ts":"2026-02-20T18:28:41.694357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-20T18:28:41.694423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:28:41.694442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-20T18:28:41.694455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:28:41.694472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-20T18:28:41.694505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-20T18:28:41.699968Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:28:41.701476Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:28:41.702886Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-20T18:28:41.705106Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:28:41.705304Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:28:41.705551Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:28:41.705658Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:28:41.705676Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:28:41.706695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-20T18:28:41.706946Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-20T18:28:41.709262Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:28:41.709471Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:28:41.709519Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:28:41.709564Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-20T18:28:41.709571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-20T18:28:42.09573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:28:42.095804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:28:42.095828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-20T18:28:42.095848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:28:42.09586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-20T18:28:42.095881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-20T18:28:42.0959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-20T18:28:42.097017Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:28:42.097019Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:28:42.097103Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:28:42.097325Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:42.097558Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:28:42.097641Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:28:42.099179Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:42.09937Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:42.099415Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:28:42.101611Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-20T18:28:42.102035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:28:57.002656Z","caller":"traceutil/trace.go:171","msg":"trace[669769761] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"111.150443ms","start":"2026-02-20T18:28:56.891485Z","end":"2026-02-20T18:28:57.002635Z","steps":["trace[669769761] 'process raft request' (duration: 111.043881ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:03.655683Z","caller":"traceutil/trace.go:171","msg":"trace[125997343] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"117.793658ms","start":"2026-02-20T18:29:03.53786Z","end":"2026-02-20T18:29:03.655653Z","steps":["trace[125997343] 'process raft request' (duration: 117.530541ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:09.985234Z","caller":"traceutil/trace.go:171","msg":"trace[1104280602] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"272.149129ms","start":"2026-02-20T18:29:09.713043Z","end":"2026-02-20T18:29:09.985192Z","steps":["trace[1104280602] 'process raft request' (duration: 271.929144ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:10.348342Z","caller":"traceutil/trace.go:171","msg":"trace[777809202] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"632.5824ms","start":"2026-02-20T18:29:09.715728Z","end":"2026-02-20T18:29:10.348311Z","steps":["trace[777809202] 'process raft request' (duration: 632.286903ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:10.348752Z","caller":"traceutil/trace.go:171","msg":"trace[1693284652] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"635.445126ms","start":"2026-02-20T18:29:09.713261Z","end":"2026-02-20T18:29:10.348706Z","steps":["trace[1693284652] 'process raft request' (duration: 553.86446ms)","trace[1693284652] 'compare' (duration: 80.553759ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:10.348785Z","caller":"traceutil/trace.go:171","msg":"trace[42094931] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:449; }","duration":"241.38576ms","start":"2026-02-20T18:29:10.107371Z","end":"2026-02-20T18:29:10.348756Z","steps":["trace[42094931] 'read index received' (duration: 159.761723ms)","trace[42094931] 'applied index is now lower than readState.Index' (duration: 81.618167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:10.349078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:09.715719Z","time spent":"632.701693ms","remote":"127.0.0.1:56298","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-20T18:29:10.349226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:09.713222Z","time spent":"635.60104ms","remote":"127.0.0.1:56298","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-20T18:29:10.349552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.138909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:29:10.349607Z","caller":"traceutil/trace.go:171","msg":"trace[228200400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"242.294584ms","start":"2026-02-20T18:29:10.107295Z","end":"2026-02-20T18:29:10.34959Z","steps":["trace[228200400] 'agreement among raft nodes before linearized reading' (duration: 241.659727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.504269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790455840,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:29:14.513868Z","caller":"traceutil/trace.go:171","msg":"trace[774868395] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"510.117467ms","start":"2026-02-20T18:29:14.003715Z","end":"2026-02-20T18:29:14.513832Z","steps":["trace[774868395] 'read index received' (duration: 509.915542ms)","trace[774868395] 'applied index is now lower than readState.Index' (duration: 200.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:14.513916Z","caller":"traceutil/trace.go:171","msg":"trace[349542217] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"515.17249ms","start":"2026-02-20T18:29:13.998694Z","end":"2026-02-20T18:29:14.513867Z","steps":["trace[349542217] 'process raft request' (duration: 514.939444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.514081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:13.998672Z","time spent":"515.321405ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:14.514121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.610035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-20T18:29:14.514162Z","caller":"traceutil/trace.go:171","msg":"trace[2041766807] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:476; }","duration":"479.689457ms","start":"2026-02-20T18:29:14.03446Z","end":"2026-02-20T18:29:14.51415Z","steps":["trace[2041766807] 'agreement among raft nodes before linearized reading' (duration: 479.566273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.514155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.416986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-20T18:29:14.514206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.034418Z","time spent":"479.778479ms","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-20T18:29:14.514241Z","caller":"traceutil/trace.go:171","msg":"trace[1970016038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"510.56672ms","start":"2026-02-20T18:29:14.003655Z","end":"2026-02-20T18:29:14.514221Z","steps":["trace[1970016038] 'agreement among raft nodes before linearized reading' (duration: 510.306033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.51424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.905971ms","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-20T18:29:14.514296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.003633Z","time spent":"510.655162ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T18:29:14.514331Z","caller":"traceutil/trace.go:171","msg":"trace[395169030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"145.037095ms","start":"2026-02-20T18:29:14.369272Z","end":"2026-02-20T18:29:14.514309Z","steps":["trace[395169030] 'agreement among raft nodes before linearized reading' (duration: 144.883081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.514379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.135923ms","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-20T18:29:14.51442Z","caller":"traceutil/trace.go:171","msg":"trace[187229746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"149.206544ms","start":"2026-02-20T18:29:14.365201Z","end":"2026-02-20T18:29:14.514408Z","steps":["trace[187229746] 'agreement among raft nodes before linearized reading' (duration: 149.108012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.514525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.83566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:29:14.514564Z","caller":"traceutil/trace.go:171","msg":"trace[1170510046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"152.907652ms","start":"2026-02-20T18:29:14.361646Z","end":"2026-02-20T18:29:14.514554Z","steps":["trace[1170510046] 'agreement among raft nodes before linearized reading' (duration: 152.842851ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.023242Z","caller":"traceutil/trace.go:171","msg":"trace[230305129] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"504.788919ms","start":"2026-02-20T18:29:14.518425Z","end":"2026-02-20T18:29:15.023213Z","steps":["trace[230305129] 'process raft request' (duration: 504.660675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.023448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.518399Z","time spent":"504.917012ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:15.034982Z","caller":"traceutil/trace.go:171","msg":"trace[549391349] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"513.470997ms","start":"2026-02-20T18:29:14.521482Z","end":"2026-02-20T18:29:15.034953Z","steps":["trace[549391349] 'process raft request' (duration: 513.22676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.035187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.521463Z","time spent":"513.603441ms","remote":"127.0.0.1:56298","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-20T18:29:15.035234Z","caller":"traceutil/trace.go:171","msg":"trace[1197228224] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"513.54713ms","start":"2026-02-20T18:29:14.521668Z","end":"2026-02-20T18:29:15.035215Z","steps":["trace[1197228224] 'process raft request' (duration: 513.432897ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.035304Z","caller":"traceutil/trace.go:171","msg":"trace[966075031] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:492; }","duration":"441.319979ms","start":"2026-02-20T18:29:14.593953Z","end":"2026-02-20T18:29:15.035273Z","steps":["trace[966075031] 'read index received' (duration: 429.164159ms)","trace[966075031] 'applied index is now lower than readState.Index' (duration: 12.15482ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:15.035328Z","caller":"traceutil/trace.go:171","msg":"trace[100285080] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"510.032797ms","start":"2026-02-20T18:29:14.525276Z","end":"2026-02-20T18:29:15.035309Z","steps":["trace[100285080] 'process raft request' (duration: 509.898713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.035407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.521662Z","time spent":"513.629312ms","remote":"127.0.0.1:56298","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-20T18:29:15.035447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.525263Z","time spent":"510.14805ms","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:15.03532Z","caller":"traceutil/trace.go:171","msg":"trace[2027088633] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"513.686613ms","start":"2026-02-20T18:29:14.521586Z","end":"2026-02-20T18:29:15.035273Z","steps":["trace[2027088633] 'process raft request' (duration: 513.286942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.035581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.621097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:15.035647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.521579Z","time spent":"513.9718ms","remote":"127.0.0.1:56298","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-20T18:29:15.035701Z","caller":"traceutil/trace.go:171","msg":"trace[1209940609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"441.807372ms","start":"2026-02-20T18:29:14.593863Z","end":"2026-02-20T18:29:15.035671Z","steps":["trace[1209940609] 'agreement among raft nodes before linearized reading' (duration: 441.606526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.035848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.59383Z","time spent":"441.971606ms","remote":"127.0.0.1:56006","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-20T18:29:21.317975Z","caller":"traceutil/trace.go:171","msg":"trace[966035685] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"273.953056ms","start":"2026-02-20T18:29:21.044001Z","end":"2026-02-20T18:29:21.317954Z","steps":["trace[966035685] 'read index received' (duration: 273.756601ms)","trace[966035685] 'applied index is now lower than readState.Index' (duration: 195.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:21.318093Z","caller":"traceutil/trace.go:171","msg":"trace[757766544] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"318.309343ms","start":"2026-02-20T18:29:20.999774Z","end":"2026-02-20T18:29:21.318083Z","steps":["trace[757766544] 'process raft request' (duration: 318.054416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.318196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:20.999736Z","time spent":"318.384225ms","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:21.318309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.000288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:29:21.318344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.354997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-20T18:29:21.318384Z","caller":"traceutil/trace.go:171","msg":"trace[2035737176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"274.451909ms","start":"2026-02-20T18:29:21.043921Z","end":"2026-02-20T18:29:21.318372Z","steps":["trace[2035737176] 'agreement among raft nodes before linearized reading' (duration: 274.390068ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:21.318388Z","caller":"traceutil/trace.go:171","msg":"trace[1809263840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"263.125201ms","start":"2026-02-20T18:29:21.055242Z","end":"2026-02-20T18:29:21.318367Z","steps":["trace[1809263840] 'agreement among raft nodes before linearized reading' (duration: 262.879945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.3184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.15998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-20T18:29:21.31831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.772233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:21.3185Z","caller":"traceutil/trace.go:171","msg":"trace[164535480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"250.305274ms","start":"2026-02-20T18:29:21.068171Z","end":"2026-02-20T18:29:21.318477Z","steps":["trace[164535480] 'agreement among raft nodes before linearized reading' (duration: 250.022217ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:21.31852Z","caller":"traceutil/trace.go:171","msg":"trace[592740852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"249.03089ms","start":"2026-02-20T18:29:21.069481Z","end":"2026-02-20T18:29:21.318512Z","steps":["trace[592740852] 'agreement among raft nodes before linearized reading' (duration: 248.671141ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:21.430025Z","caller":"traceutil/trace.go:171","msg":"trace[369563784] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"106.47458ms","start":"2026-02-20T18:29:21.323533Z","end":"2026-02-20T18:29:21.430008Z","steps":["trace[369563784] 'process raft request' (duration: 79.007547ms)","trace[369563784] 'compare' (duration: 27.34304ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:21.43022Z","caller":"traceutil/trace.go:171","msg":"trace[1994272505] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"106.539231ms","start":"2026-02-20T18:29:21.32366Z","end":"2026-02-20T18:29:21.430199Z","steps":["trace[1994272505] 'process raft request' (duration: 106.317365ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:21.430753Z","caller":"traceutil/trace.go:171","msg":"trace[1054966850] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"106.760037ms","start":"2026-02-20T18:29:21.323919Z","end":"2026-02-20T18:29:21.430679Z","steps":["trace[1054966850] 'process raft request' (duration: 106.226903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.958867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.231256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:21.959013Z","caller":"traceutil/trace.go:171","msg":"trace[2056296372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"365.398661ms","start":"2026-02-20T18:29:21.593566Z","end":"2026-02-20T18:29:21.958965Z","steps":["trace[2056296372] 'range keys from in-memory index tree' (duration: 365.131934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:21.959062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:21.593549Z","time spent":"365.499304ms","remote":"127.0.0.1:56006","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-20T18:29:23.791982Z","caller":"traceutil/trace.go:171","msg":"trace[1087391752] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"357.100803ms","start":"2026-02-20T18:29:23.434818Z","end":"2026-02-20T18:29:23.791919Z","steps":["trace[1087391752] 'read index received' (duration: 356.905788ms)","trace[1087391752] 'applied index is now lower than readState.Index' (duration: 194.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:23.792163Z","caller":"traceutil/trace.go:171","msg":"trace[1005309064] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"445.835566ms","start":"2026-02-20T18:29:23.346313Z","end":"2026-02-20T18:29:23.792148Z","steps":["trace[1005309064] 'process raft request' (duration: 445.481317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:23.792376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.522054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-20T18:29:23.792779Z","caller":"traceutil/trace.go:171","msg":"trace[371933519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"358.026077ms","start":"2026-02-20T18:29:23.434725Z","end":"2026-02-20T18:29:23.792751Z","steps":["trace[371933519] 'agreement among raft nodes before linearized reading' (duration: 357.452622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:23.792855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.948905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-20T18:29:23.792976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.434705Z","time spent":"358.256353ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:23.79238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.536894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:29:23.793223Z","caller":"traceutil/trace.go:171","msg":"trace[1090479651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:500; }","duration":"358.427438ms","start":"2026-02-20T18:29:23.434785Z","end":"2026-02-20T18:29:23.793213Z","steps":["trace[1090479651] 'agreement among raft nodes before linearized reading' (duration: 357.417431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:23.79321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.321649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:23.793278Z","caller":"traceutil/trace.go:171","msg":"trace[1555789751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"200.442222ms","start":"2026-02-20T18:29:23.592818Z","end":"2026-02-20T18:29:23.79326Z","steps":["trace[1555789751] 'agreement among raft nodes before linearized reading' (duration: 200.309449ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:23.793031Z","caller":"traceutil/trace.go:171","msg":"trace[1387490272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"358.161831ms","start":"2026-02-20T18:29:23.43485Z","end":"2026-02-20T18:29:23.793011Z","steps":["trace[1387490272] 'agreement among raft nodes before linearized reading' (duration: 357.780501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:23.793432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.434835Z","time spent":"358.576972ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:23.793474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.43477Z","time spent":"358.552051ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:29:23.793743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.346286Z","time spent":"446.310719ms","remote":"127.0.0.1:56298","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-20T18:29:24.849391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050296076s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:24.849667Z","caller":"traceutil/trace.go:171","msg":"trace[665272825] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.050650366s","start":"2026-02-20T18:29:23.798997Z","end":"2026-02-20T18:29:24.849648Z","steps":["trace[665272825] 'process raft request' (duration: 1.050539543s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:24.849774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.798973Z","time spent":"1.050737667s","remote":"127.0.0.1:56298","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-20T18:29:24.852107Z","caller":"traceutil/trace.go:171","msg":"trace[1259601331] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:518; }","duration":"259.073344ms","start":"2026-02-20T18:29:24.593021Z","end":"2026-02-20T18:29:24.852094Z","steps":["trace[1259601331] 'read index received' (duration: 256.554408ms)","trace[1259601331] 'applied index is now lower than readState.Index' (duration: 2.518216ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:24.85215Z","caller":"traceutil/trace.go:171","msg":"trace[1947883865] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.052270648s","start":"2026-02-20T18:29:23.799866Z","end":"2026-02-20T18:29:24.852137Z","steps":["trace[1947883865] 'process raft request' (duration: 1.052103244s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:24.852185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.166606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:24.852227Z","caller":"traceutil/trace.go:171","msg":"trace[1354906258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"259.212397ms","start":"2026-02-20T18:29:24.593006Z","end":"2026-02-20T18:29:24.852218Z","steps":["trace[1354906258] 'agreement among raft nodes before linearized reading' (duration: 259.136985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:24.852219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.799833Z","time spent":"1.05234426s","remote":"127.0.0.1:56298","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-20T18:29:24.85227Z","caller":"traceutil/trace.go:171","msg":"trace[676656799] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.05195516s","start":"2026-02-20T18:29:23.800281Z","end":"2026-02-20T18:29:24.852236Z","steps":["trace[676656799] 'process raft request' (duration: 1.051754295s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:24.852305Z","caller":"traceutil/trace.go:171","msg":"trace[441432613] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"147.186052ms","start":"2026-02-20T18:29:24.705079Z","end":"2026-02-20T18:29:24.852265Z","steps":["trace[441432613] 'process raft request' (duration: 146.988106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:24.852439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:23.800273Z","time spent":"1.052070273s","remote":"127.0.0.1:56298","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-20T18:29:27.023162Z","caller":"traceutil/trace.go:171","msg":"trace[1414476942] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"113.939447ms","start":"2026-02-20T18:29:26.909203Z","end":"2026-02-20T18:29:27.023143Z","steps":["trace[1414476942] 'process raft request' (duration: 113.833024ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:27.537512Z","caller":"traceutil/trace.go:171","msg":"trace[510677827] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"106.118102ms","start":"2026-02-20T18:29:27.431371Z","end":"2026-02-20T18:29:27.537489Z","steps":["trace[510677827] 'process raft request' (duration: 105.980978ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:37.258874Z","caller":"traceutil/trace.go:171","msg":"trace[1376418692] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"163.44428ms","start":"2026-02-20T18:29:37.095395Z","end":"2026-02-20T18:29:37.25884Z","steps":["trace[1376418692] 'read index received' (duration: 153.113148ms)","trace[1376418692] 'applied index is now lower than readState.Index' (duration: 10.330272ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:37.25922Z","caller":"traceutil/trace.go:171","msg":"trace[644074067] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"206.025499ms","start":"2026-02-20T18:29:37.053176Z","end":"2026-02-20T18:29:37.259201Z","steps":["trace[644074067] 'process raft request' (duration: 195.383139ms)","trace[644074067] 'compare' (duration: 10.170478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:37.259221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.822459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-qcvbl\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-20T18:29:37.259274Z","caller":"traceutil/trace.go:171","msg":"trace[88487852] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-qcvbl; range_end:; response_count:1; response_revision:642; }","duration":"163.939383ms","start":"2026-02-20T18:29:37.095322Z","end":"2026-02-20T18:29:37.259262Z","steps":["trace[88487852] 'agreement among raft nodes before linearized reading' (duration: 163.781669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:37.259599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.561401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:29:37.25964Z","caller":"traceutil/trace.go:171","msg":"trace[220290123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"120.609292ms","start":"2026-02-20T18:29:37.139021Z","end":"2026-02-20T18:29:37.25963Z","steps":["trace[220290123] 'agreement among raft nodes before linearized reading' (duration: 120.093249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:43.783092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:44.483645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.025282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:44.483786Z","caller":"traceutil/trace.go:171","msg":"trace[1833307348] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"1.201033811s","start":"2026-02-20T18:29:43.282731Z","end":"2026-02-20T18:29:44.483765Z","steps":["trace[1833307348] 'read index received' (duration: 573.396962ms)","trace[1833307348] 'applied index is now lower than readState.Index' (duration: 627.634899ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:44.483846Z","caller":"traceutil/trace.go:171","msg":"trace[2109535256] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.38004825s","start":"2026-02-20T18:29:43.103757Z","end":"2026-02-20T18:29:44.483805Z","steps":["trace[2109535256] 'process raft request' (duration: 752.484003ms)","trace[2109535256] 'compare' (duration: 626.55326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:44.484029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.103746Z","time spent":"1.380176674s","remote":"127.0.0.1:56298","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-20T18:29:44.484051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201321019s","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-20T18:29:44.484153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19717991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:29:44.484187Z","caller":"traceutil/trace.go:171","msg":"trace[135541888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"1.197289312s","start":"2026-02-20T18:29:43.286885Z","end":"2026-02-20T18:29:44.484174Z","steps":["trace[135541888] 'agreement among raft nodes before linearized reading' (duration: 1.19720291s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.484185Z","caller":"traceutil/trace.go:171","msg":"trace[1779629374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"1.201450282s","start":"2026-02-20T18:29:43.282687Z","end":"2026-02-20T18:29:44.484137Z","steps":["trace[1779629374] 'agreement among raft nodes before linearized reading' (duration: 1.201193416s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.484225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.286866Z","time spent":"1.197350394s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:44.484294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.282669Z","time spent":"1.201590906s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:29:44.484319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.793736ms","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-20T18:29:44.48437Z","caller":"traceutil/trace.go:171","msg":"trace[1396128036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"602.882048ms","start":"2026-02-20T18:29:43.881471Z","end":"2026-02-20T18:29:44.484354Z","steps":["trace[1396128036] 'agreement among raft nodes before linearized reading' (duration: 602.702323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.484361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.335984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:44.484401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.881451Z","time spent":"602.941159ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:29:44.484405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.584199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:44.484449Z","caller":"traceutil/trace.go:171","msg":"trace[272743085] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:686; }","duration":"951.673521ms","start":"2026-02-20T18:29:43.532763Z","end":"2026-02-20T18:29:44.484437Z","steps":["trace[272743085] 'agreement among raft nodes before linearized reading' (duration: 951.595469ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.484441Z","caller":"traceutil/trace.go:171","msg":"trace[360890429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"891.457298ms","start":"2026-02-20T18:29:43.592962Z","end":"2026-02-20T18:29:44.484419Z","steps":["trace[360890429] 'agreement among raft nodes before linearized reading' (duration: 891.314384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.484484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.532738Z","time spent":"951.735713ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:44.484491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.592947Z","time spent":"891.53594ms","remote":"127.0.0.1:56006","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-20T18:29:45.219317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.141721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:45.219427Z","caller":"traceutil/trace.go:171","msg":"trace[2068069070] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"731.440211ms","start":"2026-02-20T18:29:44.487965Z","end":"2026-02-20T18:29:45.219405Z","steps":["trace[2068069070] 'process raft request' (duration: 611.094535ms)","trace[2068069070] 'compare' (duration: 119.383161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:45.219491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.487944Z","time spent":"731.516714ms","remote":"127.0.0.1:56298","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-20T18:29:45.232506Z","caller":"traceutil/trace.go:171","msg":"trace[627285486] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"743.739915ms","start":"2026-02-20T18:29:44.48874Z","end":"2026-02-20T18:29:45.23248Z","steps":["trace[627285486] 'process raft request' (duration: 743.483408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.232646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.488725Z","time spent":"743.853728ms","remote":"127.0.0.1:56298","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-20T18:29:45.2325Z","caller":"traceutil/trace.go:171","msg":"trace[1931506259] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:709; }","duration":"633.515915ms","start":"2026-02-20T18:29:44.598935Z","end":"2026-02-20T18:29:45.232451Z","steps":["trace[1931506259] 'read index received' (duration: 500.068175ms)","trace[1931506259] 'applied index is now lower than readState.Index' (duration: 133.44536ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:45.232941Z","caller":"traceutil/trace.go:171","msg":"trace[629915221] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"743.94888ms","start":"2026-02-20T18:29:44.488975Z","end":"2026-02-20T18:29:45.232924Z","steps":["trace[629915221] 'process raft request' (duration: 743.414346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.233085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.661242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:29:45.233137Z","caller":"traceutil/trace.go:171","msg":"trace[828381476] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:689; }","duration":"600.782445ms","start":"2026-02-20T18:29:44.63234Z","end":"2026-02-20T18:29:45.233122Z","steps":["trace[828381476] 'agreement among raft nodes before linearized reading' (duration: 600.690423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.233185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.632318Z","time spent":"600.856906ms","remote":"127.0.0.1:56478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:45.233181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.488963Z","time spent":"744.026073ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:45.233362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.43623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:45.233511Z","caller":"traceutil/trace.go:171","msg":"trace[138437224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"634.637515ms","start":"2026-02-20T18:29:44.598857Z","end":"2026-02-20T18:29:45.233494Z","steps":["trace[138437224] 'agreement among raft nodes before linearized reading' (duration: 634.478551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.233568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.598796Z","time spent":"634.756838ms","remote":"127.0.0.1:56006","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-20T18:29:45.233683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.654346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-20T18:29:45.233781Z","caller":"traceutil/trace.go:171","msg":"trace[1112554310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"106.743329ms","start":"2026-02-20T18:29:45.126987Z","end":"2026-02-20T18:29:45.23373Z","steps":["trace[1112554310] 'agreement among raft nodes before linearized reading' (duration: 106.503933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.233792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.982549ms","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-20T18:29:45.233845Z","caller":"traceutil/trace.go:171","msg":"trace[399182547] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:689; }","duration":"382.067712ms","start":"2026-02-20T18:29:44.851763Z","end":"2026-02-20T18:29:45.233831Z","steps":["trace[399182547] 'agreement among raft nodes before linearized reading' (duration: 381.98431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.233891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.851748Z","time spent":"382.133533ms","remote":"127.0.0.1:56236","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":"warn","ts":"2026-02-20T18:29:45.234055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.633619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T18:29:45.233462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.810044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:45.234097Z","caller":"traceutil/trace.go:171","msg":"trace[321972891] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:689; }","duration":"143.751332ms","start":"2026-02-20T18:29:45.090335Z","end":"2026-02-20T18:29:45.234087Z","steps":["trace[321972891] 'agreement among raft nodes before linearized reading' (duration: 143.599778ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:45.234139Z","caller":"traceutil/trace.go:171","msg":"trace[1593045076] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:689; }","duration":"347.480831ms","start":"2026-02-20T18:29:44.886637Z","end":"2026-02-20T18:29:45.234118Z","steps":["trace[1593045076] 'agreement among raft nodes before linearized reading' (duration: 346.780563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:45.234212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.886571Z","time spent":"347.614815ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:46.722092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.501154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:46.722185Z","caller":"traceutil/trace.go:171","msg":"trace[1471649472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"127.640528ms","start":"2026-02-20T18:29:46.594525Z","end":"2026-02-20T18:29:46.722165Z","steps":["trace[1471649472] 'range keys from in-memory index tree' (duration: 127.430392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:46.7227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.90897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-20T18:29:46.722741Z","caller":"traceutil/trace.go:171","msg":"trace[832098755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"231.984712ms","start":"2026-02-20T18:29:46.490746Z","end":"2026-02-20T18:29:46.722731Z","steps":["trace[832098755] 'range keys from in-memory index tree' (duration: 231.747036ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:46.881298Z","caller":"traceutil/trace.go:171","msg":"trace[1411574150] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"152.269336ms","start":"2026-02-20T18:29:46.729009Z","end":"2026-02-20T18:29:46.881278Z","steps":["trace[1411574150] 'process raft request' (duration: 152.135082ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:11.342095Z","caller":"traceutil/trace.go:171","msg":"trace[983283619] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"307.525688ms","start":"2026-02-20T18:30:11.03455Z","end":"2026-02-20T18:30:11.342075Z","steps":["trace[983283619] 'process raft request' (duration: 307.367554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:11.342288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.034529Z","time spent":"307.635441ms","remote":"127.0.0.1:56298","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-20T18:30:12.51967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12008423s","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-20T18:30:12.519783Z","caller":"traceutil/trace.go:171","msg":"trace[68541613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"1.120260455s","start":"2026-02-20T18:30:11.399496Z","end":"2026-02-20T18:30:12.519757Z","steps":["trace[68541613] 'range keys from in-memory index tree' (duration: 1.119891164s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.519838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.399477Z","time spent":"1.120346806s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:30:12.519915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.711278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:12.520021Z","caller":"traceutil/trace.go:171","msg":"trace[2139321813] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:766; }","duration":"650.866992ms","start":"2026-02-20T18:30:11.869135Z","end":"2026-02-20T18:30:12.520002Z","steps":["trace[2139321813] 'count revisions from in-memory index tree' (duration: 650.564363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.519697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063567573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-20T18:30:12.519915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.558991ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:30:12.520074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.869116Z","time spent":"650.946334ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:30:12.520103Z","caller":"traceutil/trace.go:171","msg":"trace[1822960972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.063992894s","start":"2026-02-20T18:30:11.456075Z","end":"2026-02-20T18:30:12.520068Z","steps":["trace[1822960972] 'range keys from in-memory index tree' (duration: 1.063354758s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:12.520177Z","caller":"traceutil/trace.go:171","msg":"trace[1215495958] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:766; }","duration":"468.785107ms","start":"2026-02-20T18:30:12.051323Z","end":"2026-02-20T18:30:12.520108Z","steps":["trace[1215495958] 'range keys from in-memory index tree' (duration: 468.543261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.520198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.456058Z","time spent":"1.064117378s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:30:12.520562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120597584s","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-20T18:30:12.52072Z","caller":"traceutil/trace.go:171","msg":"trace[1714648991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"1.120800399s","start":"2026-02-20T18:30:11.399905Z","end":"2026-02-20T18:30:12.520705Z","steps":["trace[1714648991] 'range keys from in-memory index tree' (duration: 1.120370858s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.520921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.399884Z","time spent":"1.121022935s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:30:12.519832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059571156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:30:12.521736Z","caller":"traceutil/trace.go:171","msg":"trace[1151072356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.061508408s","start":"2026-02-20T18:30:11.460207Z","end":"2026-02-20T18:30:12.521716Z","steps":["trace[1151072356] 'range keys from in-memory index tree' (duration: 1.059450003s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.521799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.460188Z","time spent":"1.061591891s","remote":"127.0.0.1:56298","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-20T18:30:12.520527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041786364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:12.524169Z","caller":"traceutil/trace.go:171","msg":"trace[2077313557] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:766; }","duration":"1.045447091s","start":"2026-02-20T18:30:11.478707Z","end":"2026-02-20T18:30:12.524154Z","steps":["trace[2077313557] 'count revisions from in-memory index tree' (duration: 1.041570838s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:12.524214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:11.478696Z","time spent":"1.045503142s","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:30:12.751474Z","caller":"traceutil/trace.go:171","msg":"trace[1511974106] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"225.841401ms","start":"2026-02-20T18:30:12.525612Z","end":"2026-02-20T18:30:12.751454Z","steps":["trace[1511974106] 'process raft request' (duration: 206.663159ms)","trace[1511974106] 'compare' (duration: 19.075549ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:12.805515Z","caller":"traceutil/trace.go:171","msg":"trace[532189361] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"279.548082ms","start":"2026-02-20T18:30:12.525943Z","end":"2026-02-20T18:30:12.805491Z","steps":["trace[532189361] 'process raft request' (duration: 279.348117ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:12.805839Z","caller":"traceutil/trace.go:171","msg":"trace[1663171466] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"279.495711ms","start":"2026-02-20T18:30:12.526327Z","end":"2026-02-20T18:30:12.805823Z","steps":["trace[1663171466] 'process raft request' (duration: 279.118411ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:12.805868Z","caller":"traceutil/trace.go:171","msg":"trace[214440974] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"279.017338ms","start":"2026-02-20T18:30:12.526817Z","end":"2026-02-20T18:30:12.805834Z","steps":["trace[214440974] 'process raft request' (duration: 278.868024ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:12.805857Z","caller":"traceutil/trace.go:171","msg":"trace[651727900] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:795; }","duration":"211.681933ms","start":"2026-02-20T18:30:12.59414Z","end":"2026-02-20T18:30:12.805822Z","steps":["trace[651727900] 'read index received' (duration: 137.779843ms)","trace[651727900] 'applied index is now lower than readState.Index' (duration: 73.90063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:12.805994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.840717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:12.806069Z","caller":"traceutil/trace.go:171","msg":"trace[1764704698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"211.96517ms","start":"2026-02-20T18:30:12.594092Z","end":"2026-02-20T18:30:12.806057Z","steps":["trace[1764704698] 'agreement among raft nodes before linearized reading' (duration: 211.833737ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:15.33354Z","caller":"traceutil/trace.go:171","msg":"trace[443844493] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"428.00658ms","start":"2026-02-20T18:30:14.905501Z","end":"2026-02-20T18:30:15.333508Z","steps":["trace[443844493] 'process raft request' (duration: 427.796625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:15.333771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:14.905482Z","time spent":"428.159774ms","remote":"127.0.0.1:56298","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-20T18:30:15.557468Z","caller":"traceutil/trace.go:171","msg":"trace[625544147] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"651.871798ms","start":"2026-02-20T18:30:14.905572Z","end":"2026-02-20T18:30:15.557444Z","steps":["trace[625544147] 'process raft request' (duration: 650.605454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:15.557692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:14.905549Z","time spent":"651.98838ms","remote":"127.0.0.1:56298","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-20T18:30:15.558523Z","caller":"traceutil/trace.go:171","msg":"trace[1568033396] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:801; }","duration":"466.069176ms","start":"2026-02-20T18:30:15.09242Z","end":"2026-02-20T18:30:15.55849Z","steps":["trace[1568033396] 'read index received' (duration: 240.846522ms)","trace[1568033396] 'applied index is now lower than readState.Index' (duration: 225.221204ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:15.558702Z","caller":"traceutil/trace.go:171","msg":"trace[1761042664] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"652.311689ms","start":"2026-02-20T18:30:14.906258Z","end":"2026-02-20T18:30:15.55857Z","steps":["trace[1761042664] 'process raft request' (duration: 651.125107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:15.559444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:14.906251Z","time spent":"653.099891ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:15.558788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.365243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T18:30:15.559737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.356078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:30:15.55997Z","caller":"traceutil/trace.go:171","msg":"trace[1661382165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"192.605315ms","start":"2026-02-20T18:30:15.36735Z","end":"2026-02-20T18:30:15.559955Z","steps":["trace[1661382165] 'agreement among raft nodes before linearized reading' (duration: 192.075141ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:15.559866Z","caller":"traceutil/trace.go:171","msg":"trace[119133507] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:775; }","duration":"467.496343ms","start":"2026-02-20T18:30:15.092352Z","end":"2026-02-20T18:30:15.559848Z","steps":["trace[119133507] 'agreement among raft nodes before linearized reading' (duration: 466.303051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:15.560259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:15.092321Z","time spent":"467.916854ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-20T18:30:16.605517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.323254ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-20T18:30:16.605907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:15.561646Z","time spent":"1.04425553s","remote":"127.0.0.1:56058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:30:16.605728Z","caller":"traceutil/trace.go:171","msg":"trace[886245319] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"1.012540595s","start":"2026-02-20T18:30:15.59317Z","end":"2026-02-20T18:30:16.60571Z","steps":["trace[886245319] 'read index received' (duration: 297.979794ms)","trace[886245319] 'applied index is now lower than readState.Index' (duration: 714.559841ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:16.606015Z","caller":"traceutil/trace.go:171","msg":"trace[1340687657] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"1.041812144s","start":"2026-02-20T18:30:15.56419Z","end":"2026-02-20T18:30:16.606003Z","steps":["trace[1340687657] 'process raft request' (duration: 1.041420014s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:16.606074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012894074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:16.606109Z","caller":"traceutil/trace.go:171","msg":"trace[1711295629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"1.012968986s","start":"2026-02-20T18:30:15.59313Z","end":"2026-02-20T18:30:16.606099Z","steps":["trace[1711295629] 'agreement among raft nodes before linearized reading' (duration: 1.012904424s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:16.606121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:15.564169Z","time spent":"1.041891997s","remote":"127.0.0.1:56298","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-20T18:30:16.606139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:15.593107Z","time spent":"1.013025787s","remote":"127.0.0.1:56006","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-20T18:30:17.611194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.891339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:17.611423Z","caller":"traceutil/trace.go:171","msg":"trace[467965360] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.004180391s","start":"2026-02-20T18:30:16.607228Z","end":"2026-02-20T18:30:17.611408Z","steps":["trace[467965360] 'process raft request' (duration: 1.0041272s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:17.611443Z","caller":"traceutil/trace.go:171","msg":"trace[1507308138] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.20105549s","start":"2026-02-20T18:30:16.410355Z","end":"2026-02-20T18:30:17.61141Z","steps":["trace[1507308138] 'process raft request' (duration: 491.863903ms)","trace[1507308138] 'compare' (duration: 708.809587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:17.611498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.607213Z","time spent":"1.004258573s","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:17.611637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.410331Z","time spent":"1.201198143s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:17.611713Z","caller":"traceutil/trace.go:171","msg":"trace[1083267677] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"1.005732962s","start":"2026-02-20T18:30:16.60597Z","end":"2026-02-20T18:30:17.611703Z","steps":["trace[1083267677] 'read index received' (duration: 296.257248ms)","trace[1083267677] 'applied index is now lower than readState.Index' (duration: 709.474044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:17.611868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339582703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:17.611908Z","caller":"traceutil/trace.go:171","msg":"trace[555068727] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:778; }","duration":"1.339661795s","start":"2026-02-20T18:30:16.272238Z","end":"2026-02-20T18:30:17.611899Z","steps":["trace[555068727] 'agreement among raft nodes before linearized reading' (duration: 1.339576413s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:17.61193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004678094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:17.611967Z","caller":"traceutil/trace.go:171","msg":"trace[2040825655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"1.004745876s","start":"2026-02-20T18:30:16.607212Z","end":"2026-02-20T18:30:17.611958Z","steps":["trace[2040825655] 'agreement among raft nodes before linearized reading' (duration: 1.004685384s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:17.611945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.272218Z","time spent":"1.339719547s","remote":"127.0.0.1:56252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:30:17.611995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.607191Z","time spent":"1.004799348s","remote":"127.0.0.1:56012","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-20T18:30:17.612327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.317734ms","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-20T18:30:17.613411Z","caller":"traceutil/trace.go:171","msg":"trace[962805394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"245.413843ms","start":"2026-02-20T18:30:17.367967Z","end":"2026-02-20T18:30:17.613381Z","steps":["trace[962805394] 'agreement among raft nodes before linearized reading' (duration: 244.312553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:17.612393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.312835ms","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-20T18:30:17.613645Z","caller":"traceutil/trace.go:171","msg":"trace[1731571977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"840.602751ms","start":"2026-02-20T18:30:16.773029Z","end":"2026-02-20T18:30:17.613632Z","steps":["trace[1731571977] 'agreement among raft nodes before linearized reading' (duration: 839.323726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:17.613685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.773006Z","time spent":"840.668792ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:30:18.117388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456511,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:30:18.517089Z","caller":"traceutil/trace.go:171","msg":"trace[352306282] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"900.444796ms","start":"2026-02-20T18:30:17.616623Z","end":"2026-02-20T18:30:18.517068Z","steps":["trace[352306282] 'process raft request' (duration: 900.271901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.517286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.616607Z","time spent":"900.556898ms","remote":"127.0.0.1:56298","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-20T18:30:18.634776Z","caller":"traceutil/trace.go:171","msg":"trace[1454754469] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"1.017786924s","start":"2026-02-20T18:30:17.616963Z","end":"2026-02-20T18:30:18.63475Z","steps":["trace[1454754469] 'process raft request' (duration: 969.143137ms)","trace[1454754469] 'compare' (duration: 48.467783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:18.634994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.61694Z","time spent":"1.017915087s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:18.635158Z","caller":"traceutil/trace.go:171","msg":"trace[821624209] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"1.017836255s","start":"2026-02-20T18:30:17.617283Z","end":"2026-02-20T18:30:18.635119Z","steps":["trace[821624209] 'process raft request' (duration: 1.017423524s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.635279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.617274Z","time spent":"1.017940088s","remote":"127.0.0.1:56298","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-20T18:30:18.635313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017944289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-20T18:30:18.635151Z","caller":"traceutil/trace.go:171","msg":"trace[1831195447] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:809; }","duration":"1.017779874s","start":"2026-02-20T18:30:17.617348Z","end":"2026-02-20T18:30:18.635128Z","steps":["trace[1831195447] 'read index received' (duration: 899.706606ms)","trace[1831195447] 'applied index is now lower than readState.Index' (duration: 118.071858ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:18.635373Z","caller":"traceutil/trace.go:171","msg":"trace[78498687] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:782; }","duration":"1.018033761s","start":"2026-02-20T18:30:17.617328Z","end":"2026-02-20T18:30:18.635362Z","steps":["trace[78498687] 'agreement among raft nodes before linearized reading' (duration: 1.017871106s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.635416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.617319Z","time spent":"1.018088702s","remote":"127.0.0.1:56314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T18:30:18.635436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01466068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:18.635459Z","caller":"traceutil/trace.go:171","msg":"trace[2014385930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"1.014702282s","start":"2026-02-20T18:30:17.620749Z","end":"2026-02-20T18:30:18.635452Z","steps":["trace[2014385930] 'agreement among raft nodes before linearized reading' (duration: 1.014659081s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.635483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.620742Z","time spent":"1.014737873s","remote":"127.0.0.1:56006","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-20T18:30:18.635683Z","caller":"traceutil/trace.go:171","msg":"trace[414872599] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"1.018309188s","start":"2026-02-20T18:30:17.617332Z","end":"2026-02-20T18:30:18.635641Z","steps":["trace[414872599] 'process raft request' (duration: 1.017665401s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.635726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.03086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:18.636108Z","caller":"traceutil/trace.go:171","msg":"trace[1606886416] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:782; }","duration":"162.442671ms","start":"2026-02-20T18:30:18.473637Z","end":"2026-02-20T18:30:18.63608Z","steps":["trace[1606886416] 'agreement among raft nodes before linearized reading' (duration: 162.05213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:18.636374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:17.617326Z","time spent":"1.018666327s","remote":"127.0.0.1:56298","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-20T18:30:19.327742Z","caller":"traceutil/trace.go:171","msg":"trace[2089264837] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"359.890235ms","start":"2026-02-20T18:30:18.967831Z","end":"2026-02-20T18:30:19.327721Z","steps":["trace[2089264837] 'process raft request' (duration: 359.756521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:19.327913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:18.967806Z","time spent":"360.006038ms","remote":"127.0.0.1:56298","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-20T18:30:31.575375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.179818ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:30:31.575573Z","caller":"traceutil/trace.go:171","msg":"trace[206084504] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"349.782035ms","start":"2026-02-20T18:30:31.225769Z","end":"2026-02-20T18:30:31.575551Z","steps":["trace[206084504] 'read index received' (duration: 209.327921ms)","trace[206084504] 'applied index is now lower than readState.Index' (duration: 140.452294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:31.575689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.905218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:31.57573Z","caller":"traceutil/trace.go:171","msg":"trace[2064282309] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:868; }","duration":"350.004501ms","start":"2026-02-20T18:30:31.225712Z","end":"2026-02-20T18:30:31.575717Z","steps":["trace[2064282309] 'agreement among raft nodes before linearized reading' (duration: 349.905678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:31.575781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:31.225687Z","time spent":"350.080913ms","remote":"127.0.0.1:56254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:30:33.13003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:33.630313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:34.130512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:34.630186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000645135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:30:34.630306Z","caller":"traceutil/trace.go:171","msg":"trace[263433446] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000771139s","start":"2026-02-20T18:30:32.629492Z","end":"2026-02-20T18:30:34.630263Z","steps":["trace[263433446] 'agreement among raft nodes before linearized reading' (duration: 2.000646515s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:34.630346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.629488Z","time spent":"2.000849651s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:30:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:30:34.631256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:35.131896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456717,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:35.339712Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.710331495s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:30:35.340209Z","caller":"traceutil/trace.go:171","msg":"trace[2064817757] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"2.71087965s","start":"2026-02-20T18:30:32.629307Z","end":"2026-02-20T18:30:35.340187Z","steps":["trace[2064817757] 'process raft request' (duration: 2.710635364s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.340503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.629299Z","time spent":"2.710960232s","remote":"127.0.0.1:56298","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-20T18:30:35.40966Z","caller":"traceutil/trace.go:171","msg":"trace[418687851] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"773.916423ms","start":"2026-02-20T18:30:34.635727Z","end":"2026-02-20T18:30:35.409644Z","steps":["trace[418687851] 'process raft request' (duration: 773.777779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.40978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:34.6357Z","time spent":"774.025145ms","remote":"127.0.0.1:56086","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-20T18:30:35.409649Z","caller":"traceutil/trace.go:171","msg":"trace[746474606] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:903; }","duration":"2.780127326s","start":"2026-02-20T18:30:32.629501Z","end":"2026-02-20T18:30:35.409628Z","steps":["trace[746474606] 'read index received' (duration: 2.710451529s)","trace[746474606] 'applied index is now lower than readState.Index' (duration: 69.674907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:35.410062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.626149081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:30:35.410088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.570239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-20T18:30:35.410105Z","caller":"traceutil/trace.go:171","msg":"trace[1451685831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"2.626232084s","start":"2026-02-20T18:30:32.783862Z","end":"2026-02-20T18:30:35.410094Z","steps":["trace[1451685831] 'agreement among raft nodes before linearized reading' (duration: 2.626075889s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:35.41011Z","caller":"traceutil/trace.go:171","msg":"trace[226719769] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:870; }","duration":"316.639001ms","start":"2026-02-20T18:30:35.093464Z","end":"2026-02-20T18:30:35.410103Z","steps":["trace[226719769] 'agreement among raft nodes before linearized reading' (duration: 316.568829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.410131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:35.093431Z","time spent":"316.695712ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-20T18:30:35.410145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.783844Z","time spent":"2.626282584s","remote":"127.0.0.1:56298","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-20T18:30:35.410132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.651972149s","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-20T18:30:35.410212Z","caller":"traceutil/trace.go:171","msg":"trace[283338332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"2.652112263s","start":"2026-02-20T18:30:32.758076Z","end":"2026-02-20T18:30:35.410188Z","steps":["trace[283338332] 'agreement among raft nodes before linearized reading' (duration: 2.651865566s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.410255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.75805Z","time spent":"2.652196266s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:30:35.410294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.267582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:35.410324Z","caller":"traceutil/trace.go:171","msg":"trace[1195041728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"771.329803ms","start":"2026-02-20T18:30:34.638985Z","end":"2026-02-20T18:30:35.410315Z","steps":["trace[1195041728] 'agreement among raft nodes before linearized reading' (duration: 771.283972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.41035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:34.638962Z","time spent":"771.382245ms","remote":"127.0.0.1:56006","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-20T18:30:35.410532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.632271574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:30:35.410568Z","caller":"traceutil/trace.go:171","msg":"trace[132719307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"2.632305215s","start":"2026-02-20T18:30:32.778249Z","end":"2026-02-20T18:30:35.410555Z","steps":["trace[132719307] 'agreement among raft nodes before linearized reading' (duration: 2.632212852s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.410595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.778226Z","time spent":"2.632362917s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:30:35.410693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.636954699s","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-20T18:30:35.410723Z","caller":"traceutil/trace.go:171","msg":"trace[31565222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"2.637034101s","start":"2026-02-20T18:30:32.77368Z","end":"2026-02-20T18:30:35.410715Z","steps":["trace[31565222] 'agreement among raft nodes before linearized reading' (duration: 2.636953909s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:35.410745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:32.773652Z","time spent":"2.637088303s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:30:51.819261Z","caller":"traceutil/trace.go:171","msg":"trace[261295692] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"226.901705ms","start":"2026-02-20T18:30:51.592325Z","end":"2026-02-20T18:30:51.819227Z","steps":["trace[261295692] 'read index received' (duration: 172.027731ms)","trace[261295692] 'applied index is now lower than readState.Index' (duration: 54.872734ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:51.819312Z","caller":"traceutil/trace.go:171","msg":"trace[1660559834] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"238.665609ms","start":"2026-02-20T18:30:51.580626Z","end":"2026-02-20T18:30:51.819291Z","steps":["trace[1660559834] 'process raft request' (duration: 183.786384ms)","trace[1660559834] 'compare' (duration: 54.70193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:51.819608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.823503ms","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-20T18:30:51.819625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.034674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:30:51.819624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.935474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:30:51.819661Z","caller":"traceutil/trace.go:171","msg":"trace[59265790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"226.879285ms","start":"2026-02-20T18:30:51.592769Z","end":"2026-02-20T18:30:51.819648Z","steps":["trace[59265790] 'agreement among raft nodes before linearized reading' (duration: 226.677819ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:51.819677Z","caller":"traceutil/trace.go:171","msg":"trace[1939785184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"147.128577ms","start":"2026-02-20T18:30:51.672531Z","end":"2026-02-20T18:30:51.819659Z","steps":["trace[1939785184] 'agreement among raft nodes before linearized reading' (duration: 146.945822ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:51.819706Z","caller":"traceutil/trace.go:171","msg":"trace[1297449740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"140.044768ms","start":"2026-02-20T18:30:51.679642Z","end":"2026-02-20T18:30:51.819687Z","steps":["trace[1297449740] 'agreement among raft nodes before linearized reading' (duration: 139.832462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:51.81945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.104841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:51.819857Z","caller":"traceutil/trace.go:171","msg":"trace[722682977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"227.555593ms","start":"2026-02-20T18:30:51.592278Z","end":"2026-02-20T18:30:51.819834Z","steps":["trace[722682977] 'agreement among raft nodes before linearized reading' (duration: 227.116901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:52.161696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.100965ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:52.161809Z","caller":"traceutil/trace.go:171","msg":"trace[1796530898] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:940; }","duration":"111.227919ms","start":"2026-02-20T18:30:52.050559Z","end":"2026-02-20T18:30:52.161787Z","steps":["trace[1796530898] 'range keys from in-memory index tree' (duration: 111.082005ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:54.076763Z","caller":"traceutil/trace.go:171","msg":"trace[1168306089] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"483.615447ms","start":"2026-02-20T18:30:53.593125Z","end":"2026-02-20T18:30:54.07674Z","steps":["trace[1168306089] 'read index received' (duration: 483.400331ms)","trace[1168306089] 'applied index is now lower than readState.Index' (duration: 214.306µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:54.077145Z","caller":"traceutil/trace.go:171","msg":"trace[271208365] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"561.827534ms","start":"2026-02-20T18:30:53.515305Z","end":"2026-02-20T18:30:54.077133Z","steps":["trace[271208365] 'process raft request' (duration: 561.30494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.077247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:53.515285Z","time spent":"561.897086ms","remote":"127.0.0.1:56298","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-20T18:30:54.07726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.12342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:54.077314Z","caller":"traceutil/trace.go:171","msg":"trace[1004977182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"484.181132ms","start":"2026-02-20T18:30:53.593118Z","end":"2026-02-20T18:30:54.077299Z","steps":["trace[1004977182] 'agreement among raft nodes before linearized reading' (duration: 484.0889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.077328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.639308ms","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":"warn","ts":"2026-02-20T18:30:54.077357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:53.593069Z","time spent":"484.280344ms","remote":"127.0.0.1:56006","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-20T18:30:54.077392Z","caller":"traceutil/trace.go:171","msg":"trace[721105667] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:941; }","duration":"193.767131ms","start":"2026-02-20T18:30:53.88361Z","end":"2026-02-20T18:30:54.077377Z","steps":["trace[721105667] 'agreement among raft nodes before linearized reading' (duration: 193.646018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.077381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.681008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:30:54.077468Z","caller":"traceutil/trace.go:171","msg":"trace[1313864719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"190.798372ms","start":"2026-02-20T18:30:53.886651Z","end":"2026-02-20T18:30:54.07745Z","steps":["trace[1313864719] 'agreement among raft nodes before linearized reading' (duration: 190.589616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.07747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.255942ms","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-20T18:30:54.07754Z","caller":"traceutil/trace.go:171","msg":"trace[460037930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"243.373105ms","start":"2026-02-20T18:30:53.834149Z","end":"2026-02-20T18:30:54.077522Z","steps":["trace[460037930] 'agreement among raft nodes before linearized reading' (duration: 243.145629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.077606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.220144ms","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-20T18:30:54.077665Z","caller":"traceutil/trace.go:171","msg":"trace[2019319520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"254.281276ms","start":"2026-02-20T18:30:53.823346Z","end":"2026-02-20T18:30:54.077627Z","steps":["trace[2019319520] 'agreement among raft nodes before linearized reading' (duration: 253.799723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.078001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.386217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:30:54.078101Z","caller":"traceutil/trace.go:171","msg":"trace[1928649142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"191.512321ms","start":"2026-02-20T18:30:53.886571Z","end":"2026-02-20T18:30:54.078084Z","steps":["trace[1928649142] 'agreement among raft nodes before linearized reading' (duration: 190.581516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.543224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.290384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:54.543534Z","caller":"traceutil/trace.go:171","msg":"trace[1380787703] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:980; }","duration":"255.61827ms","start":"2026-02-20T18:30:54.287899Z","end":"2026-02-20T18:30:54.543517Z","steps":["trace[1380787703] 'read index received' (duration: 36.955466ms)","trace[1380787703] 'applied index is now lower than readState.Index' (duration: 218.661884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:54.543634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.732894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:54.543663Z","caller":"traceutil/trace.go:171","msg":"trace[1653811517] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:945; }","duration":"255.804896ms","start":"2026-02-20T18:30:54.287848Z","end":"2026-02-20T18:30:54.543653Z","steps":["trace[1653811517] 'agreement among raft nodes before linearized reading' (duration: 255.720264ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:54.543844Z","caller":"traceutil/trace.go:171","msg":"trace[628058803] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"461.848965ms","start":"2026-02-20T18:30:54.081983Z","end":"2026-02-20T18:30:54.543832Z","steps":["trace[628058803] 'process raft request' (duration: 461.357202ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:54.543879Z","caller":"traceutil/trace.go:171","msg":"trace[1724158927] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"462.100031ms","start":"2026-02-20T18:30:54.081759Z","end":"2026-02-20T18:30:54.543859Z","steps":["trace[1724158927] 'process raft request' (duration: 243.089338ms)","trace[1724158927] 'compare' (duration: 218.026917ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:54.543893Z","caller":"traceutil/trace.go:171","msg":"trace[581373319] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"460.776606ms","start":"2026-02-20T18:30:54.083086Z","end":"2026-02-20T18:30:54.543863Z","steps":["trace[581373319] 'process raft request' (duration: 460.394156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.543927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:54.081973Z","time spent":"461.920186ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:30:54.543939Z","caller":"traceutil/trace.go:171","msg":"trace[531315509] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"461.560027ms","start":"2026-02-20T18:30:54.082349Z","end":"2026-02-20T18:30:54.543909Z","steps":["trace[531315509] 'process raft request' (duration: 461.094604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:54.543979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:54.081739Z","time spent":"462.183703ms","remote":"127.0.0.1:56298","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-20T18:30:54.544031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:54.083071Z","time spent":"460.885519ms","remote":"127.0.0.1:56298","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-20T18:30:54.544077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:54.082328Z","time spent":"461.6727ms","remote":"127.0.0.1:56298","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-20T18:30:55.822728Z","caller":"traceutil/trace.go:171","msg":"trace[1331339486] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"229.453353ms","start":"2026-02-20T18:30:55.593256Z","end":"2026-02-20T18:30:55.822709Z","steps":["trace[1331339486] 'read index received' (duration: 229.253418ms)","trace[1331339486] 'applied index is now lower than readState.Index' (duration: 199.285µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:55.8228Z","caller":"traceutil/trace.go:171","msg":"trace[309250681] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"676.483274ms","start":"2026-02-20T18:30:55.14627Z","end":"2026-02-20T18:30:55.822754Z","steps":["trace[309250681] 'process raft request' (duration: 676.285958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:55.82285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.585107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:55.822888Z","caller":"traceutil/trace.go:171","msg":"trace[1702352579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"229.68183ms","start":"2026-02-20T18:30:55.593197Z","end":"2026-02-20T18:30:55.822879Z","steps":["trace[1702352579] 'agreement among raft nodes before linearized reading' (duration: 229.585497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:55.823006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:55.146252Z","time spent":"676.634248ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:57.232063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149471797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:30:57.232134Z","caller":"traceutil/trace.go:171","msg":"trace[1877553178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"1.149600261s","start":"2026-02-20T18:30:56.082517Z","end":"2026-02-20T18:30:57.232117Z","steps":["trace[1877553178] 'range keys from in-memory index tree' (duration: 1.149304472s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:57.232172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.082495Z","time spent":"1.149667832s","remote":"127.0.0.1:56298","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-20T18:30:57.232291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.069853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:30:57.232325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.559312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:57.232365Z","caller":"traceutil/trace.go:171","msg":"trace[892362719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"685.168946ms","start":"2026-02-20T18:30:56.547179Z","end":"2026-02-20T18:30:57.232348Z","steps":["trace[892362719] 'range keys from in-memory index tree' (duration: 684.94314ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:57.232377Z","caller":"traceutil/trace.go:171","msg":"trace[156395776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"638.664564ms","start":"2026-02-20T18:30:56.593698Z","end":"2026-02-20T18:30:57.232363Z","steps":["trace[156395776] 'range keys from in-memory index tree' (duration: 638.506411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:57.232405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.547172Z","time spent":"685.225057ms","remote":"127.0.0.1:56298","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-20T18:30:57.23241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.593689Z","time spent":"638.714356ms","remote":"127.0.0.1:56006","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-20T18:30:57.232422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.750061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:30:57.232507Z","caller":"traceutil/trace.go:171","msg":"trace[599959222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"685.876515ms","start":"2026-02-20T18:30:56.546609Z","end":"2026-02-20T18:30:57.232485Z","steps":["trace[599959222] 'range keys from in-memory index tree' (duration: 685.570216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:57.23252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.878458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:30:57.23255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.546589Z","time spent":"685.950527ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:30:57.232577Z","caller":"traceutil/trace.go:171","msg":"trace[1771365772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"684.95334ms","start":"2026-02-20T18:30:56.547611Z","end":"2026-02-20T18:30:57.232564Z","steps":["trace[1771365772] 'range keys from in-memory index tree' (duration: 684.789356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:57.232613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.547603Z","time spent":"685.002981ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:30:57.232726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.886665ms","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-20T18:30:57.232778Z","caller":"traceutil/trace.go:171","msg":"trace[1079038382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"685.950197ms","start":"2026-02-20T18:30:56.546812Z","end":"2026-02-20T18:30:57.232762Z","steps":["trace[1079038382] 'range keys from in-memory index tree' (duration: 685.808963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:57.232821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:56.546806Z","time spent":"686.003418ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:30:58.094665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:58.595307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:30:58.890245Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.65397374s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:30:58.890679Z","caller":"traceutil/trace.go:171","msg":"trace[55107876] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.654492544s","start":"2026-02-20T18:30:57.236165Z","end":"2026-02-20T18:30:58.890657Z","steps":["trace[55107876] 'process raft request' (duration: 1.65434779s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:58.890812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.236142Z","time spent":"1.654601207s","remote":"127.0.0.1:56298","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-20T18:30:59.053679Z","caller":"traceutil/trace.go:171","msg":"trace[452904613] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"1.81658611s","start":"2026-02-20T18:30:57.237068Z","end":"2026-02-20T18:30:59.053654Z","steps":["trace[452904613] 'process raft request' (duration: 1.816511318s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:59.053695Z","caller":"traceutil/trace.go:171","msg":"trace[1290026301] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.817385851s","start":"2026-02-20T18:30:57.236273Z","end":"2026-02-20T18:30:59.053658Z","steps":["trace[1290026301] 'process raft request' (duration: 1.802837202s)","trace[1290026301] 'compare' (duration: 14.323323ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:59.053773Z","caller":"traceutil/trace.go:171","msg":"trace[562440106] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.816826636s","start":"2026-02-20T18:30:57.236912Z","end":"2026-02-20T18:30:59.053738Z","steps":["trace[562440106] 'process raft request' (duration: 1.816624871s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:59.053897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.236265Z","time spent":"1.817525164s","remote":"127.0.0.1:56298","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-20T18:30:59.053942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.236905Z","time spent":"1.816938699s","remote":"127.0.0.1:56298","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-20T18:30:59.054001Z","caller":"traceutil/trace.go:171","msg":"trace[643319580] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"1.816673332s","start":"2026-02-20T18:30:57.23731Z","end":"2026-02-20T18:30:59.053984Z","steps":["trace[643319580] 'process raft request' (duration: 1.816299242s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:59.053818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.237061Z","time spent":"1.816687212s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:59.054084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.237289Z","time spent":"1.816751284s","remote":"127.0.0.1:56298","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-20T18:30:59.054227Z","caller":"traceutil/trace.go:171","msg":"trace[589389764] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:986; }","duration":"1.459912851s","start":"2026-02-20T18:30:57.594301Z","end":"2026-02-20T18:30:59.054214Z","steps":["trace[589389764] 'read index received' (duration: 1.296308595s)","trace[589389764] 'applied index is now lower than readState.Index' (duration: 163.603316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:59.054331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460035255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:59.054364Z","caller":"traceutil/trace.go:171","msg":"trace[1646032957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"1.460096135s","start":"2026-02-20T18:30:57.594257Z","end":"2026-02-20T18:30:59.054353Z","steps":["trace[1646032957] 'agreement among raft nodes before linearized reading' (duration: 1.460046484s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:59.054343Z","caller":"traceutil/trace.go:171","msg":"trace[2093353476] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"523.256444ms","start":"2026-02-20T18:30:58.531055Z","end":"2026-02-20T18:30:59.054312Z","steps":["trace[2093353476] 'process raft request' (duration: 522.875664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:59.054392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:57.594237Z","time spent":"1.460148967s","remote":"127.0.0.1:56006","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-20T18:30:59.054417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039901693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:59.054482Z","caller":"traceutil/trace.go:171","msg":"trace[741999322] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:952; }","duration":"1.039990205s","start":"2026-02-20T18:30:58.014473Z","end":"2026-02-20T18:30:59.054463Z","steps":["trace[741999322] 'agreement among raft nodes before linearized reading' (duration: 1.039883493s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:59.054504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:58.531028Z","time spent":"523.399198ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:59.05453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:58.014453Z","time spent":"1.040063217s","remote":"127.0.0.1:51384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:31:00.380771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100518790456929,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:31:00.962792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.582499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:00.963412Z","caller":"traceutil/trace.go:171","msg":"trace[2073320521] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.083062315s","start":"2026-02-20T18:30:59.880335Z","end":"2026-02-20T18:31:00.963398Z","steps":["trace[2073320521] 'process raft request' (duration: 1.082552881s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:00.963517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:59.880307Z","time spent":"1.083133127s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:31:00.963425Z","caller":"traceutil/trace.go:171","msg":"trace[1114751327] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.112752757s","start":"2026-02-20T18:30:59.850638Z","end":"2026-02-20T18:31:00.963391Z","steps":["trace[1114751327] 'process raft request' (duration: 638.481749ms)","trace[1114751327] 'compare' (duration: 473.377104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:00.963803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:59.850598Z","time spent":"1.113025695s","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:31:01.089142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.127256ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:31:01.089587Z","caller":"traceutil/trace.go:171","msg":"trace[19722534] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:996; }","duration":"1.209554401s","start":"2026-02-20T18:30:59.880007Z","end":"2026-02-20T18:31:01.089561Z","steps":["trace[19722534] 'read index received' (duration: 609.180818ms)","trace[19722534] 'applied index is now lower than readState.Index' (duration: 600.371963ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:01.089598Z","caller":"traceutil/trace.go:171","msg":"trace[71285336] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"710.704688ms","start":"2026-02-20T18:31:00.378871Z","end":"2026-02-20T18:31:01.089575Z","steps":["trace[71285336] 'process raft request' (duration: 710.42735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:01.089846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:00.378845Z","time spent":"710.935633ms","remote":"127.0.0.1:56298","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-20T18:31:01.245295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.888891ms","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-20T18:31:01.24539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.681726ms","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-20T18:31:01.245393Z","caller":"traceutil/trace.go:171","msg":"trace[875728730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"186.041945ms","start":"2026-02-20T18:31:01.059318Z","end":"2026-02-20T18:31:01.24536Z","steps":["trace[875728730] 'agreement among raft nodes before linearized reading' (duration: 30.404921ms)","trace[875728730] 'range keys from in-memory index tree' (duration: 155.414707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:01.245402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.432505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-20T18:31:01.245433Z","caller":"traceutil/trace.go:171","msg":"trace[1129687797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"185.736326ms","start":"2026-02-20T18:31:01.059679Z","end":"2026-02-20T18:31:01.245416Z","steps":["trace[1129687797] 'agreement among raft nodes before linearized reading' (duration: 30.041621ms)","trace[1129687797] 'range keys from in-memory index tree' (duration: 155.622653ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:01.245471Z","caller":"traceutil/trace.go:171","msg":"trace[188560633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"346.554959ms","start":"2026-02-20T18:31:00.898897Z","end":"2026-02-20T18:31:01.245452Z","steps":["trace[188560633] 'agreement among raft nodes before linearized reading' (duration: 190.847394ms)","trace[188560633] 'range keys from in-memory index tree' (duration: 155.534771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:01.245511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:00.898879Z","time spent":"346.622841ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:31:01.245567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.833279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:31:01.245586Z","caller":"traceutil/trace.go:171","msg":"trace[159639040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"185.867161ms","start":"2026-02-20T18:31:01.059712Z","end":"2026-02-20T18:31:01.245579Z","steps":["trace[159639040] 'agreement among raft nodes before linearized reading' (duration: 30.043762ms)","trace[159639040] 'range keys from in-memory index tree' (duration: 155.761877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:01.245619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.190476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:31:01.245311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.955753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:31:01.245654Z","caller":"traceutil/trace.go:171","msg":"trace[883077630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"186.360874ms","start":"2026-02-20T18:31:01.059287Z","end":"2026-02-20T18:31:01.245648Z","steps":["trace[883077630] 'agreement among raft nodes before linearized reading' (duration: 30.447123ms)","trace[883077630] 'range keys from in-memory index tree' (duration: 155.444148ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:01.245654Z","caller":"traceutil/trace.go:171","msg":"trace[483303188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"652.241547ms","start":"2026-02-20T18:31:00.593402Z","end":"2026-02-20T18:31:01.245643Z","steps":["trace[483303188] 'agreement among raft nodes before linearized reading' (duration: 496.304016ms)","trace[483303188] 'range keys from in-memory index tree' (duration: 155.891231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:01.245679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:00.593385Z","time spent":"652.287928ms","remote":"127.0.0.1:56006","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-20T18:31:01.245908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365896633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T18:31:01.245997Z","caller":"traceutil/trace.go:171","msg":"trace[1474944738] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:959; }","duration":"1.366083938s","start":"2026-02-20T18:30:59.879886Z","end":"2026-02-20T18:31:01.24597Z","steps":["trace[1474944738] 'agreement among raft nodes before linearized reading' (duration: 1.209763227s)","trace[1474944738] 'range keys from in-memory index tree' (duration: 156.087675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:01.246059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:59.879847Z","time spent":"1.366193751s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:31:01.415125Z","caller":"traceutil/trace.go:171","msg":"trace[169400244] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"165.055965ms","start":"2026-02-20T18:31:01.250041Z","end":"2026-02-20T18:31:01.415097Z","steps":["trace[169400244] 'process raft request' (duration: 164.894381ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:01.631658Z","caller":"traceutil/trace.go:171","msg":"trace[424374380] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"381.48301ms","start":"2026-02-20T18:31:01.250152Z","end":"2026-02-20T18:31:01.631635Z","steps":["trace[424374380] 'process raft request' (duration: 381.300125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:01.631806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.250133Z","time spent":"381.594363ms","remote":"127.0.0.1:56298","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-20T18:31:01.631897Z","caller":"traceutil/trace.go:171","msg":"trace[1507905928] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"379.726874ms","start":"2026-02-20T18:31:01.252161Z","end":"2026-02-20T18:31:01.631888Z","steps":["trace[1507905928] 'process raft request' (duration: 379.682523ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:01.631909Z","caller":"traceutil/trace.go:171","msg":"trace[1593497281] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"379.98738ms","start":"2026-02-20T18:31:01.2519Z","end":"2026-02-20T18:31:01.631887Z","steps":["trace[1593497281] 'process raft request' (duration: 379.697812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:01.631957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.252148Z","time spent":"379.777685ms","remote":"127.0.0.1:56298","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-20T18:31:01.631974Z","caller":"traceutil/trace.go:171","msg":"trace[1784091544] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"379.763855ms","start":"2026-02-20T18:31:01.252192Z","end":"2026-02-20T18:31:01.631956Z","steps":["trace[1784091544] 'process raft request' (duration: 379.672882ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:01.631976Z","caller":"traceutil/trace.go:171","msg":"trace[1442053054] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"379.969969ms","start":"2026-02-20T18:31:01.251975Z","end":"2026-02-20T18:31:01.631945Z","steps":["trace[1442053054] 'process raft request' (duration: 379.828336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:01.632012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.251885Z","time spent":"380.069583ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:01.632063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.252181Z","time spent":"379.835686ms","remote":"127.0.0.1:56298","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-20T18:31:01.632067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:01.251968Z","time spent":"380.056952ms","remote":"127.0.0.1:56298","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-20T18:31:03.822563Z","caller":"traceutil/trace.go:171","msg":"trace[185523580] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"336.46728ms","start":"2026-02-20T18:31:03.486051Z","end":"2026-02-20T18:31:03.822518Z","steps":["trace[185523580] 'read index received' (duration: 335.949856ms)","trace[185523580] 'applied index is now lower than readState.Index' (duration: 516.314µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:03.82299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.758937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:03.823043Z","caller":"traceutil/trace.go:171","msg":"trace[1111366354] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:966; }","duration":"337.009104ms","start":"2026-02-20T18:31:03.486018Z","end":"2026-02-20T18:31:03.823027Z","steps":["trace[1111366354] 'agreement among raft nodes before linearized reading' (duration: 336.621934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.8231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:03.486004Z","time spent":"337.082946ms","remote":"127.0.0.1:51364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:31:03.82332Z","caller":"traceutil/trace.go:171","msg":"trace[939873853] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"400.036616ms","start":"2026-02-20T18:31:03.423247Z","end":"2026-02-20T18:31:03.823284Z","steps":["trace[939873853] 'process raft request' (duration: 398.793523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.823538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.533998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:31:03.823611Z","caller":"traceutil/trace.go:171","msg":"trace[301627469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"186.647521ms","start":"2026-02-20T18:31:03.636948Z","end":"2026-02-20T18:31:03.823596Z","steps":["trace[301627469] 'agreement among raft nodes before linearized reading' (duration: 186.419555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.823551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:03.42323Z","time spent":"400.226962ms","remote":"127.0.0.1:56298","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-20T18:31:03.823979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.938599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:31:03.823989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.662029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:31:03.824027Z","caller":"traceutil/trace.go:171","msg":"trace[1440682002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"187.023241ms","start":"2026-02-20T18:31:03.636981Z","end":"2026-02-20T18:31:03.824004Z","steps":["trace[1440682002] 'agreement among raft nodes before linearized reading' (duration: 186.912488ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:03.82403Z","caller":"traceutil/trace.go:171","msg":"trace[1858775329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"184.70242ms","start":"2026-02-20T18:31:03.639315Z","end":"2026-02-20T18:31:03.824017Z","steps":["trace[1858775329] 'agreement among raft nodes before linearized reading' (duration: 184.560016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:03.824422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.055699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-20T18:31:03.824472Z","caller":"traceutil/trace.go:171","msg":"trace[1345456851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"185.11316ms","start":"2026-02-20T18:31:03.639339Z","end":"2026-02-20T18:31:03.824452Z","steps":["trace[1345456851] 'agreement among raft nodes before linearized reading' (duration: 184.923365ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:04.059732Z","caller":"traceutil/trace.go:171","msg":"trace[430614053] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"229.429073ms","start":"2026-02-20T18:31:03.830268Z","end":"2026-02-20T18:31:04.059697Z","steps":["trace[430614053] 'process raft request' (duration: 149.394626ms)","trace[430614053] 'compare' (duration: 79.889523ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:04.149854Z","caller":"traceutil/trace.go:171","msg":"trace[1464099915] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"318.972093ms","start":"2026-02-20T18:31:03.83086Z","end":"2026-02-20T18:31:04.149832Z","steps":["trace[1464099915] 'process raft request' (duration: 318.931362ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:04.150204Z","caller":"traceutil/trace.go:171","msg":"trace[1328731481] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"319.841575ms","start":"2026-02-20T18:31:03.830311Z","end":"2026-02-20T18:31:04.150153Z","steps":["trace[1328731481] 'process raft request' (duration: 319.339532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:04.150289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:03.830292Z","time spent":"319.955359ms","remote":"127.0.0.1:56298","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-20T18:31:04.150415Z","caller":"traceutil/trace.go:171","msg":"trace[1643594630] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"319.975799ms","start":"2026-02-20T18:31:03.830376Z","end":"2026-02-20T18:31:04.150352Z","steps":["trace[1643594630] 'process raft request' (duration: 319.379813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:04.15007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:03.83085Z","time spent":"319.133377ms","remote":"127.0.0.1:56298","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-20T18:31:04.150625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:03.830353Z","time spent":"320.145954ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:12.33833Z","caller":"traceutil/trace.go:171","msg":"trace[153962286] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"132.734621ms","start":"2026-02-20T18:31:12.205561Z","end":"2026-02-20T18:31:12.338296Z","steps":["trace[153962286] 'process raft request' (duration: 42.753911ms)","trace[153962286] 'compare' (duration: 89.732563ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:12.338586Z","caller":"traceutil/trace.go:171","msg":"trace[13299653] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"120.308089ms","start":"2026-02-20T18:31:12.218253Z","end":"2026-02-20T18:31:12.338561Z","steps":["trace[13299653] 'read index received' (duration: 30.076342ms)","trace[13299653] 'applied index is now lower than readState.Index' (duration: 90.229527ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:12.338615Z","caller":"traceutil/trace.go:171","msg":"trace[2071801507] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"132.140656ms","start":"2026-02-20T18:31:12.206442Z","end":"2026-02-20T18:31:12.338583Z","steps":["trace[2071801507] 'process raft request' (duration: 131.790626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:12.338862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.585957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-20T18:31:12.338962Z","caller":"traceutil/trace.go:171","msg":"trace[469623505] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1028; }","duration":"120.732221ms","start":"2026-02-20T18:31:12.218212Z","end":"2026-02-20T18:31:12.338944Z","steps":["trace[469623505] 'agreement among raft nodes before linearized reading' (duration: 120.436234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:12.339099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.911766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:31:12.339271Z","caller":"traceutil/trace.go:171","msg":"trace[376382883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"118.122021ms","start":"2026-02-20T18:31:12.221123Z","end":"2026-02-20T18:31:12.339245Z","steps":["trace[376382883] 'agreement among raft nodes before linearized reading' (duration: 117.67928ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:12.561103Z","caller":"traceutil/trace.go:171","msg":"trace[441836088] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"173.691726ms","start":"2026-02-20T18:31:12.387387Z","end":"2026-02-20T18:31:12.561079Z","steps":["trace[441836088] 'read index received' (duration: 44.21089ms)","trace[441836088] 'applied index is now lower than readState.Index' (duration: 129.479626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:12.56136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.954733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-20T18:31:12.561417Z","caller":"traceutil/trace.go:171","msg":"trace[1667221954] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"174.059855ms","start":"2026-02-20T18:31:12.387341Z","end":"2026-02-20T18:31:12.561401Z","steps":["trace[1667221954] 'agreement among raft nodes before linearized reading' (duration: 173.831409ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:12.561594Z","caller":"traceutil/trace.go:171","msg":"trace[2066435541] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"218.098591ms","start":"2026-02-20T18:31:12.343468Z","end":"2026-02-20T18:31:12.561566Z","steps":["trace[2066435541] 'process raft request' (duration: 215.81032ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:15.278062Z","caller":"traceutil/trace.go:171","msg":"trace[154033883] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"148.213895ms","start":"2026-02-20T18:31:15.129824Z","end":"2026-02-20T18:31:15.278038Z","steps":["trace[154033883] 'process raft request' (duration: 102.890566ms)","trace[154033883] 'compare' (duration: 45.110134ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:16.488081Z","caller":"traceutil/trace.go:171","msg":"trace[1535269437] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"133.844332ms","start":"2026-02-20T18:31:16.354213Z","end":"2026-02-20T18:31:16.488057Z","steps":["trace[1535269437] 'process raft request' (duration: 133.702899ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:16.617963Z","caller":"traceutil/trace.go:171","msg":"trace[1791937599] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"132.747403ms","start":"2026-02-20T18:31:16.485148Z","end":"2026-02-20T18:31:16.617896Z","steps":["trace[1791937599] 'read index received' (duration: 2.824135ms)","trace[1791937599] 'applied index is now lower than readState.Index' (duration: 129.921108ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:16.617972Z","caller":"traceutil/trace.go:171","msg":"trace[1007558595] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"261.992972ms","start":"2026-02-20T18:31:16.355941Z","end":"2026-02-20T18:31:16.617934Z","steps":["trace[1007558595] 'process raft request' (duration: 251.589544ms)","trace[1007558595] 'compare' (duration: 10.240153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:16.618219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.060661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:31:16.618379Z","caller":"traceutil/trace.go:171","msg":"trace[2096406250] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1044; }","duration":"133.240255ms","start":"2026-02-20T18:31:16.485091Z","end":"2026-02-20T18:31:16.618331Z","steps":["trace[2096406250] 'agreement among raft nodes before linearized reading' (duration: 132.953738ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:21.020824Z","caller":"traceutil/trace.go:171","msg":"trace[239037794] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"167.593233ms","start":"2026-02-20T18:31:20.853212Z","end":"2026-02-20T18:31:21.020805Z","steps":["trace[239037794] 'read index received' (duration: 98.1855ms)","trace[239037794] 'applied index is now lower than readState.Index' (duration: 69.406863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:21.020948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.719276ms","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-20T18:31:21.020976Z","caller":"traceutil/trace.go:171","msg":"trace[1060953279] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1064; }","duration":"167.781788ms","start":"2026-02-20T18:31:20.853182Z","end":"2026-02-20T18:31:21.020964Z","steps":["trace[1060953279] 'agreement among raft nodes before linearized reading' (duration: 167.696746ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:21.021172Z","caller":"traceutil/trace.go:171","msg":"trace[1268460088] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"222.534939ms","start":"2026-02-20T18:31:20.798628Z","end":"2026-02-20T18:31:21.021163Z","steps":["trace[1268460088] 'process raft request' (duration: 152.745727ms)","trace[1268460088] 'compare' (duration: 68.624931ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:22.944019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.583818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:22.944117Z","caller":"traceutil/trace.go:171","msg":"trace[1229047679] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"142.354349ms","start":"2026-02-20T18:31:22.801746Z","end":"2026-02-20T18:31:22.944101Z","steps":["trace[1229047679] 'process raft request' (duration: 27.611557ms)","trace[1229047679] 'compare' (duration: 114.382702ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:32:00.680887Z","caller":"traceutil/trace.go:171","msg":"trace[1648636489] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"173.241312ms","start":"2026-02-20T18:32:00.50756Z","end":"2026-02-20T18:32:00.680802Z","steps":["trace[1648636489] 'process raft request' (duration: 84.674613ms)","trace[1648636489] 'compare' (duration: 88.016464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:32:05.492286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.24239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:32:05.49249Z","caller":"traceutil/trace.go:171","msg":"trace[738910594] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"159.155185ms","start":"2026-02-20T18:32:05.333244Z","end":"2026-02-20T18:32:05.492399Z","steps":["trace[738910594] 'read index received' (duration: 650.728µs)","trace[738910594] 'applied index is now lower than readState.Index' (duration: 158.501607ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:32:05.492714Z","caller":"traceutil/trace.go:171","msg":"trace[284004466] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"208.840458ms","start":"2026-02-20T18:32:05.283853Z","end":"2026-02-20T18:32:05.492693Z","steps":["trace[284004466] 'process raft request' (duration: 50.043763ms)","trace[284004466] 'compare' (duration: 158.047795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:32:05.493038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.779462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:32:05.493116Z","caller":"traceutil/trace.go:171","msg":"trace[371747930] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1244; }","duration":"159.903586ms","start":"2026-02-20T18:32:05.333193Z","end":"2026-02-20T18:32:05.493097Z","steps":["trace[371747930] 'agreement among raft nodes before linearized reading' (duration: 159.613118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:32:05.818786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.775049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-20T18:32:05.818884Z","caller":"traceutil/trace.go:171","msg":"trace[660251788] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1245; }","duration":"129.936145ms","start":"2026-02-20T18:32:05.688924Z","end":"2026-02-20T18:32:05.81886Z","steps":["trace[660251788] 'range keys from in-memory index tree' (duration: 129.556044ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:05.948846Z","caller":"traceutil/trace.go:171","msg":"trace[927583883] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"125.139472ms","start":"2026-02-20T18:32:05.823683Z","end":"2026-02-20T18:32:05.948822Z","steps":["trace[927583883] 'process raft request' (duration: 125.005428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:32:41.085937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.942499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:32:41.086049Z","caller":"traceutil/trace.go:171","msg":"trace[1909663435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"132.110243ms","start":"2026-02-20T18:32:40.953915Z","end":"2026-02-20T18:32:41.086025Z","steps":["trace[1909663435] 'range keys from in-memory index tree' (duration: 131.780694ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:41.246919Z","caller":"traceutil/trace.go:171","msg":"trace[351361016] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"155.868615ms","start":"2026-02-20T18:32:41.091007Z","end":"2026-02-20T18:32:41.246876Z","steps":["trace[351361016] 'process raft request' (duration: 155.838304ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:41.246962Z","caller":"traceutil/trace.go:171","msg":"trace[547529122] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"156.085361ms","start":"2026-02-20T18:32:41.090855Z","end":"2026-02-20T18:32:41.24694Z","steps":["trace[547529122] 'process raft request' (duration: 155.895296ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:42.851204Z","caller":"traceutil/trace.go:171","msg":"trace[350924043] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"141.684246ms","start":"2026-02-20T18:32:42.709497Z","end":"2026-02-20T18:32:42.851181Z","steps":["trace[350924043] 'process raft request' (duration: 141.282685ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:48.08762Z","caller":"traceutil/trace.go:171","msg":"trace[599066707] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"121.839712ms","start":"2026-02-20T18:32:47.965747Z","end":"2026-02-20T18:32:48.087587Z","steps":["trace[599066707] 'read index received' (duration: 121.535134ms)","trace[599066707] 'applied index is now lower than readState.Index' (duration: 303.218µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:32:48.087865Z","caller":"traceutil/trace.go:171","msg":"trace[2128398272] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"150.652922ms","start":"2026-02-20T18:32:47.937191Z","end":"2026-02-20T18:32:48.087844Z","steps":["trace[2128398272] 'process raft request' (duration: 150.166269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:32:48.08785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.001267ms","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-20T18:32:48.087981Z","caller":"traceutil/trace.go:171","msg":"trace[1262583075] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1418; }","duration":"122.192362ms","start":"2026-02-20T18:32:47.965742Z","end":"2026-02-20T18:32:48.087934Z","steps":["trace[1262583075] 'agreement among raft nodes before linearized reading' (duration: 121.965486ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:49.4925Z","caller":"traceutil/trace.go:171","msg":"trace[1539203506] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"124.68668ms","start":"2026-02-20T18:32:49.367777Z","end":"2026-02-20T18:32:49.492464Z","steps":["trace[1539203506] 'process raft request' (duration: 58.920066ms)","trace[1539203506] 'compare' (duration: 65.362852ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:32:49.978734Z","caller":"traceutil/trace.go:171","msg":"trace[1216149098] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"210.041761ms","start":"2026-02-20T18:32:49.768666Z","end":"2026-02-20T18:32:49.978708Z","steps":["trace[1216149098] 'process raft request' (duration: 209.884467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:00.475735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.816763ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:33:00.475845Z","caller":"traceutil/trace.go:171","msg":"trace[446962360] linearizableReadLoop","detail":"{readStateIndex:1530; appliedIndex:1529; }","duration":"128.430456ms","start":"2026-02-20T18:33:00.347398Z","end":"2026-02-20T18:33:00.475828Z","steps":["trace[446962360] 'read index received' (duration: 21.397167ms)","trace[446962360] 'applied index is now lower than readState.Index' (duration: 107.031509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:33:00.476057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.633282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:33:00.476126Z","caller":"traceutil/trace.go:171","msg":"trace[1773374080] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1465; }","duration":"128.749096ms","start":"2026-02-20T18:33:00.347355Z","end":"2026-02-20T18:33:00.476104Z","steps":["trace[1773374080] 'agreement among raft nodes before linearized reading' (duration: 128.515379ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:29.853832Z","caller":"traceutil/trace.go:171","msg":"trace[1731594228] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"131.196523ms","start":"2026-02-20T18:33:29.722605Z","end":"2026-02-20T18:33:29.853801Z","steps":["trace[1731594228] 'process raft request' (duration: 100.947632ms)","trace[1731594228] 'compare' (duration: 30.141438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:33:30.116612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.57009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:33:30.116755Z","caller":"traceutil/trace.go:171","msg":"trace[1633736616] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"168.122996ms","start":"2026-02-20T18:33:29.948607Z","end":"2026-02-20T18:33:30.11673Z","steps":["trace[1633736616] 'process raft request' (duration: 27.32965ms)","trace[1633736616] 'compare' (duration: 140.373705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:33:33.85771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.891713ms","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-20T18:33:33.857797Z","caller":"traceutil/trace.go:171","msg":"trace[876665862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1601; }","duration":"139.014637ms","start":"2026-02-20T18:33:33.71876Z","end":"2026-02-20T18:33:33.857775Z","steps":["trace[876665862] 'range keys from in-memory index tree' (duration: 138.716799ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:40.408001Z","caller":"traceutil/trace.go:171","msg":"trace[420373389] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"141.095874ms","start":"2026-02-20T18:33:40.266841Z","end":"2026-02-20T18:33:40.407937Z","steps":["trace[420373389] 'process raft request' (duration: 56.954834ms)","trace[420373389] 'compare' (duration: 83.933835ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:44.559653Z","caller":"traceutil/trace.go:171","msg":"trace[122963404] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"132.92451ms","start":"2026-02-20T18:33:44.426698Z","end":"2026-02-20T18:33:44.559622Z","steps":["trace[122963404] 'process raft request' (duration: 132.507309ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:05.735356Z","caller":"traceutil/trace.go:171","msg":"trace[482497567] linearizableReadLoop","detail":"{readStateIndex:1836; appliedIndex:1835; }","duration":"261.962143ms","start":"2026-02-20T18:34:05.473362Z","end":"2026-02-20T18:34:05.735325Z","steps":["trace[482497567] 'read index received' (duration: 261.796468ms)","trace[482497567] 'applied index is now lower than readState.Index' (duration: 164.065µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:05.735495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.108277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:05.735553Z","caller":"traceutil/trace.go:171","msg":"trace[1550040988] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1757; }","duration":"262.202449ms","start":"2026-02-20T18:34:05.473337Z","end":"2026-02-20T18:34:05.73554Z","steps":["trace[1550040988] 'agreement among raft nodes before linearized reading' (duration: 262.082956ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:05.735393Z","caller":"traceutil/trace.go:171","msg":"trace[512561321] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"298.788943ms","start":"2026-02-20T18:34:05.436579Z","end":"2026-02-20T18:34:05.735368Z","steps":["trace[512561321] 'process raft request' (duration: 298.551657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:05.735884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.482953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:05.735922Z","caller":"traceutil/trace.go:171","msg":"trace[621017536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1757; }","duration":"142.558685ms","start":"2026-02-20T18:34:05.593354Z","end":"2026-02-20T18:34:05.735913Z","steps":["trace[621017536] 'agreement among raft nodes before linearized reading' (duration: 142.506943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:05.736132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.779178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:34:05.736169Z","caller":"traceutil/trace.go:171","msg":"trace[1575210704] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1757; }","duration":"107.85332ms","start":"2026-02-20T18:34:05.628304Z","end":"2026-02-20T18:34:05.736157Z","steps":["trace[1575210704] 'agreement among raft nodes before linearized reading' (duration: 107.746397ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:07.956222Z","caller":"traceutil/trace.go:171","msg":"trace[1366380888] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"160.646991ms","start":"2026-02-20T18:34:07.795544Z","end":"2026-02-20T18:34:07.956191Z","steps":["trace[1366380888] 'process raft request' (duration: 160.491007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.247655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.769312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:34:08.247737Z","caller":"traceutil/trace.go:171","msg":"trace[492283548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1767; }","duration":"118.942886ms","start":"2026-02-20T18:34:08.128775Z","end":"2026-02-20T18:34:08.247718Z","steps":["trace[492283548] 'range keys from in-memory index tree' (duration: 118.542826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.247724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.969247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:34:08.247789Z","caller":"traceutil/trace.go:171","msg":"trace[1138701232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1767; }","duration":"119.102331ms","start":"2026-02-20T18:34:08.128672Z","end":"2026-02-20T18:34:08.247775Z","steps":["trace[1138701232] 'range keys from in-memory index tree' (duration: 118.811553ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.40479Z","caller":"traceutil/trace.go:171","msg":"trace[2020636480] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"153.143027ms","start":"2026-02-20T18:34:08.251616Z","end":"2026-02-20T18:34:08.404759Z","steps":["trace[2020636480] 'process raft request' (duration: 152.672625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.580675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.604745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:34:08.581032Z","caller":"traceutil/trace.go:171","msg":"trace[1409967175] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"328.827607ms","start":"2026-02-20T18:34:08.252179Z","end":"2026-02-20T18:34:08.581006Z","steps":["trace[1409967175] 'process raft request' (duration: 199.814661ms)","trace[1409967175] 'compare' (duration: 128.39804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:08.581163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:08.252161Z","time spent":"328.933259ms","remote":"127.0.0.1:56298","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-20T18:34:08.581224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.612328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-20T18:34:08.581282Z","caller":"traceutil/trace.go:171","msg":"trace[1570543312] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1769; }","duration":"260.721041ms","start":"2026-02-20T18:34:08.320544Z","end":"2026-02-20T18:34:08.581265Z","steps":["trace[1570543312] 'agreement among raft nodes before linearized reading' (duration: 260.533006ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.580849Z","caller":"traceutil/trace.go:171","msg":"trace[1323168317] linearizableReadLoop","detail":"{readStateIndex:1848; appliedIndex:1846; }","duration":"260.244029ms","start":"2026-02-20T18:34:08.320588Z","end":"2026-02-20T18:34:08.580832Z","steps":["trace[1323168317] 'read index received' (duration: 83.650786ms)","trace[1323168317] 'applied index is now lower than readState.Index' (duration: 176.592073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:08.581729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.420898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T18:34:08.581807Z","caller":"traceutil/trace.go:171","msg":"trace[1787998981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1769; }","duration":"131.549721ms","start":"2026-02-20T18:34:08.45024Z","end":"2026-02-20T18:34:08.58179Z","steps":["trace[1787998981] 'agreement among raft nodes before linearized reading' (duration: 131.351126ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:08.805949Z","caller":"traceutil/trace.go:171","msg":"trace[1924430824] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1849; }","duration":"121.106482ms","start":"2026-02-20T18:34:08.684813Z","end":"2026-02-20T18:34:08.80592Z","steps":["trace[1924430824] 'read index received' (duration: 117.862429ms)","trace[1924430824] 'applied index is now lower than readState.Index' (duration: 3.242913ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:08.806012Z","caller":"traceutil/trace.go:171","msg":"trace[365163389] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"219.858458ms","start":"2026-02-20T18:34:08.586121Z","end":"2026-02-20T18:34:08.805979Z","steps":["trace[365163389] 'process raft request' (duration: 216.498441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:08.806222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.390689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:34:08.806879Z","caller":"traceutil/trace.go:171","msg":"trace[1227372681] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1771; }","duration":"122.084167ms","start":"2026-02-20T18:34:08.684778Z","end":"2026-02-20T18:34:08.806862Z","steps":["trace[1227372681] 'agreement among raft nodes before linearized reading' (duration: 121.265526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:10.758508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.290948ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:34:10.758975Z","caller":"traceutil/trace.go:171","msg":"trace[1318133548] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1853; }","duration":"174.107218ms","start":"2026-02-20T18:34:10.584847Z","end":"2026-02-20T18:34:10.758954Z","steps":["trace[1318133548] 'read index received' (duration: 27.102008ms)","trace[1318133548] 'applied index is now lower than readState.Index' (duration: 147.00367ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:10.759088Z","caller":"traceutil/trace.go:171","msg":"trace[1479596503] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"346.750589ms","start":"2026-02-20T18:34:10.412325Z","end":"2026-02-20T18:34:10.759075Z","steps":["trace[1479596503] 'process raft request' (duration: 346.288357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:10.759275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:10.412256Z","time spent":"346.870242ms","remote":"127.0.0.1:56298","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-20T18:34:10.759286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.993859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:34:10.759438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.585031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:34:10.759469Z","caller":"traceutil/trace.go:171","msg":"trace[577487289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1775; }","duration":"166.213265ms","start":"2026-02-20T18:34:10.593231Z","end":"2026-02-20T18:34:10.759444Z","steps":["trace[577487289] 'agreement among raft nodes before linearized reading' (duration: 165.960788ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:10.7596Z","caller":"traceutil/trace.go:171","msg":"trace[1121257067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1775; }","duration":"174.697623ms","start":"2026-02-20T18:34:10.584811Z","end":"2026-02-20T18:34:10.759509Z","steps":["trace[1121257067] 'agreement among raft nodes before linearized reading' (duration: 174.371015ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:10.91458Z","caller":"traceutil/trace.go:171","msg":"trace[1328566061] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"150.91091ms","start":"2026-02-20T18:34:10.763646Z","end":"2026-02-20T18:34:10.914557Z","steps":["trace[1328566061] 'process raft request' (duration: 150.731116ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:10.954798Z","caller":"traceutil/trace.go:171","msg":"trace[1008630831] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1855; }","duration":"143.239423ms","start":"2026-02-20T18:34:10.811535Z","end":"2026-02-20T18:34:10.954775Z","steps":["trace[1008630831] 'read index received' (duration: 102.815721ms)","trace[1008630831] 'applied index is now lower than readState.Index' (duration: 40.422422ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:10.954957Z","caller":"traceutil/trace.go:171","msg":"trace[1213135654] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"190.990603ms","start":"2026-02-20T18:34:10.763956Z","end":"2026-02-20T18:34:10.954947Z","steps":["trace[1213135654] 'process raft request' (duration: 190.676265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:10.955301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.433116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-20T18:34:10.955374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.800297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T18:34:10.955415Z","caller":"traceutil/trace.go:171","msg":"trace[556191760] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1777; }","duration":"110.593861ms","start":"2026-02-20T18:34:10.844798Z","end":"2026-02-20T18:34:10.955392Z","steps":["trace[556191760] 'agreement among raft nodes before linearized reading' (duration: 110.257852ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:10.95548Z","caller":"traceutil/trace.go:171","msg":"trace[1775989778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1777; }","duration":"143.970601ms","start":"2026-02-20T18:34:10.811483Z","end":"2026-02-20T18:34:10.955454Z","steps":["trace[1775989778] 'agreement among raft nodes before linearized reading' (duration: 143.642713ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:11.070717Z","caller":"traceutil/trace.go:171","msg":"trace[76850134] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"110.751745ms","start":"2026-02-20T18:34:10.959924Z","end":"2026-02-20T18:34:11.070676Z","steps":["trace[76850134] 'process raft request' (duration: 89.718533ms)","trace[76850134] 'compare' (duration: 20.756405ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:11.709673Z","caller":"traceutil/trace.go:171","msg":"trace[929872325] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"150.670334ms","start":"2026-02-20T18:34:11.558983Z","end":"2026-02-20T18:34:11.709654Z","steps":["trace[929872325] 'process raft request' (duration: 150.550511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:11.986344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.492132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:34:11.986446Z","caller":"traceutil/trace.go:171","msg":"trace[1259361824] linearizableReadLoop","detail":"{readStateIndex:1863; appliedIndex:1862; }","duration":"159.184983ms","start":"2026-02-20T18:34:11.827241Z","end":"2026-02-20T18:34:11.986426Z","steps":["trace[1259361824] 'read index received' (duration: 31.731µs)","trace[1259361824] 'applied index is now lower than readState.Index' (duration: 159.151842ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:11.986649Z","caller":"traceutil/trace.go:171","msg":"trace[649083551] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"238.212201ms","start":"2026-02-20T18:34:11.748419Z","end":"2026-02-20T18:34:11.986631Z","steps":["trace[649083551] 'process raft request' (duration: 38.365849ms)","trace[649083551] 'compare' (duration: 199.159814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:11.986668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.413659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-20T18:34:11.986711Z","caller":"traceutil/trace.go:171","msg":"trace[2050963363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1783; }","duration":"159.490041ms","start":"2026-02-20T18:34:11.827208Z","end":"2026-02-20T18:34:11.986698Z","steps":["trace[2050963363] 'agreement among raft nodes before linearized reading' (duration: 159.273846ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:12.589921Z","caller":"traceutil/trace.go:171","msg":"trace[1351474233] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"143.715775ms","start":"2026-02-20T18:34:12.446182Z","end":"2026-02-20T18:34:12.589898Z","steps":["trace[1351474233] 'process raft request' (duration: 143.550181ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:13.031741Z","caller":"traceutil/trace.go:171","msg":"trace[248459482] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"113.542397ms","start":"2026-02-20T18:34:12.918182Z","end":"2026-02-20T18:34:13.031724Z","steps":["trace[248459482] 'read index received' (duration: 13.600711ms)","trace[248459482] 'applied index is now lower than readState.Index' (duration: 99.940766ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:13.031829Z","caller":"traceutil/trace.go:171","msg":"trace[242327010] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"264.361855ms","start":"2026-02-20T18:34:12.767458Z","end":"2026-02-20T18:34:13.03182Z","steps":["trace[242327010] 'process raft request' (duration: 164.408768ms)","trace[242327010] 'compare' (duration: 99.774522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:13.032182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.991729ms","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-20T18:34:13.032211Z","caller":"traceutil/trace.go:171","msg":"trace[1882695940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1789; }","duration":"114.03427ms","start":"2026-02-20T18:34:12.918171Z","end":"2026-02-20T18:34:13.032205Z","steps":["trace[1882695940] 'agreement among raft nodes before linearized reading' (duration: 113.929387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:14.538878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.3092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:34:14.539016Z","caller":"traceutil/trace.go:171","msg":"trace[1407580378] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"368.266444ms","start":"2026-02-20T18:34:14.170731Z","end":"2026-02-20T18:34:14.538997Z","steps":["trace[1407580378] 'process raft request' (duration: 100.762488ms)","trace[1407580378] 'compare' (duration: 266.951411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:14.539069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:14.170714Z","time spent":"368.333796ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:34:15.222655Z","caller":"traceutil/trace.go:171","msg":"trace[476127037] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"151.504616ms","start":"2026-02-20T18:34:15.071129Z","end":"2026-02-20T18:34:15.222634Z","steps":["trace[476127037] 'read index received' (duration: 151.336871ms)","trace[476127037] 'applied index is now lower than readState.Index' (duration: 166.725µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:15.222741Z","caller":"traceutil/trace.go:171","msg":"trace[1749718854] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"181.438817ms","start":"2026-02-20T18:34:15.041268Z","end":"2026-02-20T18:34:15.222706Z","steps":["trace[1749718854] 'process raft request' (duration: 181.222542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.222878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.735391ms","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-20T18:34:15.222968Z","caller":"traceutil/trace.go:171","msg":"trace[2019470194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1798; }","duration":"151.869794ms","start":"2026-02-20T18:34:15.071072Z","end":"2026-02-20T18:34:15.222942Z","steps":["trace[2019470194] 'agreement among raft nodes before linearized reading' (duration: 151.69024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.223239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.36163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-20T18:34:15.22329Z","caller":"traceutil/trace.go:171","msg":"trace[501812162] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1798; }","duration":"116.429121ms","start":"2026-02-20T18:34:15.10685Z","end":"2026-02-20T18:34:15.223279Z","steps":["trace[501812162] 'agreement among raft nodes before linearized reading' (duration: 116.308968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.223328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.357362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-20T18:34:15.223441Z","caller":"traceutil/trace.go:171","msg":"trace[345977497] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1798; }","duration":"125.495385ms","start":"2026-02-20T18:34:15.097927Z","end":"2026-02-20T18:34:15.223423Z","steps":["trace[345977497] 'agreement among raft nodes before linearized reading' (duration: 125.284279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.223475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.987088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T18:34:15.223665Z","caller":"traceutil/trace.go:171","msg":"trace[1958424560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1798; }","duration":"140.358158ms","start":"2026-02-20T18:34:15.083286Z","end":"2026-02-20T18:34:15.223644Z","steps":["trace[1958424560] 'agreement among raft nodes before linearized reading' (duration: 139.876605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.553508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.491106ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-20T18:34:15.553879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.224499Z","time spent":"329.372802ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:34:15.553958Z","caller":"traceutil/trace.go:171","msg":"trace[615918577] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"327.404521ms","start":"2026-02-20T18:34:15.226525Z","end":"2026-02-20T18:34:15.55393Z","steps":["trace[615918577] 'process raft request' (duration: 327.097613ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:15.554005Z","caller":"traceutil/trace.go:171","msg":"trace[1594381030] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"326.348295ms","start":"2026-02-20T18:34:15.227638Z","end":"2026-02-20T18:34:15.553986Z","steps":["trace[1594381030] 'process raft request' (duration: 326.108849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.554097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.226504Z","time spent":"327.509894ms","remote":"127.0.0.1:56298","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-20T18:34:15.554119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.227627Z","time spent":"326.432647ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:34:15.554133Z","caller":"traceutil/trace.go:171","msg":"trace[1010288475] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"326.152079ms","start":"2026-02-20T18:34:15.227921Z","end":"2026-02-20T18:34:15.554073Z","steps":["trace[1010288475] 'process raft request' (duration: 326.009125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:15.55448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.227912Z","time spent":"326.266013ms","remote":"127.0.0.1:56298","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-20T18:34:15.964128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.339199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:34:15.964209Z","caller":"traceutil/trace.go:171","msg":"trace[2131429221] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"553.903891ms","start":"2026-02-20T18:34:15.410291Z","end":"2026-02-20T18:34:15.964195Z","steps":["trace[2131429221] 'process raft request' (duration: 411.419808ms)","trace[2131429221] 'compare' (duration: 142.171465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:15.964251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.410273Z","time spent":"553.959313ms","remote":"127.0.0.1:56298","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-20T18:34:16.322144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.662093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:34:16.322275Z","caller":"traceutil/trace.go:171","msg":"trace[1582455507] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1882; }","duration":"729.496148ms","start":"2026-02-20T18:34:15.592757Z","end":"2026-02-20T18:34:16.322253Z","steps":["trace[1582455507] 'read index received' (duration: 228.947864ms)","trace[1582455507] 'applied index is now lower than readState.Index' (duration: 500.546414ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:16.322392Z","caller":"traceutil/trace.go:171","msg":"trace[551212186] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"766.898412ms","start":"2026-02-20T18:34:15.555476Z","end":"2026-02-20T18:34:16.322374Z","steps":["trace[551212186] 'process raft request' (duration: 532.89547ms)","trace[551212186] 'compare' (duration: 233.337945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:16.322487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.555454Z","time spent":"766.984154ms","remote":"127.0.0.1:56058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:34:16.322772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.128437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-20T18:34:16.322832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.376297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:34:16.322803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.00073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:16.322841Z","caller":"traceutil/trace.go:171","msg":"trace[405229346] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1803; }","duration":"364.220959ms","start":"2026-02-20T18:34:15.958601Z","end":"2026-02-20T18:34:16.322821Z","steps":["trace[405229346] 'agreement among raft nodes before linearized reading' (duration: 363.977153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:16.322892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.958587Z","time spent":"364.292152ms","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:34:16.322896Z","caller":"traceutil/trace.go:171","msg":"trace[283005338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1803; }","duration":"730.162474ms","start":"2026-02-20T18:34:15.592709Z","end":"2026-02-20T18:34:16.322872Z","steps":["trace[283005338] 'agreement among raft nodes before linearized reading' (duration: 729.954569ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:16.322991Z","caller":"traceutil/trace.go:171","msg":"trace[862514614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1803; }","duration":"254.526501ms","start":"2026-02-20T18:34:16.068328Z","end":"2026-02-20T18:34:16.322855Z","steps":["trace[862514614] 'agreement among raft nodes before linearized reading' (duration: 254.361077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:16.322993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:34:15.592692Z","time spent":"730.292268ms","remote":"127.0.0.1:56006","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-20T18:34:16.323386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.874297ms","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-20T18:34:16.323546Z","caller":"traceutil/trace.go:171","msg":"trace[517479361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1803; }","duration":"161.083432ms","start":"2026-02-20T18:34:16.162444Z","end":"2026-02-20T18:34:16.323527Z","steps":["trace[517479361] 'agreement among raft nodes before linearized reading' (duration: 160.760164ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:16.602103Z","caller":"traceutil/trace.go:171","msg":"trace[1545338516] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1885; }","duration":"188.837359ms","start":"2026-02-20T18:34:16.413247Z","end":"2026-02-20T18:34:16.602084Z","steps":["trace[1545338516] 'read index received' (duration: 168.045003ms)","trace[1545338516] 'applied index is now lower than readState.Index' (duration: 20.791346ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:16.60218Z","caller":"traceutil/trace.go:171","msg":"trace[448757093] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"274.873507ms","start":"2026-02-20T18:34:16.327273Z","end":"2026-02-20T18:34:16.602146Z","steps":["trace[448757093] 'process raft request' (duration: 254.092472ms)","trace[448757093] 'compare' (duration: 20.56104ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:16.60222Z","caller":"traceutil/trace.go:171","msg":"trace[1945078748] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"274.844617ms","start":"2026-02-20T18:34:16.327366Z","end":"2026-02-20T18:34:16.60221Z","steps":["trace[1945078748] 'process raft request' (duration: 274.657452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:16.602406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.030796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-20T18:34:16.602518Z","caller":"traceutil/trace.go:171","msg":"trace[592277790] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1806; }","duration":"186.19498ms","start":"2026-02-20T18:34:16.416304Z","end":"2026-02-20T18:34:16.602499Z","steps":["trace[592277790] 'agreement among raft nodes before linearized reading' (duration: 186.002325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:16.602606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.247962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-20T18:34:16.602694Z","caller":"traceutil/trace.go:171","msg":"trace[2080030937] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1806; }","duration":"205.383836ms","start":"2026-02-20T18:34:16.397293Z","end":"2026-02-20T18:34:16.602676Z","steps":["trace[2080030937] 'agreement among raft nodes before linearized reading' (duration: 205.117339ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:17.824263Z","caller":"traceutil/trace.go:171","msg":"trace[443217031] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"225.77962ms","start":"2026-02-20T18:34:17.598458Z","end":"2026-02-20T18:34:17.824238Z","steps":["trace[443217031] 'process raft request' (duration: 225.618156ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:17.824544Z","caller":"traceutil/trace.go:171","msg":"trace[573095253] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"226.026616ms","start":"2026-02-20T18:34:17.598484Z","end":"2026-02-20T18:34:17.824511Z","steps":["trace[573095253] 'process raft request' (duration: 225.716978ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:17.830734Z","caller":"traceutil/trace.go:171","msg":"trace[699553231] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"232.132694ms","start":"2026-02-20T18:34:17.598581Z","end":"2026-02-20T18:34:17.830713Z","steps":["trace[699553231] 'process raft request' (duration: 232.00121ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:17.830988Z","caller":"traceutil/trace.go:171","msg":"trace[370173095] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"230.406069ms","start":"2026-02-20T18:34:17.600549Z","end":"2026-02-20T18:34:17.830955Z","steps":["trace[370173095] 'process raft request' (duration: 230.129062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:18.074335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.639633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-20T18:34:18.074392Z","caller":"traceutil/trace.go:171","msg":"trace[48512530] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:1812; }","duration":"143.783556ms","start":"2026-02-20T18:34:17.930597Z","end":"2026-02-20T18:34:18.07438Z","steps":["trace[48512530] 'range keys from in-memory index tree' (duration: 143.489339ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:18.839198Z","caller":"traceutil/trace.go:171","msg":"trace[206644444] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1895; }","duration":"191.324893ms","start":"2026-02-20T18:34:18.64785Z","end":"2026-02-20T18:34:18.839175Z","steps":["trace[206644444] 'read index received' (duration: 32.905449ms)","trace[206644444] 'applied index is now lower than readState.Index' (duration: 158.418334ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:34:18.83927Z","caller":"traceutil/trace.go:171","msg":"trace[919185206] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"229.116227ms","start":"2026-02-20T18:34:18.610127Z","end":"2026-02-20T18:34:18.839244Z","steps":["trace[919185206] 'process raft request' (duration: 171.579933ms)","trace[919185206] 'compare' (duration: 57.370689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:18.839436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.851583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-20T18:34:18.839503Z","caller":"traceutil/trace.go:171","msg":"trace[1401092060] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1816; }","duration":"168.969626ms","start":"2026-02-20T18:34:18.670515Z","end":"2026-02-20T18:34:18.839485Z","steps":["trace[1401092060] 'agreement among raft nodes before linearized reading' (duration: 168.796021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:18.839584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.737003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363630"} {"level":"info","ts":"2026-02-20T18:34:18.839614Z","caller":"traceutil/trace.go:171","msg":"trace[844942458] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1816; }","duration":"191.806705ms","start":"2026-02-20T18:34:18.6478Z","end":"2026-02-20T18:34:18.839606Z","steps":["trace[844942458] 'agreement among raft nodes before linearized reading' (duration: 191.480026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:19.260199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.935815ms","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-20T18:34:19.260378Z","caller":"traceutil/trace.go:171","msg":"trace[122022470] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1817; }","duration":"264.296504ms","start":"2026-02-20T18:34:18.99606Z","end":"2026-02-20T18:34:19.260356Z","steps":["trace[122022470] 'count revisions from in-memory index tree' (duration: 263.817461ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:19.488306Z","caller":"traceutil/trace.go:171","msg":"trace[1286232845] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"215.079135ms","start":"2026-02-20T18:34:19.273202Z","end":"2026-02-20T18:34:19.488281Z","steps":["trace[1286232845] 'read index received' (duration: 214.89753ms)","trace[1286232845] 'applied index is now lower than readState.Index' (duration: 180.445µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:19.488596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.368232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:34:19.488637Z","caller":"traceutil/trace.go:171","msg":"trace[1362101876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"215.449424ms","start":"2026-02-20T18:34:19.273178Z","end":"2026-02-20T18:34:19.488627Z","steps":["trace[1362101876] 'agreement among raft nodes before linearized reading' (duration: 215.257009ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:19.488782Z","caller":"traceutil/trace.go:171","msg":"trace[833274393] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"220.920095ms","start":"2026-02-20T18:34:19.267829Z","end":"2026-02-20T18:34:19.488749Z","steps":["trace[833274393] 'process raft request' (duration: 220.299789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:19.798123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.658722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:34:19.798206Z","caller":"traceutil/trace.go:171","msg":"trace[688986064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1820; }","duration":"205.796336ms","start":"2026-02-20T18:34:19.592391Z","end":"2026-02-20T18:34:19.798188Z","steps":["trace[688986064] 'range keys from in-memory index tree' (duration: 205.570679ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:20.776225Z","caller":"traceutil/trace.go:171","msg":"trace[1241205835] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1905; }","duration":"182.228238ms","start":"2026-02-20T18:34:20.59397Z","end":"2026-02-20T18:34:20.776198Z","steps":["trace[1241205835] 'read index received' (duration: 115.467657ms)","trace[1241205835] 'applied index is now lower than readState.Index' (duration: 66.759101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:34:20.776466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.477014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:34:20.776549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.976273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:979"} {"level":"info","ts":"2026-02-20T18:34:20.776645Z","caller":"traceutil/trace.go:171","msg":"trace[835641012] range","detail":"{range_begin:/registry/clusterroles/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1823; }","duration":"165.106576ms","start":"2026-02-20T18:34:20.611525Z","end":"2026-02-20T18:34:20.776631Z","steps":["trace[835641012] 'agreement among raft nodes before linearized reading' (duration: 164.850209ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:34:20.776594Z","caller":"traceutil/trace.go:171","msg":"trace[876374175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1823; }","duration":"182.636828ms","start":"2026-02-20T18:34:20.593933Z","end":"2026-02-20T18:34:20.77657Z","steps":["trace[876374175] 'agreement among raft nodes before linearized reading' (duration: 182.463604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:34:20.777301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.682122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:1708"} {"level":"info","ts":"2026-02-20T18:34:20.777413Z","caller":"traceutil/trace.go:171","msg":"trace[503056847] range","detail":"{range_begin:/registry/clusterroles/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1823; }","duration":"146.857355ms","start":"2026-02-20T18:34:20.630534Z","end":"2026-02-20T18:34:20.777391Z","steps":["trace[503056847] 'agreement among raft nodes before linearized reading' (duration: 145.973413ms)"],"step_count":1}