{"level":"warn","ts":"2026-02-25T19:25:12.493336Z","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-25T19:25:12.493433Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.183: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.19.213.183:2380","--initial-cluster=instance=https://199.19.213.183:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.183:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.183: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-25T19:25:12.493537Z","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-25T19:25:12.493551Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.183:2380"]} {"level":"info","ts":"2026-02-25T19:25:12.493568Z","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-25T19:25:12.494244Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"]} {"level":"info","ts":"2026-02-25T19:25:12.495159Z","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.19.213.183:2380"],"listen-peer-urls":["https://199.19.213.183:2380"],"advertise-client-urls":["https://199.19.213.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.183: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-25T19:25:12.501246Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.711979ms"} {"level":"info","ts":"2026-02-25T19:25:12.518588Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d11d60056e8410fb","cluster-id":"524d1f88cdf60208"} {"level":"info","ts":"2026-02-25T19:25:12.518699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:25:12.51875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became follower at term 0"} {"level":"info","ts":"2026-02-25T19:25:12.518775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d11d60056e8410fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:25:12.518787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became follower at term 1"} {"level":"info","ts":"2026-02-25T19:25:12.518845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=(15068305504696799483)"} {"level":"warn","ts":"2026-02-25T19:25:12.531238Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:25:12.532985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:25:12.534344Z","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-25T19:25:12.537192Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d11d60056e8410fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:25:12.53745Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d11d60056e8410fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:25:12.537497Z","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-25T19:25:12.53778Z","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-25T19:25:12.537808Z","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-25T19:25:12.539601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb switched to configuration voters=(15068305504696799483)"} {"level":"info","ts":"2026-02-25T19:25:12.540117Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"524d1f88cdf60208","local-member-id":"d11d60056e8410fb","added-peer-id":"d11d60056e8410fb","added-peer-peer-urls":["https://199.19.213.183:2380"]} {"level":"info","ts":"2026-02-25T19:25:12.543803Z","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-25T19:25:12.544014Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.183:2380"} {"level":"info","ts":"2026-02-25T19:25:12.544149Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.183:2380"} {"level":"info","ts":"2026-02-25T19:25:12.544306Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d11d60056e8410fb","initial-advertise-peer-urls":["https://199.19.213.183:2380"],"listen-peer-urls":["https://199.19.213.183:2380"],"advertise-client-urls":["https://199.19.213.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:25:12.54437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:25:12.919766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:25:12.919824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:25:12.919878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb received MsgPreVoteResp from d11d60056e8410fb at term 1"} {"level":"info","ts":"2026-02-25T19:25:12.919906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:25:12.919922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb received MsgVoteResp from d11d60056e8410fb at term 2"} {"level":"info","ts":"2026-02-25T19:25:12.920171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d11d60056e8410fb became leader at term 2"} {"level":"info","ts":"2026-02-25T19:25:12.920193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d11d60056e8410fb elected leader d11d60056e8410fb at term 2"} {"level":"info","ts":"2026-02-25T19:25:12.922555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d11d60056e8410fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.183:2379]}","request-path":"/0/members/d11d60056e8410fb/attributes","cluster-id":"524d1f88cdf60208","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:25:12.92257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:25:12.92263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:25:12.922874Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:25:12.92292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:25:12.926949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.183:2379"} {"level":"info","ts":"2026-02-25T19:25:12.927056Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:25:12.927879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"524d1f88cdf60208","local-member-id":"d11d60056e8410fb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:25:12.928015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:25:12.92806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:25:12.930234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T19:25:15.570402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.468468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:25:15.570652Z","caller":"traceutil/trace.go:171","msg":"trace[407618711] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"126.741837ms","start":"2026-02-25T19:25:15.443885Z","end":"2026-02-25T19:25:15.570627Z","steps":["trace[407618711] 'process raft request' (duration: 24.452273ms)","trace[407618711] 'compare' (duration: 101.312167ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:16.063764Z","caller":"traceutil/trace.go:171","msg":"trace[2046130049] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"114.371463ms","start":"2026-02-25T19:25:15.949359Z","end":"2026-02-25T19:25:16.06373Z","steps":["trace[2046130049] 'process raft request' (duration: 70.337499ms)","trace[2046130049] 'compare' (duration: 43.886523ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:19.470845Z","caller":"traceutil/trace.go:171","msg":"trace[1291632604] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"107.15633ms","start":"2026-02-25T19:25:19.36366Z","end":"2026-02-25T19:25:19.470816Z","steps":["trace[1291632604] 'process raft request' (duration: 107.009869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:19.645203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.467567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:19.645352Z","caller":"traceutil/trace.go:171","msg":"trace[2132005347] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"244.540671ms","start":"2026-02-25T19:25:19.400768Z","end":"2026-02-25T19:25:19.645309Z","steps":["trace[2132005347] 'process raft request' (duration: 72.868893ms)","trace[2132005347] 'compare' (duration: 171.292596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:20.149572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:20.614155Z","caller":"traceutil/trace.go:171","msg":"trace[958749804] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"1.032706532s","start":"2026-02-25T19:25:19.58141Z","end":"2026-02-25T19:25:20.614116Z","steps":["trace[958749804] 'process raft request' (duration: 989.071293ms)","trace[958749804] 'compare' (duration: 43.503388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:20.614356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.581388Z","time spent":"1.032866363s","remote":"127.0.0.1:47216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:25:20.768238Z","caller":"traceutil/trace.go:171","msg":"trace[252543610] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:323; }","duration":"1.119045182s","start":"2026-02-25T19:25:19.64916Z","end":"2026-02-25T19:25:20.768205Z","steps":["trace[252543610] 'read index received' (duration: 921.354403ms)","trace[252543610] 'applied index is now lower than readState.Index' (duration: 197.689409ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:20.768302Z","caller":"traceutil/trace.go:171","msg":"trace[494343564] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.101866448s","start":"2026-02-25T19:25:19.666391Z","end":"2026-02-25T19:25:20.768257Z","steps":["trace[494343564] 'process raft request' (duration: 1.101604036s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:20.768487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119302023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:20.768529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.646986ms","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-25T19:25:20.768554Z","caller":"traceutil/trace.go:171","msg":"trace[25111446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:315; }","duration":"1.119422275s","start":"2026-02-25T19:25:19.649116Z","end":"2026-02-25T19:25:20.768538Z","steps":["trace[25111446] 'agreement among raft nodes before linearized reading' (duration: 1.119274414s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:20.768584Z","caller":"traceutil/trace.go:171","msg":"trace[1138678739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:315; }","duration":"594.778797ms","start":"2026-02-25T19:25:20.173787Z","end":"2026-02-25T19:25:20.768566Z","steps":["trace[1138678739] 'agreement among raft nodes before linearized reading' (duration: 594.650756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:20.768611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.649095Z","time spent":"1.119500295s","remote":"127.0.0.1:47244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-25T19:25:20.76862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.790891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"warn","ts":"2026-02-25T19:25:20.768636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:20.173755Z","time spent":"594.870688ms","remote":"127.0.0.1:47306","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-25T19:25:20.768676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:19.666364Z","time spent":"1.102037589s","remote":"127.0.0.1:47216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:20.768691Z","caller":"traceutil/trace.go:171","msg":"trace[537957693] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:315; }","duration":"152.867202ms","start":"2026-02-25T19:25:20.615806Z","end":"2026-02-25T19:25:20.768673Z","steps":["trace[537957693] 'agreement among raft nodes before linearized reading' (duration: 152.715991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:20.768645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.720646ms","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-25T19:25:20.768769Z","caller":"traceutil/trace.go:171","msg":"trace[2124911119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:315; }","duration":"402.889896ms","start":"2026-02-25T19:25:20.365855Z","end":"2026-02-25T19:25:20.768745Z","steps":["trace[2124911119] 'agreement among raft nodes before linearized reading' (duration: 402.703765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:20.768812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:20.365833Z","time spent":"402.972207ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:25:21.11992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.216501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:21.12016Z","caller":"traceutil/trace.go:171","msg":"trace[684122183] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"241.355997ms","start":"2026-02-25T19:25:20.878713Z","end":"2026-02-25T19:25:21.120069Z","steps":["trace[684122183] 'process raft request' (duration: 122.900084ms)","trace[684122183] 'compare' (duration: 118.0712ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:21.120247Z","caller":"traceutil/trace.go:171","msg":"trace[486256804] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"203.183063ms","start":"2026-02-25T19:25:20.916834Z","end":"2026-02-25T19:25:21.120017Z","steps":["trace[486256804] 'read index received' (duration: 84.780801ms)","trace[486256804] 'applied index is now lower than readState.Index' (duration: 118.401422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:21.120283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.452065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:21.120373Z","caller":"traceutil/trace.go:171","msg":"trace[883536789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:321; }","duration":"203.572866ms","start":"2026-02-25T19:25:20.916792Z","end":"2026-02-25T19:25:21.120365Z","steps":["trace[883536789] 'agreement among raft nodes before linearized reading' (duration: 203.470785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:21.411829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.254984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:21.411954Z","caller":"traceutil/trace.go:171","msg":"trace[170900354] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"206.376404ms","start":"2026-02-25T19:25:21.205561Z","end":"2026-02-25T19:25:21.411938Z","steps":["trace[170900354] 'read index received' (duration: 70.897788ms)","trace[170900354] 'applied index is now lower than readState.Index' (duration: 135.477156ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:21.412178Z","caller":"traceutil/trace.go:171","msg":"trace[1087998751] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"283.658475ms","start":"2026-02-25T19:25:21.128487Z","end":"2026-02-25T19:25:21.412146Z","steps":["trace[1087998751] 'process raft request' (duration: 147.966798ms)","trace[1087998751] 'compare' (duration: 135.107093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:21.412486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.646999ms","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-25T19:25:21.41255Z","caller":"traceutil/trace.go:171","msg":"trace[414774026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:323; }","duration":"205.73082ms","start":"2026-02-25T19:25:21.206804Z","end":"2026-02-25T19:25:21.412535Z","steps":["trace[414774026] 'agreement among raft nodes before linearized reading' (duration: 205.621209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:21.412787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.229639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:21.412846Z","caller":"traceutil/trace.go:171","msg":"trace[175607248] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:323; }","duration":"207.331ms","start":"2026-02-25T19:25:21.205495Z","end":"2026-02-25T19:25:21.412826Z","steps":["trace[175607248] 'agreement among raft nodes before linearized reading' (duration: 206.782127ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:35.061437Z","caller":"traceutil/trace.go:171","msg":"trace[1369793753] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"137.417901ms","start":"2026-02-25T19:25:34.923991Z","end":"2026-02-25T19:25:35.061408Z","steps":["trace[1369793753] 'process raft request' (duration: 137.23787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.054688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.854991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:36.054769Z","caller":"traceutil/trace.go:171","msg":"trace[57292807] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:469; }","duration":"1.074647584s","start":"2026-02-25T19:25:34.98011Z","end":"2026-02-25T19:25:36.054757Z","steps":["trace[57292807] 'read index received' (duration: 81.114056ms)","trace[57292807] 'applied index is now lower than readState.Index' (duration: 993.532578ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:36.054915Z","caller":"traceutil/trace.go:171","msg":"trace[1347767026] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.091562914s","start":"2026-02-25T19:25:34.963344Z","end":"2026-02-25T19:25:36.054907Z","steps":["trace[1347767026] 'process raft request' (duration: 643.416261ms)","trace[1347767026] 'compare' (duration: 447.491419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:36.054955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:34.963322Z","time spent":"1.091614654s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:36.055065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074969446s","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-25T19:25:36.055081Z","caller":"traceutil/trace.go:171","msg":"trace[1462886277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"1.075062637s","start":"2026-02-25T19:25:34.980014Z","end":"2026-02-25T19:25:36.055076Z","steps":["trace[1462886277] 'agreement among raft nodes before linearized reading' (duration: 1.075028997s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.055095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:34.979988Z","time spent":"1.075104287s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:36.055294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.728058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:36.055315Z","caller":"traceutil/trace.go:171","msg":"trace[1446921983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"331.773719ms","start":"2026-02-25T19:25:35.723536Z","end":"2026-02-25T19:25:36.05531Z","steps":["trace[1446921983] 'agreement among raft nodes before linearized reading' (duration: 331.743139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.055256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.392579ms","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-25T19:25:36.056102Z","caller":"traceutil/trace.go:171","msg":"trace[232303216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"533.235464ms","start":"2026-02-25T19:25:35.522788Z","end":"2026-02-25T19:25:36.056024Z","steps":["trace[232303216] 'agreement among raft nodes before linearized reading' (duration: 532.333128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.05623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:35.522766Z","time spent":"533.443526ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:36.055328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:35.723521Z","time spent":"331.80417ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:25:36.055372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.668281ms","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-25T19:25:36.056573Z","caller":"traceutil/trace.go:171","msg":"trace[633444772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"462.886229ms","start":"2026-02-25T19:25:35.593672Z","end":"2026-02-25T19:25:36.056558Z","steps":["trace[633444772] 'agreement among raft nodes before linearized reading' (duration: 461.64061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:36.056612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:35.593658Z","time spent":"462.944429ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:37.22596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:37.726702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:38.227257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:38.725467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000415146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:25:38.725562Z","caller":"traceutil/trace.go:171","msg":"trace[813018645] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000612927s","start":"2026-02-25T19:25:36.72493Z","end":"2026-02-25T19:25:38.725543Z","steps":["trace[813018645] 'agreement among raft nodes before linearized reading' (duration: 2.000451676s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:38.725652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:36.724907Z","time spent":"2.000733777s","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:25:38.728453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:39.197732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.933636959s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:25:39.197842Z","caller":"traceutil/trace.go:171","msg":"trace[491450738] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"3.139103449s","start":"2026-02-25T19:25:36.05872Z","end":"2026-02-25T19:25:39.197823Z","steps":["trace[491450738] 'process raft request' (duration: 205.230579ms)","trace[491450738] 'compare' (duration: 2.933346377s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:39.197903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:36.058708Z","time spent":"3.13916655s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:39.22911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:39.23158Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.967652999s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:25:39.232367Z","caller":"traceutil/trace.go:171","msg":"trace[62017077] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"3.172644376s","start":"2026-02-25T19:25:36.059689Z","end":"2026-02-25T19:25:39.232333Z","steps":["trace[62017077] 'process raft request' (duration: 3.172317994s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:39.232505Z","caller":"traceutil/trace.go:171","msg":"trace[1196221583] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"3.171524859s","start":"2026-02-25T19:25:36.060958Z","end":"2026-02-25T19:25:39.232483Z","steps":["trace[1196221583] 'process raft request' (duration: 3.171192957s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:39.232546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:36.059662Z","time spent":"3.172782857s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:39.233314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:36.060925Z","time spent":"3.17168191s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:39.270702Z","caller":"traceutil/trace.go:171","msg":"trace[1904246775] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"528.694973ms","start":"2026-02-25T19:25:38.741944Z","end":"2026-02-25T19:25:39.270639Z","steps":["trace[1904246775] 'process raft request' (duration: 526.66265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:39.271111Z","caller":"traceutil/trace.go:171","msg":"trace[475943441] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:472; }","duration":"2.545527526s","start":"2026-02-25T19:25:36.724993Z","end":"2026-02-25T19:25:39.27052Z","steps":["trace[475943441] 'read index received' (duration: 2.506830676s)","trace[475943441] 'applied index is now lower than readState.Index' (duration: 38.6959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:39.271092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:38.741924Z","time spent":"528.881926ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:39.271371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.251392101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.183\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:25:39.27141Z","caller":"traceutil/trace.go:171","msg":"trace[2083266493] range","detail":"{range_begin:/registry/masterleases/199.19.213.183; range_end:; response_count:1; response_revision:460; }","duration":"2.251495971s","start":"2026-02-25T19:25:37.019902Z","end":"2026-02-25T19:25:39.271398Z","steps":["trace[2083266493] 'agreement among raft nodes before linearized reading' (duration: 2.25134583s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:39.271409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.15534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:39.271435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:37.019885Z","time spent":"2.251544823s","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.183\" "} {"level":"info","ts":"2026-02-25T19:25:39.271453Z","caller":"traceutil/trace.go:171","msg":"trace[378650875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"545.226451ms","start":"2026-02-25T19:25:38.726214Z","end":"2026-02-25T19:25:39.271441Z","steps":["trace[378650875] 'agreement among raft nodes before linearized reading' (duration: 545.1254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:39.271598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:38.7262Z","time spent":"545.283971ms","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:25:39.271593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204435677s","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-25T19:25:39.271693Z","caller":"traceutil/trace.go:171","msg":"trace[1896146303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:460; }","duration":"2.204593848s","start":"2026-02-25T19:25:37.067073Z","end":"2026-02-25T19:25:39.271667Z","steps":["trace[1896146303] 'agreement among raft nodes before linearized reading' (duration: 2.204344456s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:39.271746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:37.067059Z","time spent":"2.204677869s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:25:41.737382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716846,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:41.763463Z","caller":"traceutil/trace.go:171","msg":"trace[1825022031] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"526.725574ms","start":"2026-02-25T19:25:41.236713Z","end":"2026-02-25T19:25:41.763439Z","steps":["trace[1825022031] 'read index received' (duration: 526.526593ms)","trace[1825022031] 'applied index is now lower than readState.Index' (duration: 197.991µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:41.763537Z","caller":"traceutil/trace.go:171","msg":"trace[334266535] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"558.334717ms","start":"2026-02-25T19:25:41.205169Z","end":"2026-02-25T19:25:41.763504Z","steps":["trace[334266535] 'process raft request' (duration: 558.129706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.763657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.917565ms","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-25T19:25:41.763696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.875604ms","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-25T19:25:41.763697Z","caller":"traceutil/trace.go:171","msg":"trace[174054476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"526.999725ms","start":"2026-02-25T19:25:41.236684Z","end":"2026-02-25T19:25:41.763684Z","steps":["trace[174054476] 'agreement among raft nodes before linearized reading' (duration: 526.906665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.76367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.600173ms","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-25T19:25:41.76373Z","caller":"traceutil/trace.go:171","msg":"trace[1482874339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"467.965816ms","start":"2026-02-25T19:25:41.295756Z","end":"2026-02-25T19:25:41.763721Z","steps":["trace[1482874339] 'agreement among raft nodes before linearized reading' (duration: 467.896705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.763742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.236673Z","time spent":"527.059276ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:25:41.763752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.295725Z","time spent":"468.022556ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:25:41.763754Z","caller":"traceutil/trace.go:171","msg":"trace[783292609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"526.729664ms","start":"2026-02-25T19:25:41.237005Z","end":"2026-02-25T19:25:41.763734Z","steps":["trace[783292609] 'agreement among raft nodes before linearized reading' (duration: 526.601713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:41.763737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.205152Z","time spent":"558.465628ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:41.763798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:41.23698Z","time spent":"526.805924ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:25:44.64699Z","caller":"traceutil/trace.go:171","msg":"trace[1810014696] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"281.467158ms","start":"2026-02-25T19:25:44.365498Z","end":"2026-02-25T19:25:44.646965Z","steps":["trace[1810014696] 'process raft request' (duration: 280.483972ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:45.905544Z","caller":"traceutil/trace.go:171","msg":"trace[2553150] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"122.501217ms","start":"2026-02-25T19:25:45.783006Z","end":"2026-02-25T19:25:45.905507Z","steps":["trace[2553150] 'process raft request' (duration: 122.194105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:47.225782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947716928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:47.560327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240877676s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:25:47.56059Z","caller":"traceutil/trace.go:171","msg":"trace[1765946171] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"835.800308ms","start":"2026-02-25T19:25:46.724774Z","end":"2026-02-25T19:25:47.560574Z","steps":["trace[1765946171] 'read index received' (duration: 835.636167ms)","trace[1765946171] 'applied index is now lower than readState.Index' (duration: 163.471µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:47.560656Z","caller":"traceutil/trace.go:171","msg":"trace[1269041599] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.241294649s","start":"2026-02-25T19:25:46.31935Z","end":"2026-02-25T19:25:47.560644Z","steps":["trace[1269041599] 'process raft request' (duration: 1.241122738s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:47.560756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:46.319328Z","time spent":"1.241357119s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:47.560911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.12321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:47.560905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.189262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.183\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:25:47.561106Z","caller":"traceutil/trace.go:171","msg":"trace[816932439] range","detail":"{range_begin:/registry/masterleases/199.19.213.183; range_end:; response_count:1; response_revision:510; }","duration":"540.385744ms","start":"2026-02-25T19:25:47.020695Z","end":"2026-02-25T19:25:47.561081Z","steps":["trace[816932439] 'agreement among raft nodes before linearized reading' (duration: 540.134902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:47.561217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:47.020638Z","time spent":"540.561184ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.183\" "} {"level":"info","ts":"2026-02-25T19:25:47.560999Z","caller":"traceutil/trace.go:171","msg":"trace[1176454852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"836.270401ms","start":"2026-02-25T19:25:46.724708Z","end":"2026-02-25T19:25:47.560978Z","steps":["trace[1176454852] 'agreement among raft nodes before linearized reading' (duration: 836.09583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:47.56163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:46.724681Z","time spent":"836.796855ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:25:54.810712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086698333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:54.810798Z","caller":"traceutil/trace.go:171","msg":"trace[1260937003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.086829934s","start":"2026-02-25T19:25:53.723945Z","end":"2026-02-25T19:25:54.810775Z","steps":["trace[1260937003] 'range keys from in-memory index tree' (duration: 1.086498112s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:54.810841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:53.723923Z","time spent":"1.086909844s","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:25:54.810861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.56934ms","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-25T19:25:54.810921Z","caller":"traceutil/trace.go:171","msg":"trace[1648706446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"846.699862ms","start":"2026-02-25T19:25:53.964205Z","end":"2026-02-25T19:25:54.810905Z","steps":["trace[1648706446] 'range keys from in-memory index tree' (duration: 846.37453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:54.810956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:53.964178Z","time spent":"846.767972ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:25:54.810968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.537707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:25:54.811033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.181457ms","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-25T19:25:54.81106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.826318ms","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-25T19:25:54.811109Z","caller":"traceutil/trace.go:171","msg":"trace[1689940017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"731.671478ms","start":"2026-02-25T19:25:54.079376Z","end":"2026-02-25T19:25:54.811047Z","steps":["trace[1689940017] 'range keys from in-memory index tree' (duration: 731.359596ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:54.811128Z","caller":"traceutil/trace.go:171","msg":"trace[648683844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"733.313398ms","start":"2026-02-25T19:25:54.07779Z","end":"2026-02-25T19:25:54.811103Z","steps":["trace[648683844] 'range keys from in-memory index tree' (duration: 732.976725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:54.811158Z","caller":"traceutil/trace.go:171","msg":"trace[571052909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"731.984529ms","start":"2026-02-25T19:25:54.079152Z","end":"2026-02-25T19:25:54.811136Z","steps":["trace[571052909] 'range keys from in-memory index tree' (duration: 731.616817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:54.811163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.07937Z","time spent":"731.782328ms","remote":"127.0.0.1:47306","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-25T19:25:54.811224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.079131Z","time spent":"732.08149ms","remote":"127.0.0.1:47306","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-25T19:25:54.811172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.077775Z","time spent":"733.388588ms","remote":"127.0.0.1:47306","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-25T19:25:55.019692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.66942ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T19:25:55.331569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947717025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:25:55.43145Z","caller":"traceutil/trace.go:171","msg":"trace[1466145025] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:570; }","duration":"600.403147ms","start":"2026-02-25T19:25:54.831022Z","end":"2026-02-25T19:25:55.431425Z","steps":["trace[1466145025] 'read index received' (duration: 600.166256ms)","trace[1466145025] 'applied index is now lower than readState.Index' (duration: 235.571µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:55.431589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.551138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:55.431626Z","caller":"traceutil/trace.go:171","msg":"trace[1374053471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"600.634979ms","start":"2026-02-25T19:25:54.83098Z","end":"2026-02-25T19:25:55.431615Z","steps":["trace[1374053471] 'agreement among raft nodes before linearized reading' (duration: 600.545278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:55.431661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.830962Z","time spent":"600.69197ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:25:55.431933Z","caller":"traceutil/trace.go:171","msg":"trace[75243683] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"605.107658ms","start":"2026-02-25T19:25:54.826815Z","end":"2026-02-25T19:25:55.431923Z","steps":["trace[75243683] 'process raft request' (duration: 604.510364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:55.432029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.826804Z","time spent":"605.162778ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:55.432062Z","caller":"traceutil/trace.go:171","msg":"trace[1242555052] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"605.987273ms","start":"2026-02-25T19:25:54.825999Z","end":"2026-02-25T19:25:55.431986Z","steps":["trace[1242555052] 'process raft request' (duration: 605.268548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:55.432275Z","caller":"traceutil/trace.go:171","msg":"trace[261024744] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"605.137268ms","start":"2026-02-25T19:25:54.827131Z","end":"2026-02-25T19:25:55.432268Z","steps":["trace[261024744] 'process raft request' (duration: 604.247232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:55.432327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.827117Z","time spent":"605.184438ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:55.432484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.825988Z","time spent":"606.313505ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:55.432195Z","caller":"traceutil/trace.go:171","msg":"trace[1258587138] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"607.264401ms","start":"2026-02-25T19:25:54.824919Z","end":"2026-02-25T19:25:55.432183Z","steps":["trace[1258587138] 'process raft request' (duration: 606.180224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:55.432632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:54.824884Z","time spent":"607.710604ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:25:57.937138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947717035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:58.438287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947717035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:25:58.489875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.071046948s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:25:58.490169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.418694Z","time spent":"1.071472138s","remote":"127.0.0.1:47058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:26:00.289624Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.721905746s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:26:00.319403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75165738s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:26:00.320787Z","caller":"traceutil/trace.go:171","msg":"trace[1806163444] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"2.884241377s","start":"2026-02-25T19:25:57.436449Z","end":"2026-02-25T19:26:00.320691Z","steps":["trace[1806163444] 'read index received' (duration: 1.053597334s)","trace[1806163444] 'applied index is now lower than readState.Index' (duration: 1.830488405s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:00.322304Z","caller":"traceutil/trace.go:171","msg":"trace[1454874880] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.594746148s","start":"2026-02-25T19:25:57.727529Z","end":"2026-02-25T19:26:00.322275Z","steps":["trace[1454874880] 'process raft request' (duration: 840.126904ms)","trace[1454874880] 'compare' (duration: 1.75146697s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:00.322423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.727501Z","time spent":"2.5948547s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:26:00.322464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.885992853s","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-25T19:26:00.322562Z","caller":"traceutil/trace.go:171","msg":"trace[98376095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"2.886110166s","start":"2026-02-25T19:25:57.436418Z","end":"2026-02-25T19:26:00.322528Z","steps":["trace[98376095] 'agreement among raft nodes before linearized reading' (duration: 2.885668717s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.322647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.436406Z","time spent":"2.886188726s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:26:00.323005Z","caller":"traceutil/trace.go:171","msg":"trace[1374344460] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.831789914s","start":"2026-02-25T19:25:58.491189Z","end":"2026-02-25T19:26:00.322979Z","steps":["trace[1374344460] 'process raft request' (duration: 1.829301004s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.323128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:58.491168Z","time spent":"1.831887216s","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:26:00.413251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689320529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:26:00.413338Z","caller":"traceutil/trace.go:171","msg":"trace[582174033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"1.689450865s","start":"2026-02-25T19:25:58.723866Z","end":"2026-02-25T19:26:00.413316Z","steps":["trace[582174033] 'agreement among raft nodes before linearized reading' (duration: 1.689245792s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.41338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:58.723844Z","time spent":"1.689527503s","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:26:00.413436Z","caller":"traceutil/trace.go:171","msg":"trace[67719427] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"995.511626ms","start":"2026-02-25T19:25:59.417887Z","end":"2026-02-25T19:26:00.413399Z","steps":["trace[67719427] 'process raft request' (duration: 995.004259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.413638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:59.417868Z","time spent":"995.64088ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:26:00.413637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.975800394s","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-25T19:26:00.413626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.953436692s","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-25T19:26:00.413712Z","caller":"traceutil/trace.go:171","msg":"trace[1616754711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.975909726s","start":"2026-02-25T19:25:57.437785Z","end":"2026-02-25T19:26:00.413695Z","steps":["trace[1616754711] 'agreement among raft nodes before linearized reading' (duration: 2.975608672s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.413768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.437778Z","time spent":"2.975981074s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:26:00.413815Z","caller":"traceutil/trace.go:171","msg":"trace[146767771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"2.953628164s","start":"2026-02-25T19:25:57.460112Z","end":"2026-02-25T19:26:00.41374Z","steps":["trace[146767771] 'agreement among raft nodes before linearized reading' (duration: 2.953035258s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.413703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.976016279s","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-25T19:26:00.413884Z","caller":"traceutil/trace.go:171","msg":"trace[1203429736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.976221252s","start":"2026-02-25T19:25:57.437653Z","end":"2026-02-25T19:26:00.413874Z","steps":["trace[1203429736] 'agreement among raft nodes before linearized reading' (duration: 2.975942871s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:00.413911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.437642Z","time spent":"2.976263268s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:26:00.413967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:57.460088Z","time spent":"2.95386515s","remote":"127.0.0.1:47306","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-25T19:26:00.948599Z","caller":"traceutil/trace.go:171","msg":"trace[1598395161] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"231.948617ms","start":"2026-02-25T19:26:00.716617Z","end":"2026-02-25T19:26:00.948566Z","steps":["trace[1598395161] 'process raft request' (duration: 231.745315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:02.696993Z","caller":"traceutil/trace.go:171","msg":"trace[1457515538] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"277.279151ms","start":"2026-02-25T19:26:02.419696Z","end":"2026-02-25T19:26:02.696975Z","steps":["trace[1457515538] 'read index received' (duration: 277.18147ms)","trace[1457515538] 'applied index is now lower than readState.Index' (duration: 96.3µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:02.697078Z","caller":"traceutil/trace.go:171","msg":"trace[2129119453] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"328.830779ms","start":"2026-02-25T19:26:02.368211Z","end":"2026-02-25T19:26:02.697041Z","steps":["trace[2129119453] 'process raft request' (duration: 328.636798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:02.697177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.470271ms","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-25T19:26:02.697197Z","caller":"traceutil/trace.go:171","msg":"trace[457307565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"277.542838ms","start":"2026-02-25T19:26:02.419648Z","end":"2026-02-25T19:26:02.697191Z","steps":["trace[457307565] 'agreement among raft nodes before linearized reading' (duration: 277.410584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:02.697249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:02.368199Z","time spent":"328.963773ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:26:02.707024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.358447ms","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-25T19:26:02.707086Z","caller":"traceutil/trace.go:171","msg":"trace[859069417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"254.463878ms","start":"2026-02-25T19:26:02.452603Z","end":"2026-02-25T19:26:02.707067Z","steps":["trace[859069417] 'agreement among raft nodes before linearized reading' (duration: 254.237674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:02.707146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.697646ms","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-25T19:26:02.707339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.591652ms","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-25T19:26:02.707472Z","caller":"traceutil/trace.go:171","msg":"trace[458117515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"254.885093ms","start":"2026-02-25T19:26:02.452577Z","end":"2026-02-25T19:26:02.707462Z","steps":["trace[458117515] 'agreement among raft nodes before linearized reading' (duration: 254.281488ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:02.707232Z","caller":"traceutil/trace.go:171","msg":"trace[450099943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"256.815779ms","start":"2026-02-25T19:26:02.450393Z","end":"2026-02-25T19:26:02.707208Z","steps":["trace[450099943] 'agreement among raft nodes before linearized reading' (duration: 256.647381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:06.127888Z","caller":"traceutil/trace.go:171","msg":"trace[187186076] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"102.356117ms","start":"2026-02-25T19:26:06.025498Z","end":"2026-02-25T19:26:06.127854Z","steps":["trace[187186076] 'process raft request' (duration: 101.841348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:06.86065Z","caller":"traceutil/trace.go:171","msg":"trace[1354588199] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"113.740393ms","start":"2026-02-25T19:26:06.746883Z","end":"2026-02-25T19:26:06.860623Z","steps":["trace[1354588199] 'read index received' (duration: 73.774158ms)","trace[1354588199] 'applied index is now lower than readState.Index' (duration: 39.965255ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:06.860775Z","caller":"traceutil/trace.go:171","msg":"trace[2049482255] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"115.915662ms","start":"2026-02-25T19:26:06.744834Z","end":"2026-02-25T19:26:06.86075Z","steps":["trace[2049482255] 'process raft request' (duration: 75.808043ms)","trace[2049482255] 'compare' (duration: 39.80398ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:06.861288Z","caller":"traceutil/trace.go:171","msg":"trace[2145112302] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"110.822312ms","start":"2026-02-25T19:26:06.750439Z","end":"2026-02-25T19:26:06.861261Z","steps":["trace[2145112302] 'process raft request' (duration: 110.156008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:06.861325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.541602ms","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-25T19:26:06.861406Z","caller":"traceutil/trace.go:171","msg":"trace[1940385625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"116.654043ms","start":"2026-02-25T19:26:06.74473Z","end":"2026-02-25T19:26:06.861384Z","steps":["trace[1940385625] 'agreement among raft nodes before linearized reading' (duration: 116.032483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:07.14547Z","caller":"traceutil/trace.go:171","msg":"trace[829201489] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"268.366117ms","start":"2026-02-25T19:26:06.877039Z","end":"2026-02-25T19:26:07.145405Z","steps":["trace[829201489] 'process raft request' (duration: 240.823413ms)","trace[829201489] 'compare' (duration: 27.259858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:07.146022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.987931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-25T19:26:07.146107Z","caller":"traceutil/trace.go:171","msg":"trace[172130364] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"236.113403ms","start":"2026-02-25T19:26:06.909974Z","end":"2026-02-25T19:26:07.146088Z","steps":["trace[172130364] 'agreement among raft nodes before linearized reading' (duration: 235.887342ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:07.145755Z","caller":"traceutil/trace.go:171","msg":"trace[1540439617] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"235.243681ms","start":"2026-02-25T19:26:06.910014Z","end":"2026-02-25T19:26:07.145258Z","steps":["trace[1540439617] 'read index received' (duration: 24.904066ms)","trace[1540439617] 'applied index is now lower than readState.Index' (duration: 210.338705ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:13.913478Z","caller":"traceutil/trace.go:171","msg":"trace[857124403] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"108.875272ms","start":"2026-02-25T19:26:13.804565Z","end":"2026-02-25T19:26:13.91344Z","steps":["trace[857124403] 'process raft request' (duration: 108.641943ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:14.153414Z","caller":"traceutil/trace.go:171","msg":"trace[927867281] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"232.646448ms","start":"2026-02-25T19:26:13.920737Z","end":"2026-02-25T19:26:14.153383Z","steps":["trace[927867281] 'read index received' (duration: 232.54875ms)","trace[927867281] 'applied index is now lower than readState.Index' (duration: 96.228µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:14.153619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.865145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-tz5hx\" ","response":"range_response_count:1 size:5083"} {"level":"info","ts":"2026-02-25T19:26:14.153607Z","caller":"traceutil/trace.go:171","msg":"trace[2119422340] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"232.905389ms","start":"2026-02-25T19:26:13.920681Z","end":"2026-02-25T19:26:14.153586Z","steps":["trace[2119422340] 'process raft request' (duration: 232.524619ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:14.153664Z","caller":"traceutil/trace.go:171","msg":"trace[484324967] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-tz5hx; range_end:; response_count:1; response_revision:711; }","duration":"232.964704ms","start":"2026-02-25T19:26:13.920686Z","end":"2026-02-25T19:26:14.153651Z","steps":["trace[484324967] 'agreement among raft nodes before linearized reading' (duration: 232.806621ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:14.163477Z","caller":"traceutil/trace.go:171","msg":"trace[245193812] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"242.540548ms","start":"2026-02-25T19:26:13.920915Z","end":"2026-02-25T19:26:14.163456Z","steps":["trace[245193812] 'process raft request' (duration: 242.416469ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:15.072568Z","caller":"traceutil/trace.go:171","msg":"trace[1486067822] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"102.23564ms","start":"2026-02-25T19:26:14.970298Z","end":"2026-02-25T19:26:15.072533Z","steps":["trace[1486067822] 'read index received' (duration: 28.772401ms)","trace[1486067822] 'applied index is now lower than readState.Index' (duration: 73.461229ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:15.072698Z","caller":"traceutil/trace.go:171","msg":"trace[613580324] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"138.074057ms","start":"2026-02-25T19:26:14.934593Z","end":"2026-02-25T19:26:15.072667Z","steps":["trace[613580324] 'process raft request' (duration: 64.561454ms)","trace[613580324] 'compare' (duration: 73.21378ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:15.072972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.62923ms","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-25T19:26:15.073095Z","caller":"traceutil/trace.go:171","msg":"trace[1280392638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"102.810425ms","start":"2026-02-25T19:26:14.97025Z","end":"2026-02-25T19:26:15.073061Z","steps":["trace[1280392638] 'agreement among raft nodes before linearized reading' (duration: 102.458948ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:15.829806Z","caller":"traceutil/trace.go:171","msg":"trace[965458255] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"326.382114ms","start":"2026-02-25T19:26:15.503402Z","end":"2026-02-25T19:26:15.829785Z","steps":["trace[965458255] 'process raft request' (duration: 326.183839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:15.829933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:15.503387Z","time spent":"326.485792ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":909,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:26:15.829943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.459082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:26:15.829996Z","caller":"traceutil/trace.go:171","msg":"trace[595081698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"106.598032ms","start":"2026-02-25T19:26:15.723385Z","end":"2026-02-25T19:26:15.829983Z","steps":["trace[595081698] 'agreement among raft nodes before linearized reading' (duration: 106.471573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:15.829794Z","caller":"traceutil/trace.go:171","msg":"trace[2016553397] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"106.30107ms","start":"2026-02-25T19:26:15.723467Z","end":"2026-02-25T19:26:15.829768Z","steps":["trace[2016553397] 'read index received' (duration: 106.053431ms)","trace[2016553397] 'applied index is now lower than readState.Index' (duration: 246.319µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:17.065918Z","caller":"traceutil/trace.go:171","msg":"trace[1317461072] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"120.642907ms","start":"2026-02-25T19:26:16.945243Z","end":"2026-02-25T19:26:17.065886Z","steps":["trace[1317461072] 'process raft request' (duration: 120.436451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:17.066321Z","caller":"traceutil/trace.go:171","msg":"trace[1014604582] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"120.617564ms","start":"2026-02-25T19:26:16.945688Z","end":"2026-02-25T19:26:17.066305Z","steps":["trace[1014604582] 'process raft request' (duration: 120.434461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:19.059838Z","caller":"traceutil/trace.go:171","msg":"trace[1289262112] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"225.993348ms","start":"2026-02-25T19:26:18.833819Z","end":"2026-02-25T19:26:19.059812Z","steps":["trace[1289262112] 'read index received' (duration: 225.773503ms)","trace[1289262112] 'applied index is now lower than readState.Index' (duration: 219.015µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:19.060138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.314271ms","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-25T19:26:19.060176Z","caller":"traceutil/trace.go:171","msg":"trace[1455557464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"226.399067ms","start":"2026-02-25T19:26:18.833765Z","end":"2026-02-25T19:26:19.060164Z","steps":["trace[1455557464] 'agreement among raft nodes before linearized reading' (duration: 226.194123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:19.060633Z","caller":"traceutil/trace.go:171","msg":"trace[81946077] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"239.108118ms","start":"2026-02-25T19:26:18.821511Z","end":"2026-02-25T19:26:19.060619Z","steps":["trace[81946077] 'process raft request' (duration: 238.14229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.439093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.408974ms","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-25T19:26:21.439582Z","caller":"traceutil/trace.go:171","msg":"trace[378514849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"303.951061ms","start":"2026-02-25T19:26:21.135609Z","end":"2026-02-25T19:26:21.439561Z","steps":["trace[378514849] 'range keys from in-memory index tree' (duration: 303.18965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.439639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.13559Z","time spent":"304.032606ms","remote":"127.0.0.1:47306","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-25T19:26:21.439758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.488598ms","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-25T19:26:21.439895Z","caller":"traceutil/trace.go:171","msg":"trace[1860966480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"370.694332ms","start":"2026-02-25T19:26:21.069169Z","end":"2026-02-25T19:26:21.439863Z","steps":["trace[1860966480] 'range keys from in-memory index tree' (duration: 370.256542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.439948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.069142Z","time spent":"370.795158ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:26:21.439913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.263685ms","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-25T19:26:21.440025Z","caller":"traceutil/trace.go:171","msg":"trace[867056048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"366.434106ms","start":"2026-02-25T19:26:21.073565Z","end":"2026-02-25T19:26:21.44Z","steps":["trace[867056048] 'range keys from in-memory index tree' (duration: 366.02877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.440104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.073544Z","time spent":"366.548884ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:26:21.440165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.89212ms","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-25T19:26:21.440245Z","caller":"traceutil/trace.go:171","msg":"trace[631178697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"356.001578ms","start":"2026-02-25T19:26:21.084224Z","end":"2026-02-25T19:26:21.440226Z","steps":["trace[631178697] 'range keys from in-memory index tree' (duration: 355.671545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.440212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.788275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:26:21.440328Z","caller":"traceutil/trace.go:171","msg":"trace[840040484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"338.943355ms","start":"2026-02-25T19:26:21.101357Z","end":"2026-02-25T19:26:21.4403Z","steps":["trace[840040484] 'range keys from in-memory index tree' (duration: 338.677037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.440369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.084214Z","time spent":"356.076053ms","remote":"127.0.0.1:47306","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-25T19:26:21.440394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.101336Z","time spent":"339.045062ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:26:21.440733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.92237ms","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-25T19:26:21.440977Z","caller":"traceutil/trace.go:171","msg":"trace[174263592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"360.201879ms","start":"2026-02-25T19:26:21.080758Z","end":"2026-02-25T19:26:21.44096Z","steps":["trace[174263592] 'range keys from in-memory index tree' (duration: 357.939658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:21.441014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:26:21.080735Z","time spent":"360.268094ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:26:29.679061Z","caller":"traceutil/trace.go:171","msg":"trace[2072655811] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"118.757779ms","start":"2026-02-25T19:26:29.560278Z","end":"2026-02-25T19:26:29.679036Z","steps":["trace[2072655811] 'process raft request' (duration: 118.715817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:29.679277Z","caller":"traceutil/trace.go:171","msg":"trace[1589194580] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"118.989321ms","start":"2026-02-25T19:26:29.560283Z","end":"2026-02-25T19:26:29.679272Z","steps":["trace[1589194580] 'process raft request' (duration: 112.240442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:26:33.019609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.509754ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:26:33.759761Z","caller":"traceutil/trace.go:171","msg":"trace[2089886241] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"142.319613ms","start":"2026-02-25T19:26:33.617396Z","end":"2026-02-25T19:26:33.759716Z","steps":["trace[2089886241] 'process raft request' (duration: 79.676218ms)","trace[2089886241] 'compare' (duration: 62.209282ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:33.760221Z","caller":"traceutil/trace.go:171","msg":"trace[1951213038] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"141.769044ms","start":"2026-02-25T19:26:33.61842Z","end":"2026-02-25T19:26:33.760189Z","steps":["trace[1951213038] 'process raft request' (duration: 141.079029ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:33.954419Z","caller":"traceutil/trace.go:171","msg":"trace[1549632610] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"188.96196ms","start":"2026-02-25T19:26:33.765427Z","end":"2026-02-25T19:26:33.954389Z","steps":["trace[1549632610] 'process raft request' (duration: 110.59837ms)","trace[1549632610] 'compare' (duration: 78.250024ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:38.103387Z","caller":"traceutil/trace.go:171","msg":"trace[2026588051] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"109.348649ms","start":"2026-02-25T19:26:37.994019Z","end":"2026-02-25T19:26:38.103367Z","steps":["trace[2026588051] 'process raft request' (duration: 88.102631ms)","trace[2026588051] 'compare' (duration: 21.152614ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:40.228286Z","caller":"traceutil/trace.go:171","msg":"trace[261157102] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"116.214333ms","start":"2026-02-25T19:26:40.112031Z","end":"2026-02-25T19:26:40.228245Z","steps":["trace[261157102] 'process raft request' (duration: 16.522689ms)","trace[261157102] 'compare' (duration: 99.303046ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:26:44.183276Z","caller":"traceutil/trace.go:171","msg":"trace[227258019] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"147.540332ms","start":"2026-02-25T19:26:44.035674Z","end":"2026-02-25T19:26:44.183214Z","steps":["trace[227258019] 'process raft request' (duration: 147.396846ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:50.116241Z","caller":"traceutil/trace.go:171","msg":"trace[1947220989] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"200.734115ms","start":"2026-02-25T19:26:49.915487Z","end":"2026-02-25T19:26:50.116221Z","steps":["trace[1947220989] 'read index received' (duration: 200.508876ms)","trace[1947220989] 'applied index is now lower than readState.Index' (duration: 224.529µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:26:50.116419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.906541ms","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-25T19:26:50.116451Z","caller":"traceutil/trace.go:171","msg":"trace[225254013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:849; }","duration":"200.982584ms","start":"2026-02-25T19:26:49.915459Z","end":"2026-02-25T19:26:50.116442Z","steps":["trace[225254013] 'agreement among raft nodes before linearized reading' (duration: 200.841219ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:26:50.116529Z","caller":"traceutil/trace.go:171","msg":"trace[235055636] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"216.928052ms","start":"2026-02-25T19:26:49.899562Z","end":"2026-02-25T19:26:50.11649Z","steps":["trace[235055636] 'process raft request' (duration: 216.529427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:10.894767Z","caller":"traceutil/trace.go:171","msg":"trace[582618421] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"170.554519ms","start":"2026-02-25T19:27:10.724177Z","end":"2026-02-25T19:27:10.894732Z","steps":["trace[582618421] 'read index received' (duration: 170.413615ms)","trace[582618421] 'applied index is now lower than readState.Index' (duration: 139.634µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:10.894915Z","caller":"traceutil/trace.go:171","msg":"trace[1559586271] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"262.6942ms","start":"2026-02-25T19:27:10.632137Z","end":"2026-02-25T19:27:10.894831Z","steps":["trace[1559586271] 'process raft request' (duration: 262.395341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:10.895059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.845328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:10.895171Z","caller":"traceutil/trace.go:171","msg":"trace[988006405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"171.025433ms","start":"2026-02-25T19:27:10.724123Z","end":"2026-02-25T19:27:10.895148Z","steps":["trace[988006405] 'agreement among raft nodes before linearized reading' (duration: 170.790556ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:12.189093Z","caller":"traceutil/trace.go:171","msg":"trace[2002179729] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"175.229207ms","start":"2026-02-25T19:27:12.013837Z","end":"2026-02-25T19:27:12.189066Z","steps":["trace[2002179729] 'process raft request' (duration: 175.049492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:12.434484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:12.434765Z","caller":"traceutil/trace.go:171","msg":"trace[1862498789] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"372.79458ms","start":"2026-02-25T19:27:12.061951Z","end":"2026-02-25T19:27:12.434745Z","steps":["trace[1862498789] 'process raft request' (duration: 219.176532ms)","trace[1862498789] 'compare' (duration: 152.990381ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:12.434592Z","caller":"traceutil/trace.go:171","msg":"trace[207900524] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:945; }","duration":"368.025245ms","start":"2026-02-25T19:27:12.06655Z","end":"2026-02-25T19:27:12.434575Z","steps":["trace[207900524] 'read index received' (duration: 122.378897ms)","trace[207900524] 'applied index is now lower than readState.Index' (duration: 245.644798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:12.434859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:12.061918Z","time spent":"372.897413ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:12.435071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.815294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:27:12.435114Z","caller":"traceutil/trace.go:171","msg":"trace[1576274204] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:910; }","duration":"244.902017ms","start":"2026-02-25T19:27:12.1902Z","end":"2026-02-25T19:27:12.435102Z","steps":["trace[1576274204] 'agreement among raft nodes before linearized reading' (duration: 244.759173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:12.435114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.533949ms","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-25T19:27:12.435315Z","caller":"traceutil/trace.go:171","msg":"trace[1153845754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"368.759245ms","start":"2026-02-25T19:27:12.066497Z","end":"2026-02-25T19:27:12.435257Z","steps":["trace[1153845754] 'agreement among raft nodes before linearized reading' (duration: 368.360234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:12.43546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:12.066485Z","time spent":"368.936771ms","remote":"127.0.0.1:47306","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-25T19:27:12.718027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.895289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:12.718294Z","caller":"traceutil/trace.go:171","msg":"trace[1572677779] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"279.063194ms","start":"2026-02-25T19:27:12.439193Z","end":"2026-02-25T19:27:12.718256Z","steps":["trace[1572677779] 'process raft request' (duration: 146.832276ms)","trace[1572677779] 'compare' (duration: 131.741534ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:12.718305Z","caller":"traceutil/trace.go:171","msg":"trace[783179058] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"278.136417ms","start":"2026-02-25T19:27:12.440151Z","end":"2026-02-25T19:27:12.718287Z","steps":["trace[783179058] 'process raft request' (duration: 278.010643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:12.71867Z","caller":"traceutil/trace.go:171","msg":"trace[350591213] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"277.716715ms","start":"2026-02-25T19:27:12.440887Z","end":"2026-02-25T19:27:12.718603Z","steps":["trace[350591213] 'process raft request' (duration: 277.348454ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:12.721236Z","caller":"traceutil/trace.go:171","msg":"trace[410080873] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:947; }","duration":"235.507119ms","start":"2026-02-25T19:27:12.485693Z","end":"2026-02-25T19:27:12.7212Z","steps":["trace[410080873] 'read index received' (duration: 100.354667ms)","trace[410080873] 'applied index is now lower than readState.Index' (duration: 135.150792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:12.721564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.856618ms","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-25T19:27:12.721605Z","caller":"traceutil/trace.go:171","msg":"trace[1819383090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"235.950781ms","start":"2026-02-25T19:27:12.485645Z","end":"2026-02-25T19:27:12.721595Z","steps":["trace[1819383090] 'agreement among raft nodes before linearized reading' (duration: 235.659543ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:17.627555Z","caller":"traceutil/trace.go:171","msg":"trace[434490206] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"117.539764ms","start":"2026-02-25T19:27:17.509977Z","end":"2026-02-25T19:27:17.627516Z","steps":["trace[434490206] 'process raft request' (duration: 99.305429ms)","trace[434490206] 'compare' (duration: 17.906597ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:22.946135Z","caller":"traceutil/trace.go:171","msg":"trace[855704267] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"174.30839ms","start":"2026-02-25T19:27:22.771789Z","end":"2026-02-25T19:27:22.946098Z","steps":["trace[855704267] 'process raft request' (duration: 79.583328ms)","trace[855704267] 'compare' (duration: 94.482526ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:22.94612Z","caller":"traceutil/trace.go:171","msg":"trace[905477484] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:978; }","duration":"170.116901ms","start":"2026-02-25T19:27:22.775959Z","end":"2026-02-25T19:27:22.946076Z","steps":["trace[905477484] 'read index received' (duration: 20.129963ms)","trace[905477484] 'applied index is now lower than readState.Index' (duration: 149.984828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:22.946547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.408421ms","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-25T19:27:22.946638Z","caller":"traceutil/trace.go:171","msg":"trace[748592044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"162.539764ms","start":"2026-02-25T19:27:22.784078Z","end":"2026-02-25T19:27:22.946618Z","steps":["trace[748592044] 'agreement among raft nodes before linearized reading' (duration: 162.334279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:22.947083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.078717ms","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-25T19:27:22.947235Z","caller":"traceutil/trace.go:171","msg":"trace[1115853165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"171.301762ms","start":"2026-02-25T19:27:22.775902Z","end":"2026-02-25T19:27:22.947204Z","steps":["trace[1115853165] 'agreement among raft nodes before linearized reading' (duration: 170.257595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:23.192467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.054434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:23.192604Z","caller":"traceutil/trace.go:171","msg":"trace[219276049] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"207.010889ms","start":"2026-02-25T19:27:22.985573Z","end":"2026-02-25T19:27:23.192584Z","steps":["trace[219276049] 'read index received' (duration: 3.783269ms)","trace[219276049] 'applied index is now lower than readState.Index' (duration: 203.22645ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:23.192682Z","caller":"traceutil/trace.go:171","msg":"trace[702578669] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"239.74809ms","start":"2026-02-25T19:27:22.952899Z","end":"2026-02-25T19:27:23.192647Z","steps":["trace[702578669] 'process raft request' (duration: 93.386708ms)","trace[702578669] 'compare' (duration: 145.868119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:23.192876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.308626ms","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-25T19:27:23.192926Z","caller":"traceutil/trace.go:171","msg":"trace[975756103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"207.399249ms","start":"2026-02-25T19:27:22.985514Z","end":"2026-02-25T19:27:23.192913Z","steps":["trace[975756103] 'agreement among raft nodes before linearized reading' (duration: 207.189263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:23.193209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.99322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:23.193277Z","caller":"traceutil/trace.go:171","msg":"trace[629223776] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:942; }","duration":"137.132194ms","start":"2026-02-25T19:27:23.056129Z","end":"2026-02-25T19:27:23.193261Z","steps":["trace[629223776] 'agreement among raft nodes before linearized reading' (duration: 136.99309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:23.360356Z","caller":"traceutil/trace.go:171","msg":"trace[523625581] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"158.509096ms","start":"2026-02-25T19:27:23.201818Z","end":"2026-02-25T19:27:23.360327Z","steps":["trace[523625581] 'read index received' (duration: 156.501104ms)","trace[523625581] 'applied index is now lower than readState.Index' (duration: 2.006422ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:23.360446Z","caller":"traceutil/trace.go:171","msg":"trace[1704973493] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"162.893439ms","start":"2026-02-25T19:27:23.197508Z","end":"2026-02-25T19:27:23.360401Z","steps":["trace[1704973493] 'process raft request' (duration: 160.879327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:23.360545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.635989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:23.360625Z","caller":"traceutil/trace.go:171","msg":"trace[1289603565] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:943; }","duration":"158.819034ms","start":"2026-02-25T19:27:23.201789Z","end":"2026-02-25T19:27:23.360608Z","steps":["trace[1289603565] 'agreement among raft nodes before linearized reading' (duration: 158.633469ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:23.571632Z","caller":"traceutil/trace.go:171","msg":"trace[612609187] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"115.695302ms","start":"2026-02-25T19:27:23.455906Z","end":"2026-02-25T19:27:23.571601Z","steps":["trace[612609187] 'process raft request' (duration: 46.682823ms)","trace[612609187] 'compare' (duration: 68.773053ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:27.138149Z","caller":"traceutil/trace.go:171","msg":"trace[576818360] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"177.81653ms","start":"2026-02-25T19:27:26.960306Z","end":"2026-02-25T19:27:27.138123Z","steps":["trace[576818360] 'read index received' (duration: 177.657896ms)","trace[576818360] 'applied index is now lower than readState.Index' (duration: 157.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:27.138172Z","caller":"traceutil/trace.go:171","msg":"trace[416676264] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"233.238495ms","start":"2026-02-25T19:27:26.904909Z","end":"2026-02-25T19:27:27.138147Z","steps":["trace[416676264] 'process raft request' (duration: 233.05635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:27.138465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.103815ms","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-25T19:27:27.138535Z","caller":"traceutil/trace.go:171","msg":"trace[1593801079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"133.241268ms","start":"2026-02-25T19:27:27.005277Z","end":"2026-02-25T19:27:27.138518Z","steps":["trace[1593801079] 'agreement among raft nodes before linearized reading' (duration: 132.991122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:27.139032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.738502ms","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-25T19:27:27.13908Z","caller":"traceutil/trace.go:171","msg":"trace[74283811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"178.857126ms","start":"2026-02-25T19:27:26.960212Z","end":"2026-02-25T19:27:27.139069Z","steps":["trace[74283811] 'agreement among raft nodes before linearized reading' (duration: 178.732943ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:27.271371Z","caller":"traceutil/trace.go:171","msg":"trace[196947935] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"127.014493ms","start":"2026-02-25T19:27:27.144324Z","end":"2026-02-25T19:27:27.271338Z","steps":["trace[196947935] 'process raft request' (duration: 67.637529ms)","trace[196947935] 'compare' (duration: 59.134067ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:27.271701Z","caller":"traceutil/trace.go:171","msg":"trace[995943319] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"124.197013ms","start":"2026-02-25T19:27:27.147471Z","end":"2026-02-25T19:27:27.271668Z","steps":["trace[995943319] 'process raft request' (duration: 123.819333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:27.481025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.213638ms","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-25T19:27:27.48111Z","caller":"traceutil/trace.go:171","msg":"trace[1859156315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"101.398212ms","start":"2026-02-25T19:27:27.379691Z","end":"2026-02-25T19:27:27.481089Z","steps":["trace[1859156315] 'range keys from in-memory index tree' (duration: 100.947161ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:27.831104Z","caller":"traceutil/trace.go:171","msg":"trace[1298608474] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"106.91045ms","start":"2026-02-25T19:27:27.724165Z","end":"2026-02-25T19:27:27.831075Z","steps":["trace[1298608474] 'read index received' (duration: 106.676704ms)","trace[1298608474] 'applied index is now lower than readState.Index' (duration: 231.816µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:27.831137Z","caller":"traceutil/trace.go:171","msg":"trace[1281903297] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"340.491174ms","start":"2026-02-25T19:27:27.490601Z","end":"2026-02-25T19:27:27.831092Z","steps":["trace[1281903297] 'process raft request' (duration: 340.269419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:27.831266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.091375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:27:27.831321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:27.490566Z","time spent":"340.655659ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:27.831337Z","caller":"traceutil/trace.go:171","msg":"trace[883743448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"107.336491ms","start":"2026-02-25T19:27:27.723984Z","end":"2026-02-25T19:27:27.831321Z","steps":["trace[883743448] 'agreement among raft nodes before linearized reading' (duration: 107.151656ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:28.24496Z","caller":"traceutil/trace.go:171","msg":"trace[1348179980] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"178.083306ms","start":"2026-02-25T19:27:28.066849Z","end":"2026-02-25T19:27:28.244933Z","steps":["trace[1348179980] 'process raft request' (duration: 177.902141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:28.24519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.189642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-02-25T19:27:28.245254Z","caller":"traceutil/trace.go:171","msg":"trace[2136737922] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a; range_end:; response_count:1; response_revision:970; }","duration":"108.299015ms","start":"2026-02-25T19:27:28.136936Z","end":"2026-02-25T19:27:28.245235Z","steps":["trace[2136737922] 'agreement among raft nodes before linearized reading' (duration: 108.07437ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:28.244964Z","caller":"traceutil/trace.go:171","msg":"trace[1681583542] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"107.951837ms","start":"2026-02-25T19:27:28.136979Z","end":"2026-02-25T19:27:28.244931Z","steps":["trace[1681583542] 'read index received' (duration: 107.744381ms)","trace[1681583542] 'applied index is now lower than readState.Index' (duration: 205.306µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:28.483183Z","caller":"traceutil/trace.go:171","msg":"trace[1218095129] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"206.307945ms","start":"2026-02-25T19:27:28.276843Z","end":"2026-02-25T19:27:28.483151Z","steps":["trace[1218095129] 'read index received' (duration: 206.10782ms)","trace[1218095129] 'applied index is now lower than readState.Index' (duration: 198.805µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:28.483396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.528331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a\" ","response":"range_response_count:1 size:3693"} {"level":"info","ts":"2026-02-25T19:27:28.483516Z","caller":"traceutil/trace.go:171","msg":"trace[2002466088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a; range_end:; response_count:1; response_revision:971; }","duration":"206.707535ms","start":"2026-02-25T19:27:28.27678Z","end":"2026-02-25T19:27:28.483487Z","steps":["trace[2002466088] 'agreement among raft nodes before linearized reading' (duration: 206.48605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:28.484486Z","caller":"traceutil/trace.go:171","msg":"trace[2072207655] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"228.306ms","start":"2026-02-25T19:27:28.256143Z","end":"2026-02-25T19:27:28.484449Z","steps":["trace[2072207655] 'process raft request' (duration: 226.817003ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:31.409349Z","caller":"traceutil/trace.go:171","msg":"trace[916039561] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"253.080204ms","start":"2026-02-25T19:27:31.156239Z","end":"2026-02-25T19:27:31.409319Z","steps":["trace[916039561] 'read index received' (duration: 252.95158ms)","trace[916039561] 'applied index is now lower than readState.Index' (duration: 127.504µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:31.409427Z","caller":"traceutil/trace.go:171","msg":"trace[853811825] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"528.805257ms","start":"2026-02-25T19:27:30.88058Z","end":"2026-02-25T19:27:31.409385Z","steps":["trace[853811825] 'process raft request' (duration: 528.550231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.409626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.880567Z","time spent":"528.94844ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:31.409719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.980645ms","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-25T19:27:31.409772Z","caller":"traceutil/trace.go:171","msg":"trace[2000542611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"123.091938ms","start":"2026-02-25T19:27:31.286665Z","end":"2026-02-25T19:27:31.409757Z","steps":["trace[2000542611] 'agreement among raft nodes before linearized reading' (duration: 122.834012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.40976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.93965ms","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-25T19:27:31.409826Z","caller":"traceutil/trace.go:171","msg":"trace[654146662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"122.028422ms","start":"2026-02-25T19:27:31.28778Z","end":"2026-02-25T19:27:31.409809Z","steps":["trace[654146662] 'agreement among raft nodes before linearized reading' (duration: 121.845188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.409771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.488823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a\" ","response":"range_response_count:1 size:3879"} {"level":"info","ts":"2026-02-25T19:27:31.409961Z","caller":"traceutil/trace.go:171","msg":"trace[483266016] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a; range_end:; response_count:1; response_revision:979; }","duration":"253.701738ms","start":"2026-02-25T19:27:31.156177Z","end":"2026-02-25T19:27:31.409878Z","steps":["trace[483266016] 'agreement among raft nodes before linearized reading' (duration: 253.313099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.848283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.458012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:31.848363Z","caller":"traceutil/trace.go:171","msg":"trace[1989025403] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"432.560451ms","start":"2026-02-25T19:27:31.415789Z","end":"2026-02-25T19:27:31.84835Z","steps":["trace[1989025403] 'process raft request' (duration: 288.968275ms)","trace[1989025403] 'compare' (duration: 143.34155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:31.848404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.41576Z","time spent":"432.624612ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:31.98231Z","caller":"traceutil/trace.go:171","msg":"trace[1461916010] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"565.680532ms","start":"2026-02-25T19:27:31.416587Z","end":"2026-02-25T19:27:31.982268Z","steps":["trace[1461916010] 'process raft request' (duration: 565.498957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:31.982421Z","caller":"traceutil/trace.go:171","msg":"trace[684713103] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1021; }","duration":"480.425252ms","start":"2026-02-25T19:27:31.501961Z","end":"2026-02-25T19:27:31.982387Z","steps":["trace[684713103] 'read index received' (duration: 202.769423ms)","trace[684713103] 'applied index is now lower than readState.Index' (duration: 277.653519ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:31.982252Z","caller":"traceutil/trace.go:171","msg":"trace[1971910060] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"565.961298ms","start":"2026-02-25T19:27:31.416257Z","end":"2026-02-25T19:27:31.982218Z","steps":["trace[1971910060] 'process raft request' (duration: 558.677272ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:31.982573Z","caller":"traceutil/trace.go:171","msg":"trace[265938087] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"565.263882ms","start":"2026-02-25T19:27:31.417293Z","end":"2026-02-25T19:27:31.982557Z","steps":["trace[265938087] 'process raft request' (duration: 564.847082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.982751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.417283Z","time spent":"565.339793ms","remote":"127.0.0.1:47216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:31.982771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.416233Z","time spent":"566.368929ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:31.982527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.416576Z","time spent":"565.838045ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:31.983161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.182451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a\" ","response":"range_response_count:1 size:3881"} {"level":"warn","ts":"2026-02-25T19:27:31.983178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.320969ms","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-25T19:27:31.983276Z","caller":"traceutil/trace.go:171","msg":"trace[1383125077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"433.522224ms","start":"2026-02-25T19:27:31.549735Z","end":"2026-02-25T19:27:31.983257Z","steps":["trace[1383125077] 'agreement among raft nodes before linearized reading' (duration: 433.060822ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:31.983266Z","caller":"traceutil/trace.go:171","msg":"trace[1902540021] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a; range_end:; response_count:1; response_revision:983; }","duration":"481.326414ms","start":"2026-02-25T19:27:31.501915Z","end":"2026-02-25T19:27:31.983241Z","steps":["trace[1902540021] 'agreement among raft nodes before linearized reading' (duration: 480.970626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.983386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.54971Z","time spent":"433.658397ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:27:31.983465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.501897Z","time spent":"481.551559ms","remote":"127.0.0.1:47216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3904,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-784502f9-b759-465c-9fa8-50976e320d7a\" "} {"level":"info","ts":"2026-02-25T19:27:32.150895Z","caller":"traceutil/trace.go:171","msg":"trace[2055450189] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"115.367732ms","start":"2026-02-25T19:27:32.035475Z","end":"2026-02-25T19:27:32.150842Z","steps":["trace[2055450189] 'process raft request' (duration: 112.521233ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.358515Z","caller":"traceutil/trace.go:171","msg":"trace[390236376] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"147.922637ms","start":"2026-02-25T19:27:32.210564Z","end":"2026-02-25T19:27:32.358487Z","steps":["trace[390236376] 'process raft request' (duration: 147.787343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.396477Z","caller":"traceutil/trace.go:171","msg":"trace[1235724904] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"105.099164ms","start":"2026-02-25T19:27:32.291336Z","end":"2026-02-25T19:27:32.396435Z","steps":["trace[1235724904] 'process raft request' (duration: 98.74055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:32.977068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.52583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:32.977224Z","caller":"traceutil/trace.go:171","msg":"trace[947412312] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"162.787656ms","start":"2026-02-25T19:27:32.8144Z","end":"2026-02-25T19:27:32.977187Z","steps":["trace[947412312] 'process raft request' (duration: 58.0389ms)","trace[947412312] 'compare' (duration: 104.395227ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:33.312242Z","caller":"traceutil/trace.go:171","msg":"trace[1914597592] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"179.998402ms","start":"2026-02-25T19:27:33.132217Z","end":"2026-02-25T19:27:33.312215Z","steps":["trace[1914597592] 'process raft request' (duration: 179.779597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:33.646742Z","caller":"traceutil/trace.go:171","msg":"trace[1393559108] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"104.310209ms","start":"2026-02-25T19:27:33.542398Z","end":"2026-02-25T19:27:33.646708Z","steps":["trace[1393559108] 'read index received' (duration: 104.146185ms)","trace[1393559108] 'applied index is now lower than readState.Index' (duration: 162.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:33.646804Z","caller":"traceutil/trace.go:171","msg":"trace[642533764] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"137.465743ms","start":"2026-02-25T19:27:33.509297Z","end":"2026-02-25T19:27:33.646762Z","steps":["trace[642533764] 'process raft request' (duration: 137.233758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.646949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.529124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-02-25T19:27:33.64705Z","caller":"traceutil/trace.go:171","msg":"trace[483350255] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:997; }","duration":"104.667417ms","start":"2026-02-25T19:27:33.542345Z","end":"2026-02-25T19:27:33.647012Z","steps":["trace[483350255] 'agreement among raft nodes before linearized reading' (duration: 104.487393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:34.211723Z","caller":"traceutil/trace.go:171","msg":"trace[44135852] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"124.461172ms","start":"2026-02-25T19:27:34.087233Z","end":"2026-02-25T19:27:34.211694Z","steps":["trace[44135852] 'process raft request' (duration: 124.289798ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:34.257662Z","caller":"traceutil/trace.go:171","msg":"trace[1068466951] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1041; }","duration":"117.333782ms","start":"2026-02-25T19:27:34.140297Z","end":"2026-02-25T19:27:34.257631Z","steps":["trace[1068466951] 'read index received' (duration: 71.282676ms)","trace[1068466951] 'applied index is now lower than readState.Index' (duration: 46.050116ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:34.257713Z","caller":"traceutil/trace.go:171","msg":"trace[609313630] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"169.662399ms","start":"2026-02-25T19:27:34.088018Z","end":"2026-02-25T19:27:34.257681Z","steps":["trace[609313630] 'process raft request' (duration: 169.558296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:34.257713Z","caller":"traceutil/trace.go:171","msg":"trace[1982351130] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"170.102238ms","start":"2026-02-25T19:27:34.087567Z","end":"2026-02-25T19:27:34.257669Z","steps":["trace[1982351130] 'process raft request' (duration: 169.846272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:34.26316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.790722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-25T19:27:34.263366Z","caller":"traceutil/trace.go:171","msg":"trace[1457641729] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1001; }","duration":"123.108931ms","start":"2026-02-25T19:27:34.140232Z","end":"2026-02-25T19:27:34.263341Z","steps":["trace[1457641729] 'agreement among raft nodes before linearized reading' (duration: 117.546068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:36.262529Z","caller":"traceutil/trace.go:171","msg":"trace[2121651309] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"344.653237ms","start":"2026-02-25T19:27:35.917834Z","end":"2026-02-25T19:27:36.262487Z","steps":["trace[2121651309] 'process raft request' (duration: 344.299138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:36.262729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:35.917813Z","time spent":"344.81491ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:36.407535Z","caller":"traceutil/trace.go:171","msg":"trace[2139266046] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"138.363223ms","start":"2026-02-25T19:27:36.269134Z","end":"2026-02-25T19:27:36.407497Z","steps":["trace[2139266046] 'process raft request' (duration: 75.976306ms)","trace[2139266046] 'compare' (duration: 62.096041ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:36.40781Z","caller":"traceutil/trace.go:171","msg":"trace[1466123598] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"136.736275ms","start":"2026-02-25T19:27:36.271054Z","end":"2026-02-25T19:27:36.407791Z","steps":["trace[1466123598] 'process raft request' (duration: 136.381757ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:36.407811Z","caller":"traceutil/trace.go:171","msg":"trace[1682072869] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1049; }","duration":"100.930833ms","start":"2026-02-25T19:27:36.306858Z","end":"2026-02-25T19:27:36.407789Z","steps":["trace[1682072869] 'read index received' (duration: 38.29347ms)","trace[1682072869] 'applied index is now lower than readState.Index' (duration: 62.635463ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:36.408107Z","caller":"traceutil/trace.go:171","msg":"trace[1272817326] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"136.54149ms","start":"2026-02-25T19:27:36.27137Z","end":"2026-02-25T19:27:36.407911Z","steps":["trace[1272817326] 'process raft request' (duration: 136.348256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:36.40816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.287362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:27:36.408398Z","caller":"traceutil/trace.go:171","msg":"trace[1313048338] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1009; }","duration":"101.589589ms","start":"2026-02-25T19:27:36.306781Z","end":"2026-02-25T19:27:36.408371Z","steps":["trace[1313048338] 'agreement among raft nodes before linearized reading' (duration: 101.22877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.72683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.74922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:37.727068Z","caller":"traceutil/trace.go:171","msg":"trace[2073318851] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"263.722725ms","start":"2026-02-25T19:27:37.46332Z","end":"2026-02-25T19:27:37.727043Z","steps":["trace[2073318851] 'process raft request' (duration: 110.636956ms)","trace[2073318851] 'compare' (duration: 152.612147ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:38.440392Z","caller":"traceutil/trace.go:171","msg":"trace[149232547] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"166.800837ms","start":"2026-02-25T19:27:38.273538Z","end":"2026-02-25T19:27:38.440339Z","steps":["trace[149232547] 'process raft request' (duration: 166.349356ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:40.007508Z","caller":"traceutil/trace.go:171","msg":"trace[1724043182] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"250.288913ms","start":"2026-02-25T19:27:39.757188Z","end":"2026-02-25T19:27:40.007477Z","steps":["trace[1724043182] 'process raft request' (duration: 250.109749ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:50.740239Z","caller":"traceutil/trace.go:171","msg":"trace[846304996] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"117.324172ms","start":"2026-02-25T19:27:50.62288Z","end":"2026-02-25T19:27:50.740204Z","steps":["trace[846304996] 'read index received' (duration: 116.995995ms)","trace[846304996] 'applied index is now lower than readState.Index' (duration: 326.787µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:50.740344Z","caller":"traceutil/trace.go:171","msg":"trace[1855122234] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"150.796406ms","start":"2026-02-25T19:27:50.58951Z","end":"2026-02-25T19:27:50.740307Z","steps":["trace[1855122234] 'process raft request' (duration: 150.422248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:50.740572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.65446ms","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-25T19:27:50.740629Z","caller":"traceutil/trace.go:171","msg":"trace[1919881137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"117.789302ms","start":"2026-02-25T19:27:50.622821Z","end":"2026-02-25T19:27:50.74061Z","steps":["trace[1919881137] 'agreement among raft nodes before linearized reading' (duration: 117.578018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:51.403967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947718236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:51.620413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.115158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:51.620569Z","caller":"traceutil/trace.go:171","msg":"trace[140763956] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"873.564428ms","start":"2026-02-25T19:27:50.74698Z","end":"2026-02-25T19:27:51.620544Z","steps":["trace[140763956] 'process raft request' (duration: 93.221684ms)","trace[140763956] 'compare' (duration: 779.909224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:51.620637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:50.746959Z","time spent":"873.646479ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:51.632887Z","caller":"traceutil/trace.go:171","msg":"trace[17433174] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"885.409157ms","start":"2026-02-25T19:27:50.747451Z","end":"2026-02-25T19:27:51.63286Z","steps":["trace[17433174] 'process raft request' (duration: 885.10826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:51.633055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:50.747427Z","time spent":"885.530549ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:51.633116Z","caller":"traceutil/trace.go:171","msg":"trace[1264793778] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"885.58639ms","start":"2026-02-25T19:27:50.7475Z","end":"2026-02-25T19:27:51.633087Z","steps":["trace[1264793778] 'process raft request' (duration: 885.331694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:51.633267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:50.747492Z","time spent":"885.692943ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:51.633242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.297376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:27:51.633338Z","caller":"traceutil/trace.go:171","msg":"trace[22547993] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1054; }","duration":"730.47553ms","start":"2026-02-25T19:27:50.902843Z","end":"2026-02-25T19:27:51.633319Z","steps":["trace[22547993] 'agreement among raft nodes before linearized reading' (duration: 730.294016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:51.633381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:50.902815Z","time spent":"730.557092ms","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:27:51.634176Z","caller":"traceutil/trace.go:171","msg":"trace[1145313119] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"730.078461ms","start":"2026-02-25T19:27:50.902924Z","end":"2026-02-25T19:27:51.633003Z","steps":["trace[1145313119] 'read index received' (duration: 729.671942ms)","trace[1145313119] 'applied index is now lower than readState.Index' (duration: 405.349µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:51.81536Z","caller":"traceutil/trace.go:171","msg":"trace[1040893002] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"181.106834ms","start":"2026-02-25T19:27:51.634219Z","end":"2026-02-25T19:27:51.815326Z","steps":["trace[1040893002] 'read index received' (duration: 157.336565ms)","trace[1040893002] 'applied index is now lower than readState.Index' (duration: 23.768929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:51.815615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.346838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:51.815752Z","caller":"traceutil/trace.go:171","msg":"trace[1511728856] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1055; }","duration":"377.556703ms","start":"2026-02-25T19:27:51.438175Z","end":"2026-02-25T19:27:51.815731Z","steps":["trace[1511728856] 'agreement among raft nodes before linearized reading' (duration: 377.347369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:51.815807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:51.438149Z","time spent":"377.646245ms","remote":"127.0.0.1:47188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-02-25T19:27:51.817434Z","caller":"traceutil/trace.go:171","msg":"trace[1145144906] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"275.678038ms","start":"2026-02-25T19:27:51.539795Z","end":"2026-02-25T19:27:51.815473Z","steps":["trace[1145144906] 'process raft request' (duration: 251.875708ms)","trace[1145144906] 'compare' (duration: 23.511723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:53.24349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947718246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:53.558953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011518223s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:27:53.685451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569667872s","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-25T19:27:53.685514Z","caller":"traceutil/trace.go:171","msg":"trace[1603720069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"1.569793315s","start":"2026-02-25T19:27:52.115705Z","end":"2026-02-25T19:27:53.685498Z","steps":["trace[1603720069] 'range keys from in-memory index tree' (duration: 1.569490298s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.685489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.000393ms","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-25T19:27:53.685542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.115677Z","time spent":"1.569858757s","remote":"127.0.0.1:47306","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-25T19:27:53.685568Z","caller":"traceutil/trace.go:171","msg":"trace[1326873770] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1055; }","duration":"122.093135ms","start":"2026-02-25T19:27:53.563464Z","end":"2026-02-25T19:27:53.685557Z","steps":["trace[1326873770] 'range keys from in-memory index tree' (duration: 121.987603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.710506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.844648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:27:53.710766Z","caller":"traceutil/trace.go:171","msg":"trace[636358050] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"968.046366ms","start":"2026-02-25T19:27:52.742703Z","end":"2026-02-25T19:27:53.710749Z","steps":["trace[636358050] 'read index received' (duration: 817.022404ms)","trace[636358050] 'applied index is now lower than readState.Index' (duration: 151.023032ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:53.71088Z","caller":"traceutil/trace.go:171","msg":"trace[747965107] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"693.381213ms","start":"2026-02-25T19:27:53.01747Z","end":"2026-02-25T19:27:53.710851Z","steps":["trace[747965107] 'process raft request' (duration: 693.152058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.710932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.223119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:53.710965Z","caller":"traceutil/trace.go:171","msg":"trace[1193310745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"968.303961ms","start":"2026-02-25T19:27:52.742649Z","end":"2026-02-25T19:27:53.710953Z","steps":["trace[1193310745] 'agreement among raft nodes before linearized reading' (duration: 968.24119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.711006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.74263Z","time spent":"968.368332ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:53.711035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:53.017441Z","time spent":"693.508336ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:53.714273Z","caller":"traceutil/trace.go:171","msg":"trace[41156294] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"102.514762ms","start":"2026-02-25T19:27:53.611741Z","end":"2026-02-25T19:27:53.714256Z","steps":["trace[41156294] 'process raft request' (duration: 102.132763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.71468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.677381ms","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-25T19:27:53.714726Z","caller":"traceutil/trace.go:171","msg":"trace[228795652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"969.787513ms","start":"2026-02-25T19:27:52.744928Z","end":"2026-02-25T19:27:53.714716Z","steps":["trace[228795652] 'agreement among raft nodes before linearized reading' (duration: 969.274412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.714769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.744894Z","time spent":"969.856794ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:27:54.518021Z","caller":"traceutil/trace.go:171","msg":"trace[1263871586] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"226.959378ms","start":"2026-02-25T19:27:54.291006Z","end":"2026-02-25T19:27:54.517965Z","steps":["trace[1263871586] 'process raft request' (duration: 226.658232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:54.919055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.671291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:54.919187Z","caller":"traceutil/trace.go:171","msg":"trace[1326001839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"193.879785ms","start":"2026-02-25T19:27:54.725278Z","end":"2026-02-25T19:27:54.919157Z","steps":["trace[1326001839] 'range keys from in-memory index tree' (duration: 193.496307ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:55.908455Z","caller":"traceutil/trace.go:171","msg":"trace[61165439] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"161.249397ms","start":"2026-02-25T19:27:55.747179Z","end":"2026-02-25T19:27:55.908428Z","steps":["trace[61165439] 'read index received' (duration: 161.037413ms)","trace[61165439] 'applied index is now lower than readState.Index' (duration: 210.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:55.90861Z","caller":"traceutil/trace.go:171","msg":"trace[292558619] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"183.417505ms","start":"2026-02-25T19:27:55.725174Z","end":"2026-02-25T19:27:55.908591Z","steps":["trace[292558619] 'process raft request' (duration: 183.076058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:55.908972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.943361ms","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-25T19:27:55.909068Z","caller":"traceutil/trace.go:171","msg":"trace[349560719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"161.113315ms","start":"2026-02-25T19:27:55.747928Z","end":"2026-02-25T19:27:55.909041Z","steps":["trace[349560719] 'agreement among raft nodes before linearized reading' (duration: 160.858429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:55.90917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.590792ms","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-25T19:27:55.909209Z","caller":"traceutil/trace.go:171","msg":"trace[1820792264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"159.670814ms","start":"2026-02-25T19:27:55.749529Z","end":"2026-02-25T19:27:55.9092Z","steps":["trace[1820792264] 'agreement among raft nodes before linearized reading' (duration: 159.505781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:55.909313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.147437ms","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-25T19:27:55.909301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.044243ms","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-25T19:27:55.909336Z","caller":"traceutil/trace.go:171","msg":"trace[12844522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"162.232989ms","start":"2026-02-25T19:27:55.747098Z","end":"2026-02-25T19:27:55.909331Z","steps":["trace[12844522] 'agreement among raft nodes before linearized reading' (duration: 162.172598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:55.909353Z","caller":"traceutil/trace.go:171","msg":"trace[1099386043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"161.121495ms","start":"2026-02-25T19:27:55.74822Z","end":"2026-02-25T19:27:55.909341Z","steps":["trace[1099386043] 'agreement among raft nodes before linearized reading' (duration: 161.001913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.384904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.709303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:56.385067Z","caller":"traceutil/trace.go:171","msg":"trace[1375679574] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"470.47343ms","start":"2026-02-25T19:27:55.91456Z","end":"2026-02-25T19:27:56.385033Z","steps":["trace[1375679574] 'process raft request' (duration: 60.42586ms)","trace[1375679574] 'compare' (duration: 409.321704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:56.385166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.914536Z","time spent":"470.582002ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:56.49779Z","caller":"traceutil/trace.go:171","msg":"trace[328848035] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"583.013462ms","start":"2026-02-25T19:27:55.914738Z","end":"2026-02-25T19:27:56.497752Z","steps":["trace[328848035] 'process raft request' (duration: 571.654328ms)","trace[328848035] 'compare' (duration: 11.084198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:56.498007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.914694Z","time spent":"583.206756ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:56.498101Z","caller":"traceutil/trace.go:171","msg":"trace[329055517] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"581.495639ms","start":"2026-02-25T19:27:55.916576Z","end":"2026-02-25T19:27:56.498072Z","steps":["trace[329055517] 'process raft request' (duration: 581.10746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.498278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.916547Z","time spent":"581.640992ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:56.498455Z","caller":"traceutil/trace.go:171","msg":"trace[1154146187] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"580.469787ms","start":"2026-02-25T19:27:55.917951Z","end":"2026-02-25T19:27:56.498421Z","steps":["trace[1154146187] 'process raft request' (duration: 580.024707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.498665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.917934Z","time spent":"580.61645ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:57.614157Z","caller":"traceutil/trace.go:171","msg":"trace[1551507574] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"133.425299ms","start":"2026-02-25T19:27:57.480694Z","end":"2026-02-25T19:27:57.61412Z","steps":["trace[1551507574] 'process raft request' (duration: 94.671772ms)","trace[1551507574] 'compare' (duration: 38.573753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:01.096657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947718315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:01.597725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1223743892947718315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:01.764551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351972026s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:28:01.765119Z","caller":"traceutil/trace.go:171","msg":"trace[1399794694] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"1.352678791s","start":"2026-02-25T19:28:00.412403Z","end":"2026-02-25T19:28:01.765082Z","steps":["trace[1399794694] 'process raft request' (duration: 1.352465107s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.765313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.412366Z","time spent":"1.352835614s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:01.803208Z","caller":"traceutil/trace.go:171","msg":"trace[1161849825] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"1.207468258s","start":"2026-02-25T19:28:00.595714Z","end":"2026-02-25T19:28:01.803182Z","steps":["trace[1161849825] 'read index received' (duration: 1.169566269s)","trace[1161849825] 'applied index is now lower than readState.Index' (duration: 37.900799ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:01.803495Z","caller":"traceutil/trace.go:171","msg":"trace[112870243] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"1.389512738s","start":"2026-02-25T19:28:00.41396Z","end":"2026-02-25T19:28:01.803473Z","steps":["trace[112870243] 'process raft request' (duration: 1.374062162s)","trace[112870243] 'compare' (duration: 15.028797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:01.803721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20804247s","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-25T19:28:01.804107Z","caller":"traceutil/trace.go:171","msg":"trace[755539359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"1.208507779s","start":"2026-02-25T19:28:00.595588Z","end":"2026-02-25T19:28:01.804095Z","steps":["trace[755539359] 'agreement among raft nodes before linearized reading' (duration: 1.207964878s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.804239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.595554Z","time spent":"1.208653372s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:28:01.80436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.284064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:28:01.804601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203689118s","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-25T19:28:01.804734Z","caller":"traceutil/trace.go:171","msg":"trace[1302416191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"1.203867191s","start":"2026-02-25T19:28:00.600838Z","end":"2026-02-25T19:28:01.804705Z","steps":["trace[1302416191] 'agreement among raft nodes before linearized reading' (duration: 1.203467523s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.804799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.600821Z","time spent":"1.203962934s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:01.80464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208820997s","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-25T19:28:01.804905Z","caller":"traceutil/trace.go:171","msg":"trace[1335641152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"1.209137903s","start":"2026-02-25T19:28:00.595725Z","end":"2026-02-25T19:28:01.804862Z","steps":["trace[1335641152] 'agreement among raft nodes before linearized reading' (duration: 1.208363917s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.805031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.5956Z","time spent":"1.209386978s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:01.805019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.413933Z","time spent":"1.390013968s","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:01.805246Z","caller":"traceutil/trace.go:171","msg":"trace[202231703] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1079; }","duration":"981.426607ms","start":"2026-02-25T19:28:00.822995Z","end":"2026-02-25T19:28:01.804421Z","steps":["trace[202231703] 'agreement among raft nodes before linearized reading' (duration: 981.242103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.805333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.822973Z","time spent":"982.342176ms","remote":"127.0.0.1:38224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:28:04.09127Z","caller":"traceutil/trace.go:171","msg":"trace[114402005] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"174.102135ms","start":"2026-02-25T19:28:03.917137Z","end":"2026-02-25T19:28:04.091239Z","steps":["trace[114402005] 'process raft request' (duration: 173.194566ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.091381Z","caller":"traceutil/trace.go:171","msg":"trace[2117872207] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"174.009013ms","start":"2026-02-25T19:28:03.917358Z","end":"2026-02-25T19:28:04.091367Z","steps":["trace[2117872207] 'read index received' (duration: 30.52908ms)","trace[2117872207] 'applied index is now lower than readState.Index' (duration: 143.478663ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:04.091472Z","caller":"traceutil/trace.go:171","msg":"trace[1621963806] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"174.043454ms","start":"2026-02-25T19:28:03.91742Z","end":"2026-02-25T19:28:04.091463Z","steps":["trace[1621963806] 'process raft request' (duration: 173.767828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.0916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.219218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-02-25T19:28:04.091644Z","caller":"traceutil/trace.go:171","msg":"trace[3271204] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1088; }","duration":"174.309319ms","start":"2026-02-25T19:28:03.917314Z","end":"2026-02-25T19:28:04.091624Z","steps":["trace[3271204] 'agreement among raft nodes before linearized reading' (duration: 174.224607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.09785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.08163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:720"} {"level":"info","ts":"2026-02-25T19:28:04.097972Z","caller":"traceutil/trace.go:171","msg":"trace[731366445] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1089; }","duration":"108.229423ms","start":"2026-02-25T19:28:03.989715Z","end":"2026-02-25T19:28:04.097944Z","steps":["trace[731366445] 'agreement among raft nodes before linearized reading' (duration: 107.914576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.330418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.610751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-02-25T19:28:06.330589Z","caller":"traceutil/trace.go:171","msg":"trace[1598738476] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1094; }","duration":"116.853155ms","start":"2026-02-25T19:28:06.213705Z","end":"2026-02-25T19:28:06.330558Z","steps":["trace[1598738476] 'range keys from in-memory index tree' (duration: 116.469169ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:08.324413Z","caller":"traceutil/trace.go:171","msg":"trace[1068793164] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"194.721167ms","start":"2026-02-25T19:28:08.12966Z","end":"2026-02-25T19:28:08.324381Z","steps":["trace[1068793164] 'read index received' (duration: 191.699632ms)","trace[1068793164] 'applied index is now lower than readState.Index' (duration: 3.020495ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:08.324587Z","caller":"traceutil/trace.go:171","msg":"trace[553361233] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"196.663327ms","start":"2026-02-25T19:28:08.127878Z","end":"2026-02-25T19:28:08.324541Z","steps":["trace[553361233] 'process raft request' (duration: 193.484889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:08.324733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.046802ms","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-25T19:28:08.324778Z","caller":"traceutil/trace.go:171","msg":"trace[310148417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"195.158314ms","start":"2026-02-25T19:28:08.129606Z","end":"2026-02-25T19:28:08.324765Z","steps":["trace[310148417] 'agreement among raft nodes before linearized reading' (duration: 194.926981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:08.324753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.609068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-25T19:28:08.324862Z","caller":"traceutil/trace.go:171","msg":"trace[299694695] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1099; }","duration":"176.73699ms","start":"2026-02-25T19:28:08.148096Z","end":"2026-02-25T19:28:08.324833Z","steps":["trace[299694695] 'agreement among raft nodes before linearized reading' (duration: 176.494916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:08.752169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.163283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-25T19:28:08.752289Z","caller":"traceutil/trace.go:171","msg":"trace[1603195607] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1100; }","duration":"248.317075ms","start":"2026-02-25T19:28:08.503944Z","end":"2026-02-25T19:28:08.752261Z","steps":["trace[1603195607] 'range keys from in-memory index tree' (duration: 248.074771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:08.752439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.152552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-02-25T19:28:08.752506Z","caller":"traceutil/trace.go:171","msg":"trace[1294223232] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1100; }","duration":"256.281963ms","start":"2026-02-25T19:28:08.496202Z","end":"2026-02-25T19:28:08.752484Z","steps":["trace[1294223232] 'range keys from in-memory index tree' (duration: 256.03932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:10.122854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.074655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-25T19:28:10.123Z","caller":"traceutil/trace.go:171","msg":"trace[1335154809] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1101; }","duration":"125.280148ms","start":"2026-02-25T19:28:09.997693Z","end":"2026-02-25T19:28:10.122973Z","steps":["trace[1335154809] 'range keys from in-memory index tree' (duration: 124.919613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:10.123036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.879147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-02-25T19:28:10.123339Z","caller":"traceutil/trace.go:171","msg":"trace[27936967] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1101; }","duration":"122.108751ms","start":"2026-02-25T19:28:10.001059Z","end":"2026-02-25T19:28:10.123168Z","steps":["trace[27936967] 'range keys from in-memory index tree' (duration: 121.749736ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:10.663717Z","caller":"traceutil/trace.go:171","msg":"trace[1010887481] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"152.521008ms","start":"2026-02-25T19:28:10.511164Z","end":"2026-02-25T19:28:10.663685Z","steps":["trace[1010887481] 'read index received' (duration: 139.044005ms)","trace[1010887481] 'applied index is now lower than readState.Index' (duration: 13.475833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:10.663942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.756951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:coredns\" ","response":"range_response_count:1 size:388"} {"level":"info","ts":"2026-02-25T19:28:10.663984Z","caller":"traceutil/trace.go:171","msg":"trace[952547568] range","detail":"{range_begin:/registry/clusterroles/system:coredns; range_end:; response_count:1; response_revision:1105; }","duration":"152.842232ms","start":"2026-02-25T19:28:10.511132Z","end":"2026-02-25T19:28:10.663974Z","steps":["trace[952547568] 'agreement among raft nodes before linearized reading' (duration: 152.71022ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:10.664099Z","caller":"traceutil/trace.go:171","msg":"trace[1095703212] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"256.305923ms","start":"2026-02-25T19:28:10.40749Z","end":"2026-02-25T19:28:10.663796Z","steps":["trace[1095703212] 'process raft request' (duration: 242.707239ms)","trace[1095703212] 'compare' (duration: 13.30489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:10.664457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.459507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-02-25T19:28:10.664557Z","caller":"traceutil/trace.go:171","msg":"trace[386720905] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1105; }","duration":"142.605158ms","start":"2026-02-25T19:28:10.521924Z","end":"2026-02-25T19:28:10.66453Z","steps":["trace[386720905] 'agreement among raft nodes before linearized reading' (duration: 142.415165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:12.173054Z","caller":"traceutil/trace.go:171","msg":"trace[1421137229] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"100.005851ms","start":"2026-02-25T19:28:12.07296Z","end":"2026-02-25T19:28:12.172966Z","steps":["trace[1421137229] 'range keys from in-memory index tree' (duration: 99.803788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.513457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.291922ms","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-25T19:28:12.513542Z","caller":"traceutil/trace.go:171","msg":"trace[417465056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1108; }","duration":"115.419863ms","start":"2026-02-25T19:28:12.398106Z","end":"2026-02-25T19:28:12.513526Z","steps":["trace[417465056] 'range keys from in-memory index tree' (duration: 115.031318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.513652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.260731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-25T19:28:12.513763Z","caller":"traceutil/trace.go:171","msg":"trace[2004893826] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1108; }","duration":"162.409014ms","start":"2026-02-25T19:28:12.351335Z","end":"2026-02-25T19:28:12.513744Z","steps":["trace[2004893826] 'range keys from in-memory index tree' (duration: 161.876156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.513826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.224615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-02-25T19:28:12.513958Z","caller":"traceutil/trace.go:171","msg":"trace[177870120] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1108; }","duration":"202.428308ms","start":"2026-02-25T19:28:12.311487Z","end":"2026-02-25T19:28:12.513915Z","steps":["trace[177870120] 'range keys from in-memory index tree' (duration: 201.585205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.962275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.35383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"warn","ts":"2026-02-25T19:28:12.962277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-02-25T19:28:12.962351Z","caller":"traceutil/trace.go:171","msg":"trace[589989078] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1110; }","duration":"144.509383ms","start":"2026-02-25T19:28:12.817822Z","end":"2026-02-25T19:28:12.962332Z","steps":["trace[589989078] 'range keys from in-memory index tree' (duration: 144.238009ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:12.962361Z","caller":"traceutil/trace.go:171","msg":"trace[584910833] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1110; }","duration":"141.789022ms","start":"2026-02-25T19:28:12.820555Z","end":"2026-02-25T19:28:12.962344Z","steps":["trace[584910833] 'range keys from in-memory index tree' (duration: 141.518328ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:14.224717Z","caller":"traceutil/trace.go:171","msg":"trace[1678177500] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"229.871678ms","start":"2026-02-25T19:28:13.994821Z","end":"2026-02-25T19:28:14.224693Z","steps":["trace[1678177500] 'read index received' (duration: 229.585163ms)","trace[1678177500] 'applied index is now lower than readState.Index' (duration: 285.625µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:14.224881Z","caller":"traceutil/trace.go:171","msg":"trace[498609032] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"276.86233ms","start":"2026-02-25T19:28:13.947987Z","end":"2026-02-25T19:28:14.224849Z","steps":["trace[498609032] 'process raft request' (duration: 276.499444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.224932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.090041ms","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-25T19:28:14.225044Z","caller":"traceutil/trace.go:171","msg":"trace[1262878451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"230.241983ms","start":"2026-02-25T19:28:13.994792Z","end":"2026-02-25T19:28:14.225034Z","steps":["trace[1262878451] 'agreement among raft nodes before linearized reading' (duration: 230.03345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.225233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.865021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-02-25T19:28:14.225315Z","caller":"traceutil/trace.go:171","msg":"trace[1021230345] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1111; }","duration":"199.994404ms","start":"2026-02-25T19:28:14.025298Z","end":"2026-02-25T19:28:14.225292Z","steps":["trace[1021230345] 'agreement among raft nodes before linearized reading' (duration: 199.832261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.225244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.314036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-25T19:28:14.225464Z","caller":"traceutil/trace.go:171","msg":"trace[520305140] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1111; }","duration":"217.586571ms","start":"2026-02-25T19:28:14.007848Z","end":"2026-02-25T19:28:14.225434Z","steps":["trace[520305140] 'agreement among raft nodes before linearized reading' (duration: 217.230545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:14.378612Z","caller":"traceutil/trace.go:171","msg":"trace[816047769] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1163; }","duration":"146.466778ms","start":"2026-02-25T19:28:14.232107Z","end":"2026-02-25T19:28:14.378573Z","steps":["trace[816047769] 'read index received' (duration: 42.466256ms)","trace[816047769] 'applied index is now lower than readState.Index' (duration: 103.998342ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:14.378918Z","caller":"traceutil/trace.go:171","msg":"trace[657512728] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"148.651722ms","start":"2026-02-25T19:28:14.230251Z","end":"2026-02-25T19:28:14.378903Z","steps":["trace[657512728] 'process raft request' (duration: 148.243426ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:14.378884Z","caller":"traceutil/trace.go:171","msg":"trace[2455882] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"148.660772ms","start":"2026-02-25T19:28:14.230168Z","end":"2026-02-25T19:28:14.378829Z","steps":["trace[2455882] 'process raft request' (duration: 87.248188ms)","trace[2455882] 'compare' (duration: 60.833605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:14.379135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.963996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-25T19:28:14.379236Z","caller":"traceutil/trace.go:171","msg":"trace[1788220750] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1114; }","duration":"147.107618ms","start":"2026-02-25T19:28:14.232103Z","end":"2026-02-25T19:28:14.37921Z","steps":["trace[1788220750] 'agreement among raft nodes before linearized reading' (duration: 146.884594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.59552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.275281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:14.595627Z","caller":"traceutil/trace.go:171","msg":"trace[2066048800] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"130.84876ms","start":"2026-02-25T19:28:14.464761Z","end":"2026-02-25T19:28:14.59561Z","steps":["trace[2066048800] 'read index received' (duration: 25.332795ms)","trace[2066048800] 'applied index is now lower than readState.Index' (duration: 105.514415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:14.595938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.157485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-25T19:28:14.596091Z","caller":"traceutil/trace.go:171","msg":"trace[202001134] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1115; }","duration":"131.314147ms","start":"2026-02-25T19:28:14.464718Z","end":"2026-02-25T19:28:14.596032Z","steps":["trace[202001134] 'agreement among raft nodes before linearized reading' (duration: 131.103994ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:14.596074Z","caller":"traceutil/trace.go:171","msg":"trace[863544079] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"144.314445ms","start":"2026-02-25T19:28:14.451675Z","end":"2026-02-25T19:28:14.59599Z","steps":["trace[863544079] 'process raft request' (duration: 38.474085ms)","trace[863544079] 'compare' (duration: 104.862805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:14.596203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.358772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-02-25T19:28:14.596315Z","caller":"traceutil/trace.go:171","msg":"trace[1970498464] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1115; }","duration":"126.513634ms","start":"2026-02-25T19:28:14.469779Z","end":"2026-02-25T19:28:14.596293Z","steps":["trace[1970498464] 'agreement among raft nodes before linearized reading' (duration: 126.280821ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:16.518609Z","caller":"traceutil/trace.go:171","msg":"trace[1115534000] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"129.910691ms","start":"2026-02-25T19:28:16.388649Z","end":"2026-02-25T19:28:16.518559Z","steps":["trace[1115534000] 'process raft request' (duration: 78.33709ms)","trace[1115534000] 'compare' (duration: 51.188395ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:16.518928Z","caller":"traceutil/trace.go:171","msg":"trace[860765304] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"130.099943ms","start":"2026-02-25T19:28:16.388767Z","end":"2026-02-25T19:28:16.518867Z","steps":["trace[860765304] 'process raft request' (duration: 129.650557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:16.872149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.341836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:16.872248Z","caller":"traceutil/trace.go:171","msg":"trace[464941896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"147.462718ms","start":"2026-02-25T19:28:16.72476Z","end":"2026-02-25T19:28:16.872223Z","steps":["trace[464941896] 'range keys from in-memory index tree' (duration: 147.161244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:16.872413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.390999ms","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-25T19:28:16.872537Z","caller":"traceutil/trace.go:171","msg":"trace[1768084667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"202.547161ms","start":"2026-02-25T19:28:16.669958Z","end":"2026-02-25T19:28:16.872505Z","steps":["trace[1768084667] 'range keys from in-memory index tree' (duration: 202.149476ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:17.750502Z","caller":"traceutil/trace.go:171","msg":"trace[1410007326] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"251.939243ms","start":"2026-02-25T19:28:17.498542Z","end":"2026-02-25T19:28:17.750481Z","steps":["trace[1410007326] 'read index received' (duration: 251.729059ms)","trace[1410007326] 'applied index is now lower than readState.Index' (duration: 207.004µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:17.750569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:17.426Z","time spent":"324.556899ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:28:17.750705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.135676ms","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-25T19:28:17.750782Z","caller":"traceutil/trace.go:171","msg":"trace[13901754] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1122; }","duration":"252.273118ms","start":"2026-02-25T19:28:17.498475Z","end":"2026-02-25T19:28:17.750748Z","steps":["trace[13901754] 'agreement among raft nodes before linearized reading' (duration: 252.153606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:18.103145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.823308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:18.103314Z","caller":"traceutil/trace.go:171","msg":"trace[1724727495] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"351.233745ms","start":"2026-02-25T19:28:17.752061Z","end":"2026-02-25T19:28:18.103295Z","steps":["trace[1724727495] 'process raft request' (duration: 216.187383ms)","trace[1724727495] 'compare' (duration: 134.714826ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:18.103419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:17.752007Z","time spent":"351.369587ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:18.691393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.432897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:18.691592Z","caller":"traceutil/trace.go:171","msg":"trace[802206605] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1177; }","duration":"104.829777ms","start":"2026-02-25T19:28:18.586743Z","end":"2026-02-25T19:28:18.691573Z","steps":["trace[802206605] 'read index received' (duration: 63.266656ms)","trace[802206605] 'applied index is now lower than readState.Index' (duration: 41.562061ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:18.6916Z","caller":"traceutil/trace.go:171","msg":"trace[297225156] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"165.069936ms","start":"2026-02-25T19:28:18.526498Z","end":"2026-02-25T19:28:18.691568Z","steps":["trace[297225156] 'process raft request' (duration: 33.524117ms)","trace[297225156] 'compare' (duration: 131.131042ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:18.691806Z","caller":"traceutil/trace.go:171","msg":"trace[912727670] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"164.667139ms","start":"2026-02-25T19:28:18.527128Z","end":"2026-02-25T19:28:18.691795Z","steps":["trace[912727670] 'process raft request' (duration: 164.388405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:18.692189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.431836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/default/\" range_end:\"/registry/cronjobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:18.692232Z","caller":"traceutil/trace.go:171","msg":"trace[88032937] range","detail":"{range_begin:/registry/cronjobs/default/; range_end:/registry/cronjobs/default0; response_count:0; response_revision:1126; }","duration":"105.530938ms","start":"2026-02-25T19:28:18.586689Z","end":"2026-02-25T19:28:18.69222Z","steps":["trace[88032937] 'agreement among raft nodes before linearized reading' (duration: 105.452766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.142995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.791672ms","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-25T19:28:19.143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.349354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-25T19:28:19.143087Z","caller":"traceutil/trace.go:171","msg":"trace[1855262451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1127; }","duration":"172.920163ms","start":"2026-02-25T19:28:18.970145Z","end":"2026-02-25T19:28:19.143065Z","steps":["trace[1855262451] 'range keys from in-memory index tree' (duration: 172.554448ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.143105Z","caller":"traceutil/trace.go:171","msg":"trace[814286323] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1127; }","duration":"205.499176ms","start":"2026-02-25T19:28:18.937581Z","end":"2026-02-25T19:28:19.14308Z","steps":["trace[814286323] 'range keys from in-memory index tree' (duration: 205.253102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.143132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.587375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/default/\" range_end:\"/registry/deployments/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:19.143201Z","caller":"traceutil/trace.go:171","msg":"trace[1968113596] range","detail":"{range_begin:/registry/deployments/default/; range_end:/registry/deployments/default0; response_count:0; response_revision:1127; }","duration":"166.656397ms","start":"2026-02-25T19:28:18.976517Z","end":"2026-02-25T19:28:19.143174Z","steps":["trace[1968113596] 'range keys from in-memory index tree' (duration: 166.546055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.14321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.29067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-25T19:28:19.143263Z","caller":"traceutil/trace.go:171","msg":"trace[1745775108] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1127; }","duration":"177.371852ms","start":"2026-02-25T19:28:18.965875Z","end":"2026-02-25T19:28:19.143247Z","steps":["trace[1745775108] 'range keys from in-memory index tree' (duration: 177.21286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.143062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.877761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-node-lease/\" range_end:\"/registry/deployments/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:19.14339Z","caller":"traceutil/trace.go:171","msg":"trace[585356171] range","detail":"{range_begin:/registry/deployments/kube-node-lease/; range_end:/registry/deployments/kube-node-lease0; response_count:0; response_revision:1127; }","duration":"143.235406ms","start":"2026-02-25T19:28:19.000136Z","end":"2026-02-25T19:28:19.143371Z","steps":["trace[585356171] 'range keys from in-memory index tree' (duration: 142.777858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.224101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.989944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"info","ts":"2026-02-25T19:28:23.224214Z","caller":"traceutil/trace.go:171","msg":"trace[1212484166] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1137; }","duration":"142.143617ms","start":"2026-02-25T19:28:23.082047Z","end":"2026-02-25T19:28:23.224191Z","steps":["trace[1212484166] 'range keys from in-memory index tree' (duration: 141.868192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.224383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.48554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/\" range_end:\"/registry/pods/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3962"} {"level":"info","ts":"2026-02-25T19:28:23.224515Z","caller":"traceutil/trace.go:171","msg":"trace[434680587] range","detail":"{range_begin:/registry/pods/local-path-storage/; range_end:/registry/pods/local-path-storage0; response_count:1; response_revision:1137; }","duration":"108.640192ms","start":"2026-02-25T19:28:23.115843Z","end":"2026-02-25T19:28:23.224484Z","steps":["trace[434680587] 'range keys from in-memory index tree' (duration: 108.238025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.573793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.296348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"info","ts":"2026-02-25T19:28:23.573884Z","caller":"traceutil/trace.go:171","msg":"trace[1144131775] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:1138; }","duration":"140.50057ms","start":"2026-02-25T19:28:23.433359Z","end":"2026-02-25T19:28:23.57386Z","steps":["trace[1144131775] 'range keys from in-memory index tree' (duration: 140.173255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.573784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.256303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/\" range_end:\"/registry/serviceaccounts/local-path-storage0\" limit:500 ","response":"range_response_count:2 size:1083"} {"level":"info","ts":"2026-02-25T19:28:23.574092Z","caller":"traceutil/trace.go:171","msg":"trace[1839117928] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/; range_end:/registry/serviceaccounts/local-path-storage0; response_count:2; response_revision:1138; }","duration":"141.640498ms","start":"2026-02-25T19:28:23.432401Z","end":"2026-02-25T19:28:23.574042Z","steps":["trace[1839117928] 'range keys from in-memory index tree' (duration: 141.040439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.574313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.359115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-02-25T19:28:23.574381Z","caller":"traceutil/trace.go:171","msg":"trace[988899657] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:1138; }","duration":"127.473756ms","start":"2026-02-25T19:28:23.446895Z","end":"2026-02-25T19:28:23.574369Z","steps":["trace[988899657] 'range keys from in-memory index tree' (duration: 126.532942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.954872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.034737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-02-25T19:28:24.95498Z","caller":"traceutil/trace.go:171","msg":"trace[27646239] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:1139; }","duration":"411.159808ms","start":"2026-02-25T19:28:24.543785Z","end":"2026-02-25T19:28:24.954945Z","steps":["trace[27646239] 'range keys from in-memory index tree' (duration: 410.940336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.954966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.560957ms","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-25T19:28:24.955435Z","caller":"traceutil/trace.go:171","msg":"trace[955131670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1139; }","duration":"344.069694ms","start":"2026-02-25T19:28:24.611338Z","end":"2026-02-25T19:28:24.955408Z","steps":["trace[955131670] 'range keys from in-memory index tree' (duration: 343.376064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.955421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.543778Z","time spent":"411.619976ms","remote":"127.0.0.1:47368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":752,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" "} {"level":"warn","ts":"2026-02-25T19:28:24.955269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.7744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"info","ts":"2026-02-25T19:28:24.955727Z","caller":"traceutil/trace.go:171","msg":"trace[661356200] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1139; }","duration":"414.261818ms","start":"2026-02-25T19:28:24.541422Z","end":"2026-02-25T19:28:24.955684Z","steps":["trace[661356200] 'range keys from in-memory index tree' (duration: 413.067319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.955772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.611317Z","time spent":"344.171297ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:28:24.955798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.541406Z","time spent":"414.376409ms","remote":"127.0.0.1:47368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":756,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" "} {"level":"info","ts":"2026-02-25T19:28:25.078159Z","caller":"traceutil/trace.go:171","msg":"trace[35686497] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"353.044709ms","start":"2026-02-25T19:28:24.725089Z","end":"2026-02-25T19:28:25.078134Z","steps":["trace[35686497] 'read index received' (duration: 352.930717ms)","trace[35686497] 'applied index is now lower than readState.Index' (duration: 112.532µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:25.078276Z","caller":"traceutil/trace.go:171","msg":"trace[821887961] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"419.747618ms","start":"2026-02-25T19:28:24.658494Z","end":"2026-02-25T19:28:25.078242Z","steps":["trace[821887961] 'process raft request' (duration: 419.494144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.078307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.209982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:25.078336Z","caller":"traceutil/trace.go:171","msg":"trace[1020247245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"353.299543ms","start":"2026-02-25T19:28:24.725028Z","end":"2026-02-25T19:28:25.078327Z","steps":["trace[1020247245] 'agreement among raft nodes before linearized reading' (duration: 353.230782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.078362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.724997Z","time spent":"353.359775ms","remote":"127.0.0.1:47024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:25.080087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.658458Z","time spent":"421.546265ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:25.144175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.427611ms","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-25T19:28:25.144182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.815734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:1 size:733"} {"level":"warn","ts":"2026-02-25T19:28:25.144285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.730599ms","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-25T19:28:25.14435Z","caller":"traceutil/trace.go:171","msg":"trace[1821865914] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:cloud-provider; range_end:; response_count:1; response_revision:1141; }","duration":"184.034078ms","start":"2026-02-25T19:28:24.960284Z","end":"2026-02-25T19:28:25.144318Z","steps":["trace[1821865914] 'agreement among raft nodes before linearized reading' (duration: 183.644782ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:25.144374Z","caller":"traceutil/trace.go:171","msg":"trace[1305356572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"354.860492ms","start":"2026-02-25T19:28:24.789493Z","end":"2026-02-25T19:28:25.144354Z","steps":["trace[1305356572] 'agreement among raft nodes before linearized reading' (duration: 354.532206ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:25.144269Z","caller":"traceutil/trace.go:171","msg":"trace[1185737265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1141; }","duration":"371.578884ms","start":"2026-02-25T19:28:24.772675Z","end":"2026-02-25T19:28:25.144253Z","steps":["trace[1185737265] 'agreement among raft nodes before linearized reading' (duration: 371.280369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.144439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.789482Z","time spent":"354.944163ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:25.144469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.772652Z","time spent":"371.801997ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:28:25.144146Z","caller":"traceutil/trace.go:171","msg":"trace[2124419148] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"182.130018ms","start":"2026-02-25T19:28:24.961959Z","end":"2026-02-25T19:28:25.144089Z","steps":["trace[2124419148] 'process raft request' (duration: 181.685721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.144178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.790514ms","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-25T19:28:25.144665Z","caller":"traceutil/trace.go:171","msg":"trace[755274883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1141; }","duration":"149.343403ms","start":"2026-02-25T19:28:24.995307Z","end":"2026-02-25T19:28:25.14465Z","steps":["trace[755274883] 'agreement among raft nodes before linearized reading' (duration: 148.597321ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:29.306447Z","caller":"traceutil/trace.go:171","msg":"trace[447240310] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"113.511418ms","start":"2026-02-25T19:28:29.192901Z","end":"2026-02-25T19:28:29.306413Z","steps":["trace[447240310] 'read index received' (duration: 113.286634ms)","trace[447240310] 'applied index is now lower than readState.Index' (duration: 222.634µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:29.306621Z","caller":"traceutil/trace.go:171","msg":"trace[2080333033] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"134.592243ms","start":"2026-02-25T19:28:29.171988Z","end":"2026-02-25T19:28:29.30658Z","steps":["trace[2080333033] 'process raft request' (duration: 134.180327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:29.30669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.770742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-25T19:28:29.30673Z","caller":"traceutil/trace.go:171","msg":"trace[295020865] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1152; }","duration":"113.867693ms","start":"2026-02-25T19:28:29.19285Z","end":"2026-02-25T19:28:29.306718Z","steps":["trace[295020865] 'agreement among raft nodes before linearized reading' (duration: 113.730491ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:29.467683Z","caller":"traceutil/trace.go:171","msg":"trace[65892955] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"154.165565ms","start":"2026-02-25T19:28:29.313481Z","end":"2026-02-25T19:28:29.467647Z","steps":["trace[65892955] 'process raft request' (duration: 74.368445ms)","trace[65892955] 'compare' (duration: 79.605207ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:29.467903Z","caller":"traceutil/trace.go:171","msg":"trace[1112582125] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1207; }","duration":"152.014281ms","start":"2026-02-25T19:28:29.315869Z","end":"2026-02-25T19:28:29.467883Z","steps":["trace[1112582125] 'read index received' (duration: 25.226922ms)","trace[1112582125] 'applied index is now lower than readState.Index' (duration: 126.786169ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:29.468117Z","caller":"traceutil/trace.go:171","msg":"trace[907061108] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"154.544012ms","start":"2026-02-25T19:28:29.31356Z","end":"2026-02-25T19:28:29.468104Z","steps":["trace[907061108] 'process raft request' (duration: 154.028273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:29.468278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.382407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T19:28:29.468332Z","caller":"traceutil/trace.go:171","msg":"trace[815241740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:1155; }","duration":"152.492319ms","start":"2026-02-25T19:28:29.315823Z","end":"2026-02-25T19:28:29.468315Z","steps":["trace[815241740] 'agreement among raft nodes before linearized reading' (duration: 152.380187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:29.709889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.800007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-02-25T19:28:29.709964Z","caller":"traceutil/trace.go:171","msg":"trace[1685818627] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1156; }","duration":"139.908998ms","start":"2026-02-25T19:28:29.570038Z","end":"2026-02-25T19:28:29.709947Z","steps":["trace[1685818627] 'range keys from in-memory index tree' (duration: 139.246267ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:34.967731Z","caller":"traceutil/trace.go:171","msg":"trace[350021304] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"119.240593ms","start":"2026-02-25T19:28:34.848457Z","end":"2026-02-25T19:28:34.967697Z","steps":["trace[350021304] 'process raft request' (duration: 119.01744ms)"],"step_count":1}