{"level":"warn","ts":"2026-03-19T17:52:22.714458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:22.714617Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.29: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.29:2380","--initial-cluster=instance=https://199.19.213.29:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.29:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.29: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-03-19T17:52:22.714796Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:22.714828Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-03-19T17:52:22.714866Z","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-03-19T17:52:22.715968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"]} {"level":"info","ts":"2026-03-19T17:52:22.716124Z","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.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.29: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-03-19T17:52:22.735212Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"18.840847ms"} {"level":"info","ts":"2026-03-19T17:52:22.745717Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1ecbac2f2214dbd","cluster-id":"94b127ce9c24156a"} {"level":"info","ts":"2026-03-19T17:52:22.745852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:52:22.745881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 0"} {"level":"info","ts":"2026-03-19T17:52:22.745892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1ecbac2f2214dbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:52:22.745901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 1"} {"level":"info","ts":"2026-03-19T17:52:22.745949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"warn","ts":"2026-03-19T17:52:22.751914Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:52:22.7531Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:52:22.754002Z","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-03-19T17:52:22.756859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1ecbac2f2214dbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:52:22.756965Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1ecbac2f2214dbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:52:22.757159Z","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-03-19T17:52:22.757227Z","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-03-19T17:52:22.757234Z","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-03-19T17:52:22.757919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"info","ts":"2026-03-19T17:52:22.7586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","added-peer-id":"b1ecbac2f2214dbd","added-peer-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-03-19T17:52:22.760782Z","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-03-19T17:52:22.760925Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-03-19T17:52:22.760976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-03-19T17:52:22.761047Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1ecbac2f2214dbd","initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:52:22.761094Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:52:23.747029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:52:23.747124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:52:23.74719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgPreVoteResp from b1ecbac2f2214dbd at term 1"} {"level":"info","ts":"2026-03-19T17:52:23.74721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:52:23.747218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgVoteResp from b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-03-19T17:52:23.747235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became leader at term 2"} {"level":"info","ts":"2026-03-19T17:52:23.747252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1ecbac2f2214dbd elected leader b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-03-19T17:52:23.749561Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:23.750539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:23.750539Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1ecbac2f2214dbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.29:2379]}","request-path":"/0/members/b1ecbac2f2214dbd/attributes","cluster-id":"94b127ce9c24156a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:52:23.750681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:23.750807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:23.75084Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:23.750858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:23.751088Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:52:23.751168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:52:23.754124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T17:52:23.754701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.29:2379"} {"level":"warn","ts":"2026-03-19T17:52:30.234148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.003863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:52:30.234283Z","caller":"traceutil/trace.go:171","msg":"trace[466384066] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:304; }","duration":"314.177478ms","start":"2026-03-19T17:52:29.920085Z","end":"2026-03-19T17:52:30.234263Z","steps":["trace[466384066] 'range keys from in-memory index tree' (duration: 313.85978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:30.234359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:29.920069Z","time spent":"314.278541ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T17:52:30.234559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.478503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-03-19T17:52:30.234673Z","caller":"traceutil/trace.go:171","msg":"trace[1616295962] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:304; }","duration":"296.648606ms","start":"2026-03-19T17:52:29.938003Z","end":"2026-03-19T17:52:30.234652Z","steps":["trace[1616295962] 'range keys from in-memory index tree' (duration: 296.337629ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.235031Z","caller":"traceutil/trace.go:171","msg":"trace[1075736461] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"294.652023ms","start":"2026-03-19T17:52:29.940347Z","end":"2026-03-19T17:52:30.234999Z","steps":["trace[1075736461] 'process raft request' (duration: 294.53404ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.240148Z","caller":"traceutil/trace.go:171","msg":"trace[272137990] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:313; }","duration":"102.79676ms","start":"2026-03-19T17:52:30.137311Z","end":"2026-03-19T17:52:30.240108Z","steps":["trace[272137990] 'read index received' (duration: 97.586797ms)","trace[272137990] 'applied index is now lower than readState.Index' (duration: 5.208763ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:30.240232Z","caller":"traceutil/trace.go:171","msg":"trace[576132618] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"299.042824ms","start":"2026-03-19T17:52:29.941178Z","end":"2026-03-19T17:52:30.240221Z","steps":["trace[576132618] 'process raft request' (duration: 298.727585ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.240396Z","caller":"traceutil/trace.go:171","msg":"trace[768946041] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"298.597212ms","start":"2026-03-19T17:52:29.941679Z","end":"2026-03-19T17:52:30.240276Z","steps":["trace[768946041] 'process raft request' (duration: 298.383406ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.240155Z","caller":"traceutil/trace.go:171","msg":"trace[624830351] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"298.868149ms","start":"2026-03-19T17:52:29.941258Z","end":"2026-03-19T17:52:30.240126Z","steps":["trace[624830351] 'process raft request' (duration: 298.779196ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.240685Z","caller":"traceutil/trace.go:171","msg":"trace[1065054189] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"296.875286ms","start":"2026-03-19T17:52:29.943797Z","end":"2026-03-19T17:52:30.240672Z","steps":["trace[1065054189] 'process raft request' (duration: 296.28627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:30.240715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.378485ms","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-03-19T17:52:30.240942Z","caller":"traceutil/trace.go:171","msg":"trace[505855579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:304; }","duration":"103.626922ms","start":"2026-03-19T17:52:30.137264Z","end":"2026-03-19T17:52:30.240891Z","steps":["trace[505855579] 'agreement among raft nodes before linearized reading' (duration: 103.102737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:30.240848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.214898ms","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-03-19T17:52:30.241172Z","caller":"traceutil/trace.go:171","msg":"trace[1445145096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"100.570947ms","start":"2026-03-19T17:52:30.140571Z","end":"2026-03-19T17:52:30.241142Z","steps":["trace[1445145096] 'agreement among raft nodes before linearized reading' (duration: 100.188407ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:31.662919Z","caller":"traceutil/trace.go:171","msg":"trace[1199906344] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"191.333479ms","start":"2026-03-19T17:52:31.471559Z","end":"2026-03-19T17:52:31.662892Z","steps":["trace[1199906344] 'process raft request' (duration: 191.183265ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:33.59172Z","caller":"traceutil/trace.go:171","msg":"trace[284394836] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"102.477921ms","start":"2026-03-19T17:52:33.489214Z","end":"2026-03-19T17:52:33.591692Z","steps":["trace[284394836] 'process raft request' (duration: 102.240024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.095763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198265,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:52:36.096069Z","caller":"traceutil/trace.go:171","msg":"trace[147899248] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"720.832037ms","start":"2026-03-19T17:52:35.375206Z","end":"2026-03-19T17:52:36.096038Z","steps":["trace[147899248] 'process raft request' (duration: 720.646861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.096984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.375185Z","time spent":"720.96628ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:36.811201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.544724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:36.811354Z","caller":"traceutil/trace.go:171","msg":"trace[172163219] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"1.215596557s","start":"2026-03-19T17:52:35.595737Z","end":"2026-03-19T17:52:36.811333Z","steps":["trace[172163219] 'read index received' (duration: 500.185338ms)","trace[172163219] 'applied index is now lower than readState.Index' (duration: 715.409869ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:36.811406Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":5601806165884198268,"received-request-id":5601806165884198265} {"level":"info","ts":"2026-03-19T17:52:36.81142Z","caller":"traceutil/trace.go:171","msg":"trace[1994328057] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.237875116s","start":"2026-03-19T17:52:35.573498Z","end":"2026-03-19T17:52:36.811373Z","steps":["trace[1994328057] 'process raft request' (duration: 787.871229ms)","trace[1994328057] 'compare' (duration: 449.342049ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:36.811547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215787583s","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-03-19T17:52:36.811641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.57347Z","time spent":"1.238061361s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:36.811655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.486223ms","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-03-19T17:52:36.811654Z","caller":"traceutil/trace.go:171","msg":"trace[1141356593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"1.215932786s","start":"2026-03-19T17:52:35.59569Z","end":"2026-03-19T17:52:36.811623Z","steps":["trace[1141356593] 'agreement among raft nodes before linearized reading' (duration: 1.215739321s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.811716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.59567Z","time spent":"1.216037089s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:52:36.81176Z","caller":"traceutil/trace.go:171","msg":"trace[768782851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"531.567155ms","start":"2026-03-19T17:52:36.280111Z","end":"2026-03-19T17:52:36.811678Z","steps":["trace[768782851] 'agreement among raft nodes before linearized reading' (duration: 531.505584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.811814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.280082Z","time spent":"531.721619ms","remote":"127.0.0.1:45804","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-03-19T17:52:36.811586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041626552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:36.811895Z","caller":"traceutil/trace.go:171","msg":"trace[1751207998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"1.041988942s","start":"2026-03-19T17:52:35.769893Z","end":"2026-03-19T17:52:36.811882Z","steps":["trace[1751207998] 'agreement among raft nodes before linearized reading' (duration: 1.041650873s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.811935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.769867Z","time spent":"1.042059385s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:52:36.812046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.995697ms","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-03-19T17:52:36.812079Z","caller":"traceutil/trace.go:171","msg":"trace[1534813482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:395; }","duration":"532.074039ms","start":"2026-03-19T17:52:36.279996Z","end":"2026-03-19T17:52:36.81207Z","steps":["trace[1534813482] 'agreement among raft nodes before linearized reading' (duration: 531.982637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.812098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.27997Z","time spent":"532.124261ms","remote":"127.0.0.1:45804","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-03-19T17:52:36.812165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.186684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-sm7sl\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-03-19T17:52:36.8122Z","caller":"traceutil/trace.go:171","msg":"trace[1326042583] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-sm7sl; range_end:; response_count:1; response_revision:395; }","duration":"846.259994ms","start":"2026-03-19T17:52:35.96593Z","end":"2026-03-19T17:52:36.81219Z","steps":["trace[1326042583] 'agreement among raft nodes before linearized reading' (duration: 846.188822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:36.812222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.965901Z","time spent":"846.316936ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4486,"request content":"key:\"/registry/pods/kube-system/kube-proxy-sm7sl\" "} {"level":"warn","ts":"2026-03-19T17:52:37.323664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:52:37.990961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.717669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:37.991091Z","caller":"traceutil/trace.go:171","msg":"trace[1481713354] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.174986046s","start":"2026-03-19T17:52:36.816085Z","end":"2026-03-19T17:52:37.991071Z","steps":["trace[1481713354] 'process raft request' (duration: 613.028911ms)","trace[1481713354] 'compare' (duration: 561.571755ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:37.991176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.816063Z","time spent":"1.175066659s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:38.464179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.605824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:38.46456Z","caller":"traceutil/trace.go:171","msg":"trace[1754906903] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.647661223s","start":"2026-03-19T17:52:36.816846Z","end":"2026-03-19T17:52:38.464507Z","steps":["trace[1754906903] 'process raft request' (duration: 1.301626328s)","trace[1754906903] 'compare' (duration: 345.44866ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:38.464767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.816834Z","time spent":"1.647805858s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:38.46479Z","caller":"traceutil/trace.go:171","msg":"trace[177261383] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:410; }","duration":"1.64167445s","start":"2026-03-19T17:52:36.823094Z","end":"2026-03-19T17:52:38.464768Z","steps":["trace[177261383] 'read index received' (duration: 606.147713ms)","trace[177261383] 'applied index is now lower than readState.Index' (duration: 1.035525307s)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:38.464989Z","caller":"traceutil/trace.go:171","msg":"trace[1079793374] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.645901326s","start":"2026-03-19T17:52:36.819077Z","end":"2026-03-19T17:52:38.464978Z","steps":["trace[1079793374] 'process raft request' (duration: 1.645209776s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.465067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.819047Z","time spent":"1.645979966s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:38.465202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642072311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:38.465291Z","caller":"traceutil/trace.go:171","msg":"trace[1743166412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"1.642200144s","start":"2026-03-19T17:52:36.823069Z","end":"2026-03-19T17:52:38.465269Z","steps":["trace[1743166412] 'agreement among raft nodes before linearized reading' (duration: 1.641994498s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.465345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.82306Z","time spent":"1.642276635s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:52:38.465485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.57544944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T17:52:38.465536Z","caller":"traceutil/trace.go:171","msg":"trace[791162253] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:399; }","duration":"1.575547433s","start":"2026-03-19T17:52:36.889976Z","end":"2026-03-19T17:52:38.465523Z","steps":["trace[791162253] 'agreement among raft nodes before linearized reading' (duration: 1.575464531s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.465574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.889951Z","time spent":"1.575616844s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.29\" "} {"level":"info","ts":"2026-03-19T17:52:38.46562Z","caller":"traceutil/trace.go:171","msg":"trace[63028712] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.645554066s","start":"2026-03-19T17:52:36.820043Z","end":"2026-03-19T17:52:38.465597Z","steps":["trace[63028712] 'process raft request' (duration: 1.644664811s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.465746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:36.820033Z","time spent":"1.645660047s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:38.465758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256112884s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:38.465838Z","caller":"traceutil/trace.go:171","msg":"trace[2097680388] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:399; }","duration":"1.256194397s","start":"2026-03-19T17:52:37.209632Z","end":"2026-03-19T17:52:38.465826Z","steps":["trace[2097680388] 'agreement among raft nodes before linearized reading' (duration: 1.256098934s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.466144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.956818ms","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-03-19T17:52:38.466184Z","caller":"traceutil/trace.go:171","msg":"trace[1272074541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"363.03058ms","start":"2026-03-19T17:52:38.103144Z","end":"2026-03-19T17:52:38.466175Z","steps":["trace[1272074541] 'agreement among raft nodes before linearized reading' (duration: 362.946068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:38.466223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.103118Z","time spent":"363.098801ms","remote":"127.0.0.1:45804","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-03-19T17:52:39.529002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.242258ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-19T17:52:39.529127Z","caller":"traceutil/trace.go:171","msg":"trace[115116277] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"1.059518802s","start":"2026-03-19T17:52:38.469587Z","end":"2026-03-19T17:52:39.529106Z","steps":["trace[115116277] 'read index received' (duration: 222.166631ms)","trace[115116277] 'applied index is now lower than readState.Index' (duration: 837.350431ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:39.529132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.46851Z","time spent":"1.060613242s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T17:52:39.529265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059667086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-03-19T17:52:39.529297Z","caller":"traceutil/trace.go:171","msg":"trace[1543666203] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:399; }","duration":"1.059719848s","start":"2026-03-19T17:52:38.469566Z","end":"2026-03-19T17:52:39.529286Z","steps":["trace[1543666203] 'agreement among raft nodes before linearized reading' (duration: 1.059622596s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:39.52933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.469554Z","time spent":"1.05976754s","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-19T17:52:39.653965Z","caller":"traceutil/trace.go:171","msg":"trace[1539702569] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"1.179706395s","start":"2026-03-19T17:52:38.474233Z","end":"2026-03-19T17:52:39.653939Z","steps":["trace[1539702569] 'process raft request' (duration: 1.178070321s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:39.65413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.474202Z","time spent":"1.17983939s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:39.654488Z","caller":"traceutil/trace.go:171","msg":"trace[831904862] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.178263347s","start":"2026-03-19T17:52:38.47621Z","end":"2026-03-19T17:52:39.654473Z","steps":["trace[831904862] 'process raft request' (duration: 1.17764839s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:39.654575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.476177Z","time spent":"1.178353128s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:39.692241Z","caller":"traceutil/trace.go:171","msg":"trace[792945207] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"443.119978ms","start":"2026-03-19T17:52:39.249089Z","end":"2026-03-19T17:52:39.692209Z","steps":["trace[792945207] 'process raft request' (duration: 442.814989ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:39.692338Z","caller":"traceutil/trace.go:171","msg":"trace[754152418] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"161.06165ms","start":"2026-03-19T17:52:39.53123Z","end":"2026-03-19T17:52:39.692291Z","steps":["trace[754152418] 'process raft request' (duration: 160.835784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:39.692455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:39.249062Z","time spent":"443.287524ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:39.692677Z","caller":"traceutil/trace.go:171","msg":"trace[691879940] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:415; }","duration":"163.502297ms","start":"2026-03-19T17:52:39.529156Z","end":"2026-03-19T17:52:39.692658Z","steps":["trace[691879940] 'read index received' (duration: 123.082913ms)","trace[691879940] 'applied index is now lower than readState.Index' (duration: 40.418034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:39.692887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217655213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:52:39.692997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.536309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:39.693052Z","caller":"traceutil/trace.go:171","msg":"trace[1484009401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"144.629232ms","start":"2026-03-19T17:52:39.548407Z","end":"2026-03-19T17:52:39.693036Z","steps":["trace[1484009401] 'agreement among raft nodes before linearized reading' (duration: 144.55654ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:39.693248Z","caller":"traceutil/trace.go:171","msg":"trace[1286718679] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"154.173563ms","start":"2026-03-19T17:52:39.539034Z","end":"2026-03-19T17:52:39.693208Z","steps":["trace[1286718679] 'process raft request' (duration: 153.107854ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:39.692931Z","caller":"traceutil/trace.go:171","msg":"trace[1299987416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"1.217759867s","start":"2026-03-19T17:52:38.475157Z","end":"2026-03-19T17:52:39.692917Z","steps":["trace[1299987416] 'agreement among raft nodes before linearized reading' (duration: 1.217629583s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:39.693441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:38.475127Z","time spent":"1.218207588s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:52:44.67844Z","caller":"traceutil/trace.go:171","msg":"trace[1283313299] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"188.80941ms","start":"2026-03-19T17:52:44.489601Z","end":"2026-03-19T17:52:44.67841Z","steps":["trace[1283313299] 'read index received' (duration: 188.498901ms)","trace[1283313299] 'applied index is now lower than readState.Index' (duration: 308.929µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:44.678506Z","caller":"traceutil/trace.go:171","msg":"trace[774224999] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"192.666845ms","start":"2026-03-19T17:52:44.485803Z","end":"2026-03-19T17:52:44.67847Z","steps":["trace[774224999] 'process raft request' (duration: 192.362486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:44.678646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.020845ms","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-03-19T17:52:44.678687Z","caller":"traceutil/trace.go:171","msg":"trace[1202823701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"189.106697ms","start":"2026-03-19T17:52:44.489567Z","end":"2026-03-19T17:52:44.678673Z","steps":["trace[1202823701] 'agreement among raft nodes before linearized reading' (duration: 189.006725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:44.678792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.390653ms","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-03-19T17:52:44.678839Z","caller":"traceutil/trace.go:171","msg":"trace[952899553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"186.464085ms","start":"2026-03-19T17:52:44.492359Z","end":"2026-03-19T17:52:44.678823Z","steps":["trace[952899553] 'agreement among raft nodes before linearized reading' (duration: 186.348352ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:58.967918Z","caller":"traceutil/trace.go:171","msg":"trace[1341962789] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"123.324871ms","start":"2026-03-19T17:52:58.84456Z","end":"2026-03-19T17:52:58.967885Z","steps":["trace[1341962789] 'read index received' (duration: 123.116555ms)","trace[1341962789] 'applied index is now lower than readState.Index' (duration: 207.136µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:58.967992Z","caller":"traceutil/trace.go:171","msg":"trace[960661682] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"123.886755ms","start":"2026-03-19T17:52:58.844061Z","end":"2026-03-19T17:52:58.967948Z","steps":["trace[960661682] 'process raft request' (duration: 123.634339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:58.968296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.701591ms","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-03-19T17:52:58.96841Z","caller":"traceutil/trace.go:171","msg":"trace[1518930957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"123.883585ms","start":"2026-03-19T17:52:58.844503Z","end":"2026-03-19T17:52:58.968387Z","steps":["trace[1518930957] 'agreement among raft nodes before linearized reading' (duration: 123.541486ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:59.296131Z","caller":"traceutil/trace.go:171","msg":"trace[1102241937] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"322.861963ms","start":"2026-03-19T17:52:58.973221Z","end":"2026-03-19T17:52:59.296083Z","steps":["trace[1102241937] 'process raft request' (duration: 322.334898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:59.296362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:58.973193Z","time spent":"323.046027ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:01.507207Z","caller":"traceutil/trace.go:171","msg":"trace[619446367] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"201.900487ms","start":"2026-03-19T17:53:01.305279Z","end":"2026-03-19T17:53:01.50718Z","steps":["trace[619446367] 'process raft request' (duration: 201.735483ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:06.188337Z","caller":"traceutil/trace.go:171","msg":"trace[830268086] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"110.445908ms","start":"2026-03-19T17:53:06.077855Z","end":"2026-03-19T17:53:06.188301Z","steps":["trace[830268086] 'process raft request' (duration: 109.880602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:06.646754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.891043ms","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-03-19T17:53:06.646856Z","caller":"traceutil/trace.go:171","msg":"trace[897879257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"293.063638ms","start":"2026-03-19T17:53:06.353771Z","end":"2026-03-19T17:53:06.646834Z","steps":["trace[897879257] 'range keys from in-memory index tree' (duration: 292.516622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:07.326459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:06.964715Z","time spent":"361.732224ms","remote":"127.0.0.1:38672","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T17:53:07.326554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.568038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:07.326629Z","caller":"traceutil/trace.go:171","msg":"trace[390237848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"553.6876ms","start":"2026-03-19T17:53:06.772911Z","end":"2026-03-19T17:53:07.326599Z","steps":["trace[390237848] 'range keys from in-memory index tree' (duration: 553.476724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:07.326662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:06.772891Z","time spent":"553.765532ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:07.326707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.812289ms","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-03-19T17:53:07.326795Z","caller":"traceutil/trace.go:171","msg":"trace[123593458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"315.959234ms","start":"2026-03-19T17:53:07.010813Z","end":"2026-03-19T17:53:07.326772Z","steps":["trace[123593458] 'range keys from in-memory index tree' (duration: 315.597274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:07.326834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:07.01077Z","time spent":"316.056125ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:07.326844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.980127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T17:53:07.326934Z","caller":"traceutil/trace.go:171","msg":"trace[2070627275] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:535; }","duration":"435.10877ms","start":"2026-03-19T17:53:06.891804Z","end":"2026-03-19T17:53:07.326912Z","steps":["trace[2070627275] 'range keys from in-memory index tree' (duration: 434.677048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:07.326978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:06.891791Z","time spent":"435.179972ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.29\" "} {"level":"info","ts":"2026-03-19T17:53:07.481523Z","caller":"traceutil/trace.go:171","msg":"trace[459375435] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"149.769173ms","start":"2026-03-19T17:53:07.331728Z","end":"2026-03-19T17:53:07.481498Z","steps":["trace[459375435] 'process raft request' (duration: 130.67432ms)","trace[459375435] 'compare' (duration: 18.881997ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:08.322045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:08.822565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:09.323587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:09.82435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:10.325149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:10.73586Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.875472069s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:53:10.736346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.133614198s","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-03-19T17:53:10.736392Z","caller":"traceutil/trace.go:171","msg":"trace[136138989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"3.133708271s","start":"2026-03-19T17:53:07.602664Z","end":"2026-03-19T17:53:10.736372Z","steps":["trace[136138989] 'range keys from in-memory index tree' (duration: 3.133426222s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:10.736425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:07.602633Z","time spent":"3.133784283s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:53:10.736722Z","caller":"traceutil/trace.go:171","msg":"trace[1917590944] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"2.915513119s","start":"2026-03-19T17:53:07.821174Z","end":"2026-03-19T17:53:10.736687Z","steps":["trace[1917590944] 'read index received' (duration: 2.91518538s)","trace[1917590944] 'applied index is now lower than readState.Index' (duration: 325.699µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:10.73681Z","caller":"traceutil/trace.go:171","msg":"trace[1425830267] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"3.020806046s","start":"2026-03-19T17:53:07.715993Z","end":"2026-03-19T17:53:10.736799Z","steps":["trace[1425830267] 'process raft request' (duration: 3.020097146s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:10.736907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:07.715955Z","time spent":"3.020880897s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:10.737098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.915905938s","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-03-19T17:53:10.73715Z","caller":"traceutil/trace.go:171","msg":"trace[644467162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"2.916024173s","start":"2026-03-19T17:53:07.821106Z","end":"2026-03-19T17:53:10.73713Z","steps":["trace[644467162] 'agreement among raft nodes before linearized reading' (duration: 2.915732634s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:10.737216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:07.821074Z","time spent":"2.916105585s","remote":"127.0.0.1:45804","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-03-19T17:53:10.745675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.042035939s","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-03-19T17:53:10.745767Z","caller":"traceutil/trace.go:171","msg":"trace[1633553687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"2.042192504s","start":"2026-03-19T17:53:08.703554Z","end":"2026-03-19T17:53:10.745746Z","steps":["trace[1633553687] 'agreement among raft nodes before linearized reading' (duration: 2.041926246s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:10.745808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:08.703517Z","time spent":"2.042280756s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:10.745817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259057925s","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-03-19T17:53:10.745703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973539648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:10.745901Z","caller":"traceutil/trace.go:171","msg":"trace[1623637451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.259177378s","start":"2026-03-19T17:53:09.486696Z","end":"2026-03-19T17:53:10.745873Z","steps":["trace[1623637451] 'agreement among raft nodes before linearized reading' (duration: 1.258929842s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:10.745921Z","caller":"traceutil/trace.go:171","msg":"trace[2121745480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"1.973795605s","start":"2026-03-19T17:53:08.772107Z","end":"2026-03-19T17:53:10.745903Z","steps":["trace[2121745480] 'agreement among raft nodes before linearized reading' (duration: 1.973553559s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:10.745958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:09.48667Z","time spent":"1.259276931s","remote":"127.0.0.1:45804","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-03-19T17:53:10.745965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:08.772087Z","time spent":"1.973866968s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:53:13.536963Z","caller":"traceutil/trace.go:171","msg":"trace[1679402928] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"179.765971ms","start":"2026-03-19T17:53:13.357162Z","end":"2026-03-19T17:53:13.536928Z","steps":["trace[1679402928] 'process raft request' (duration: 179.592247ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:16.545314Z","caller":"traceutil/trace.go:171","msg":"trace[715746922] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"408.920274ms","start":"2026-03-19T17:53:16.136345Z","end":"2026-03-19T17:53:16.545266Z","steps":["trace[715746922] 'process raft request' (duration: 401.870593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:16.545573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.136307Z","time spent":"409.128879ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:16.545675Z","caller":"traceutil/trace.go:171","msg":"trace[2011944712] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"190.30906ms","start":"2026-03-19T17:53:16.355335Z","end":"2026-03-19T17:53:16.545644Z","steps":["trace[2011944712] 'read index received' (duration: 182.915748ms)","trace[2011944712] 'applied index is now lower than readState.Index' (duration: 7.391842ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:16.545782Z","caller":"traceutil/trace.go:171","msg":"trace[486692381] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"383.253833ms","start":"2026-03-19T17:53:16.162507Z","end":"2026-03-19T17:53:16.545761Z","steps":["trace[486692381] 'process raft request' (duration: 382.650476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:16.545871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.16247Z","time spent":"383.363376ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:53:16.545964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.609638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-03-19T17:53:16.546011Z","caller":"traceutil/trace.go:171","msg":"trace[646450249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"190.722792ms","start":"2026-03-19T17:53:16.355271Z","end":"2026-03-19T17:53:16.545993Z","steps":["trace[646450249] 'agreement among raft nodes before linearized reading' (duration: 190.486215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.199425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.151432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:53:17.199597Z","caller":"traceutil/trace.go:171","msg":"trace[1923896625] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"620.180487ms","start":"2026-03-19T17:53:16.579392Z","end":"2026-03-19T17:53:17.199572Z","steps":["trace[1923896625] 'process raft request' (duration: 390.734977ms)","trace[1923896625] 'compare' (duration: 228.808493ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:17.199667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.579364Z","time spent":"620.274498ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:53:17.202222Z","caller":"traceutil/trace.go:171","msg":"trace[641801197] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"429.752513ms","start":"2026-03-19T17:53:16.772458Z","end":"2026-03-19T17:53:17.202211Z","steps":["trace[641801197] 'read index received' (duration: 197.770744ms)","trace[641801197] 'applied index is now lower than readState.Index' (duration: 231.980919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:17.202413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.945028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:17.202392Z","caller":"traceutil/trace.go:171","msg":"trace[580701992] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"399.046934ms","start":"2026-03-19T17:53:16.803305Z","end":"2026-03-19T17:53:17.202352Z","steps":["trace[580701992] 'process raft request' (duration: 398.774746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.202552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.29118ms","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-03-19T17:53:17.202556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.803279Z","time spent":"399.197308ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:53:17.202583Z","caller":"traceutil/trace.go:171","msg":"trace[1511588707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"414.349673ms","start":"2026-03-19T17:53:16.788227Z","end":"2026-03-19T17:53:17.202576Z","steps":["trace[1511588707] 'agreement among raft nodes before linearized reading' (duration: 414.24197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.202607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.788181Z","time spent":"414.420694ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:53:17.202449Z","caller":"traceutil/trace.go:171","msg":"trace[115682717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"430.035341ms","start":"2026-03-19T17:53:16.772405Z","end":"2026-03-19T17:53:17.202441Z","steps":["trace[115682717] 'agreement among raft nodes before linearized reading' (duration: 429.951769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.202709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.772376Z","time spent":"430.318909ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:17.20285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.523069ms","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-03-19T17:53:17.202872Z","caller":"traceutil/trace.go:171","msg":"trace[1034455919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:613; }","duration":"226.583981ms","start":"2026-03-19T17:53:16.976281Z","end":"2026-03-19T17:53:17.202865Z","steps":["trace[1034455919] 'agreement among raft nodes before linearized reading' (duration: 226.52821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.203001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.255684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-19T17:53:17.203024Z","caller":"traceutil/trace.go:171","msg":"trace[1156930172] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:613; }","duration":"248.329855ms","start":"2026-03-19T17:53:16.954685Z","end":"2026-03-19T17:53:17.203015Z","steps":["trace[1156930172] 'agreement among raft nodes before linearized reading' (duration: 248.267133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.203107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.775145ms","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-03-19T17:53:17.203123Z","caller":"traceutil/trace.go:171","msg":"trace[423803583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"414.829675ms","start":"2026-03-19T17:53:16.788288Z","end":"2026-03-19T17:53:17.203118Z","steps":["trace[423803583] 'agreement among raft nodes before linearized reading' (duration: 414.782633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.203139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.788263Z","time spent":"414.871616ms","remote":"127.0.0.1:45804","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-03-19T17:53:17.203139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.361459ms","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-03-19T17:53:17.203183Z","caller":"traceutil/trace.go:171","msg":"trace[924458240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"374.438672ms","start":"2026-03-19T17:53:16.828731Z","end":"2026-03-19T17:53:17.20317Z","steps":["trace[924458240] 'agreement among raft nodes before linearized reading' (duration: 374.274768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.203211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.828708Z","time spent":"374.497004ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:17.2035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.343251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-19T17:53:17.203531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.970459ms","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-03-19T17:53:17.203572Z","caller":"traceutil/trace.go:171","msg":"trace[1539119698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"370.032121ms","start":"2026-03-19T17:53:16.833529Z","end":"2026-03-19T17:53:17.203561Z","steps":["trace[1539119698] 'agreement among raft nodes before linearized reading' (duration: 369.927968ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.203581Z","caller":"traceutil/trace.go:171","msg":"trace[703901421] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:613; }","duration":"310.458504ms","start":"2026-03-19T17:53:16.893078Z","end":"2026-03-19T17:53:17.203537Z","steps":["trace[703901421] 'agreement among raft nodes before linearized reading' (duration: 310.264298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.203601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.833519Z","time spent":"370.075753ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:17.203648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.893045Z","time spent":"310.567555ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.29\" "} {"level":"info","ts":"2026-03-19T17:53:17.202222Z","caller":"traceutil/trace.go:171","msg":"trace[1289473993] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"585.145889ms","start":"2026-03-19T17:53:16.617032Z","end":"2026-03-19T17:53:17.202178Z","steps":["trace[1289473993] 'process raft request' (duration: 584.669926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.2055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:16.617001Z","time spent":"588.361418ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.567689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.607491ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-19T17:53:17.567999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.206877Z","time spent":"361.110498ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T17:53:17.569041Z","caller":"traceutil/trace.go:171","msg":"trace[693334558] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"362.007752ms","start":"2026-03-19T17:53:17.207002Z","end":"2026-03-19T17:53:17.56901Z","steps":["trace[693334558] 'process raft request' (duration: 360.874752ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.569141Z","caller":"traceutil/trace.go:171","msg":"trace[667338143] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"359.931266ms","start":"2026-03-19T17:53:17.209172Z","end":"2026-03-19T17:53:17.569104Z","steps":["trace[667338143] 'process raft request' (duration: 359.70697ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.56932Z","caller":"traceutil/trace.go:171","msg":"trace[1172773346] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"360.105121ms","start":"2026-03-19T17:53:17.209167Z","end":"2026-03-19T17:53:17.569272Z","steps":["trace[1172773346] 'process raft request' (duration: 359.801272ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.569341Z","caller":"traceutil/trace.go:171","msg":"trace[1551638822] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"360.10286ms","start":"2026-03-19T17:53:17.209202Z","end":"2026-03-19T17:53:17.569305Z","steps":["trace[1551638822] 'process raft request' (duration: 359.813582ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.56947Z","caller":"traceutil/trace.go:171","msg":"trace[1667007919] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"360.181363ms","start":"2026-03-19T17:53:17.20921Z","end":"2026-03-19T17:53:17.569392Z","steps":["trace[1667007919] 'process raft request' (duration: 359.858544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.569554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.209199Z","time spent":"360.317946ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.569584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.209187Z","time spent":"360.227983ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.569599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.209163Z","time spent":"360.192322ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.569214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.206984Z","time spent":"362.140095ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.570151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.209153Z","time spent":"360.193262ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:17.572012Z","caller":"traceutil/trace.go:171","msg":"trace[1461973400] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"364.844229ms","start":"2026-03-19T17:53:17.207142Z","end":"2026-03-19T17:53:17.571986Z","steps":["trace[1461973400] 'read index received' (duration: 103.835207ms)","trace[1461973400] 'applied index is now lower than readState.Index' (duration: 261.007272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:17.572234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.088716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:17.572264Z","caller":"traceutil/trace.go:171","msg":"trace[1585315223] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"299.998547ms","start":"2026-03-19T17:53:17.272244Z","end":"2026-03-19T17:53:17.572242Z","steps":["trace[1585315223] 'process raft request' (duration: 299.266647ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.572296Z","caller":"traceutil/trace.go:171","msg":"trace[1145896887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"365.20528ms","start":"2026-03-19T17:53:17.207072Z","end":"2026-03-19T17:53:17.572277Z","steps":["trace[1145896887] 'agreement among raft nodes before linearized reading' (duration: 365.004354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.572351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.272208Z","time spent":"300.097911ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:17.572474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.207024Z","time spent":"365.398974ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:53:17.819418Z","caller":"traceutil/trace.go:171","msg":"trace[742225947] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"373.099125ms","start":"2026-03-19T17:53:17.446282Z","end":"2026-03-19T17:53:17.819381Z","steps":["trace[742225947] 'process raft request' (duration: 306.142735ms)","trace[742225947] 'compare' (duration: 66.622911ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:17.819604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:17.446254Z","time spent":"373.26701ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:53:17.820197Z","caller":"traceutil/trace.go:171","msg":"trace[2135695372] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"250.024662ms","start":"2026-03-19T17:53:17.570126Z","end":"2026-03-19T17:53:17.82015Z","steps":["trace[2135695372] 'process raft request' (duration: 249.20519ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.823547Z","caller":"traceutil/trace.go:171","msg":"trace[312105088] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"136.465029ms","start":"2026-03-19T17:53:17.687044Z","end":"2026-03-19T17:53:17.823509Z","steps":["trace[312105088] 'process raft request' (duration: 132.678526ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.828811Z","caller":"traceutil/trace.go:171","msg":"trace[887612702] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"136.734206ms","start":"2026-03-19T17:53:17.692046Z","end":"2026-03-19T17:53:17.828781Z","steps":["trace[887612702] 'process raft request' (duration: 129.146929ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:17.829398Z","caller":"traceutil/trace.go:171","msg":"trace[157628669] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:646; }","duration":"103.457276ms","start":"2026-03-19T17:53:17.725209Z","end":"2026-03-19T17:53:17.828666Z","steps":["trace[157628669] 'read index received' (duration: 27.308825ms)","trace[157628669] 'applied index is now lower than readState.Index' (duration: 76.139871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:17.831726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.493229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-03-19T17:53:17.831774Z","caller":"traceutil/trace.go:171","msg":"trace[276315451] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:624; }","duration":"106.589963ms","start":"2026-03-19T17:53:17.725172Z","end":"2026-03-19T17:53:17.831762Z","steps":["trace[276315451] 'agreement among raft nodes before linearized reading' (duration: 104.109585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:17.832712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.735781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-03-19T17:53:17.832867Z","caller":"traceutil/trace.go:171","msg":"trace[78989860] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:624; }","duration":"104.895116ms","start":"2026-03-19T17:53:17.727929Z","end":"2026-03-19T17:53:17.832824Z","steps":["trace[78989860] 'agreement among raft nodes before linearized reading' (duration: 102.649655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.227032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433965784s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T17:53:24.227213Z","caller":"traceutil/trace.go:171","msg":"trace[232194087] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"1.454513865s","start":"2026-03-19T17:53:22.772669Z","end":"2026-03-19T17:53:24.227182Z","steps":["trace[232194087] 'read index received' (duration: 20.338785ms)","trace[232194087] 'applied index is now lower than readState.Index' (duration: 1.43417265s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:24.227437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454740821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:24.227503Z","caller":"traceutil/trace.go:171","msg":"trace[2144332016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.454847175s","start":"2026-03-19T17:53:22.772636Z","end":"2026-03-19T17:53:24.227483Z","steps":["trace[2144332016] 'agreement among raft nodes before linearized reading' (duration: 1.454719471s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.227563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:22.772617Z","time spent":"1.454932467s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:24.227662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.539385ms","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-03-19T17:53:24.227709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.150896ms","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-03-19T17:53:24.22781Z","caller":"traceutil/trace.go:171","msg":"trace[1024746413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"508.30846ms","start":"2026-03-19T17:53:23.719475Z","end":"2026-03-19T17:53:24.227784Z","steps":["trace[1024746413] 'agreement among raft nodes before linearized reading' (duration: 508.035723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.227858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:23.719439Z","time spent":"508.409373ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:24.227871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.432993ms","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-03-19T17:53:24.227928Z","caller":"traceutil/trace.go:171","msg":"trace[1410362221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"508.527815ms","start":"2026-03-19T17:53:23.719387Z","end":"2026-03-19T17:53:24.227915Z","steps":["trace[1410362221] 'agreement among raft nodes before linearized reading' (duration: 508.385072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.22798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:23.719367Z","time spent":"508.600398ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:53:24.227743Z","caller":"traceutil/trace.go:171","msg":"trace[1775027946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"561.650109ms","start":"2026-03-19T17:53:23.666073Z","end":"2026-03-19T17:53:24.227723Z","steps":["trace[1775027946] 'agreement among raft nodes before linearized reading' (duration: 561.396841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.228057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:23.666049Z","time spent":"561.999588ms","remote":"127.0.0.1:45804","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-03-19T17:53:24.228123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.5245ms","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-03-19T17:53:24.228214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.092124ms","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-03-19T17:53:24.228255Z","caller":"traceutil/trace.go:171","msg":"trace[1242180599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"507.712864ms","start":"2026-03-19T17:53:23.720521Z","end":"2026-03-19T17:53:24.228233Z","steps":["trace[1242180599] 'agreement among raft nodes before linearized reading' (duration: 507.403307ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.228274Z","caller":"traceutil/trace.go:171","msg":"trace[1802219659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"512.218529ms","start":"2026-03-19T17:53:23.716035Z","end":"2026-03-19T17:53:24.228254Z","steps":["trace[1802219659] 'agreement among raft nodes before linearized reading' (duration: 512.053634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.228336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:23.716008Z","time spent":"512.30254ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:53:24.228344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:23.720496Z","time spent":"507.796416ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T17:53:24.333507Z","caller":"traceutil/trace.go:171","msg":"trace[192632257] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"100.819046ms","start":"2026-03-19T17:53:24.232645Z","end":"2026-03-19T17:53:24.333464Z","steps":["trace[192632257] 'process raft request' (duration: 100.445465ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.342715Z","caller":"traceutil/trace.go:171","msg":"trace[482737872] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"108.843473ms","start":"2026-03-19T17:53:24.233855Z","end":"2026-03-19T17:53:24.342699Z","steps":["trace[482737872] 'process raft request' (duration: 108.71588ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.342763Z","caller":"traceutil/trace.go:171","msg":"trace[1402904848] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"107.634822ms","start":"2026-03-19T17:53:24.235107Z","end":"2026-03-19T17:53:24.342742Z","steps":["trace[1402904848] 'process raft request' (duration: 107.501208ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.342805Z","caller":"traceutil/trace.go:171","msg":"trace[433638802] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"107.083816ms","start":"2026-03-19T17:53:24.235704Z","end":"2026-03-19T17:53:24.342788Z","steps":["trace[433638802] 'process raft request' (duration: 106.938962ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.34288Z","caller":"traceutil/trace.go:171","msg":"trace[522439462] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"109.727719ms","start":"2026-03-19T17:53:24.233111Z","end":"2026-03-19T17:53:24.342838Z","steps":["trace[522439462] 'process raft request' (duration: 109.309087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:24.34317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.011563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:24.34324Z","caller":"traceutil/trace.go:171","msg":"trace[1665162055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"107.105226ms","start":"2026-03-19T17:53:24.236119Z","end":"2026-03-19T17:53:24.343224Z","steps":["trace[1665162055] 'agreement among raft nodes before linearized reading' (duration: 106.977022ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:24.342713Z","caller":"traceutil/trace.go:171","msg":"trace[1850646285] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:694; }","duration":"106.551671ms","start":"2026-03-19T17:53:24.236129Z","end":"2026-03-19T17:53:24.342681Z","steps":["trace[1850646285] 'read index received' (duration: 96.862866ms)","trace[1850646285] 'applied index is now lower than readState.Index' (duration: 9.687445ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:25.317689Z","caller":"traceutil/trace.go:171","msg":"trace[1182923652] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"146.440571ms","start":"2026-03-19T17:53:25.171223Z","end":"2026-03-19T17:53:25.317664Z","steps":["trace[1182923652] 'process raft request' (duration: 146.195284ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:25.453579Z","caller":"traceutil/trace.go:171","msg":"trace[108223568] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"129.225882ms","start":"2026-03-19T17:53:25.32428Z","end":"2026-03-19T17:53:25.453506Z","steps":["trace[108223568] 'process raft request' (duration: 128.618535ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:25.453694Z","caller":"traceutil/trace.go:171","msg":"trace[1963960791] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"122.60329ms","start":"2026-03-19T17:53:25.331056Z","end":"2026-03-19T17:53:25.45366Z","steps":["trace[1963960791] 'process raft request' (duration: 122.372584ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:28.542273Z","caller":"traceutil/trace.go:171","msg":"trace[229535051] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"160.321492ms","start":"2026-03-19T17:53:28.381911Z","end":"2026-03-19T17:53:28.542233Z","steps":["trace[229535051] 'process raft request' (duration: 143.44787ms)","trace[229535051] 'compare' (duration: 16.724528ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:28.546882Z","caller":"traceutil/trace.go:171","msg":"trace[595590303] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"164.530916ms","start":"2026-03-19T17:53:28.382331Z","end":"2026-03-19T17:53:28.546861Z","steps":["trace[595590303] 'process raft request' (duration: 164.32458ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:28.546988Z","caller":"traceutil/trace.go:171","msg":"trace[747314813] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"164.570577ms","start":"2026-03-19T17:53:28.382395Z","end":"2026-03-19T17:53:28.546966Z","steps":["trace[747314813] 'process raft request' (duration: 164.352931ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:28.547344Z","caller":"traceutil/trace.go:171","msg":"trace[299976047] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"164.374851ms","start":"2026-03-19T17:53:28.382783Z","end":"2026-03-19T17:53:28.547158Z","steps":["trace[299976047] 'process raft request' (duration: 164.029922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:30.882336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:31.383238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198919,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:31.545566Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.233225159s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T17:53:31.546042Z","caller":"traceutil/trace.go:171","msg":"trace[2033686104] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.233914208s","start":"2026-03-19T17:53:30.312093Z","end":"2026-03-19T17:53:31.546007Z","steps":["trace[2033686104] 'process raft request' (duration: 1.233683991s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.546262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.312062Z","time spent":"1.234089083s","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5169,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:31.577482Z","caller":"traceutil/trace.go:171","msg":"trace[1584523012] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"413.702245ms","start":"2026-03-19T17:53:31.163755Z","end":"2026-03-19T17:53:31.577457Z","steps":["trace[1584523012] 'process raft request' (duration: 413.49841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.577701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.163732Z","time spent":"413.839748ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:31.577707Z","caller":"traceutil/trace.go:171","msg":"trace[792311761] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:750; }","duration":"1.19592241s","start":"2026-03-19T17:53:30.38174Z","end":"2026-03-19T17:53:31.577662Z","steps":["trace[792311761] 'read index received' (duration: 1.16409181s)","trace[792311761] 'applied index is now lower than readState.Index' (duration: 31.823349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:31.577952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.314645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:31.578008Z","caller":"traceutil/trace.go:171","msg":"trace[191582005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"805.446849ms","start":"2026-03-19T17:53:30.772542Z","end":"2026-03-19T17:53:31.577989Z","steps":["trace[191582005] 'agreement among raft nodes before linearized reading' (duration: 805.338386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.772511Z","time spent":"805.527252ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:31.578073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030872689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T17:53:31.5781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1963292s","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-03-19T17:53:31.578168Z","caller":"traceutil/trace.go:171","msg":"trace[1647187511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.030964772s","start":"2026-03-19T17:53:30.547143Z","end":"2026-03-19T17:53:31.578108Z","steps":["trace[1647187511] 'agreement among raft nodes before linearized reading' (duration: 1.030733596s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:31.578216Z","caller":"traceutil/trace.go:171","msg":"trace[261068892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"1.196504925s","start":"2026-03-19T17:53:30.381677Z","end":"2026-03-19T17:53:31.578182Z","steps":["trace[261068892] 'agreement among raft nodes before linearized reading' (duration: 1.196165786s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.547121Z","time spent":"1.031089046s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:31.578273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.381649Z","time spent":"1.196614378s","remote":"127.0.0.1:45804","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-03-19T17:53:31.578282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.541552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:53:31.578301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027162137s","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-03-19T17:53:31.578349Z","caller":"traceutil/trace.go:171","msg":"trace[840284755] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:722; }","duration":"420.663105ms","start":"2026-03-19T17:53:31.157675Z","end":"2026-03-19T17:53:31.578338Z","steps":["trace[840284755] 'agreement among raft nodes before linearized reading' (duration: 420.571352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.157644Z","time spent":"420.732307ms","remote":"127.0.0.1:45650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-03-19T17:53:31.578388Z","caller":"traceutil/trace.go:171","msg":"trace[1250926323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"1.027285082s","start":"2026-03-19T17:53:30.551085Z","end":"2026-03-19T17:53:31.57837Z","steps":["trace[1250926323] 'agreement among raft nodes before linearized reading' (duration: 1.027095946s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.55106Z","time spent":"1.027356793s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:53:31.578134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.415212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:31.578463Z","caller":"traceutil/trace.go:171","msg":"trace[1353846233] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:722; }","duration":"340.749111ms","start":"2026-03-19T17:53:31.237702Z","end":"2026-03-19T17:53:31.578451Z","steps":["trace[1353846233] 'agreement among raft nodes before linearized reading' (duration: 340.389552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.237607Z","time spent":"340.878815ms","remote":"127.0.0.1:46032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:53:31.578568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025910813s","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-03-19T17:53:31.578599Z","caller":"traceutil/trace.go:171","msg":"trace[1017806047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.025962035s","start":"2026-03-19T17:53:30.552626Z","end":"2026-03-19T17:53:31.578588Z","steps":["trace[1017806047] 'agreement among raft nodes before linearized reading' (duration: 1.025880822s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.552618Z","time spent":"1.025999935s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:31.578684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027570029s","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-03-19T17:53:31.578746Z","caller":"traceutil/trace.go:171","msg":"trace[2130821247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.027668361s","start":"2026-03-19T17:53:30.551054Z","end":"2026-03-19T17:53:31.578722Z","steps":["trace[2130821247] 'agreement among raft nodes before linearized reading' (duration: 1.027519647s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.578795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:30.551028Z","time spent":"1.027753334s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T17:53:31.578964Z","caller":"traceutil/trace.go:171","msg":"trace[1504873401] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"279.340744ms","start":"2026-03-19T17:53:31.29847Z","end":"2026-03-19T17:53:31.577811Z","steps":["trace[1504873401] 'process raft request' (duration: 278.941232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:31.97165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.360594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:53:31.971765Z","caller":"traceutil/trace.go:171","msg":"trace[1666758128] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"387.726404ms","start":"2026-03-19T17:53:31.584019Z","end":"2026-03-19T17:53:31.971746Z","steps":["trace[1666758128] 'process raft request' (duration: 284.168814ms)","trace[1666758128] 'compare' (duration: 103.223781ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:31.971835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.583981Z","time spent":"387.826297ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:32.067326Z","caller":"traceutil/trace.go:171","msg":"trace[1973233338] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"483.177432ms","start":"2026-03-19T17:53:31.584131Z","end":"2026-03-19T17:53:32.067309Z","steps":["trace[1973233338] 'process raft request' (duration: 483.10489ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:32.067382Z","caller":"traceutil/trace.go:171","msg":"trace[642110526] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"483.211874ms","start":"2026-03-19T17:53:31.584134Z","end":"2026-03-19T17:53:32.067346Z","steps":["trace[642110526] 'process raft request' (duration: 483.009828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:32.067427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.584102Z","time spent":"483.274016ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:32.067559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.58411Z","time spent":"483.357549ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:32.067606Z","caller":"traceutil/trace.go:171","msg":"trace[1002858392] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"483.44129ms","start":"2026-03-19T17:53:31.584145Z","end":"2026-03-19T17:53:32.067586Z","steps":["trace[1002858392] 'process raft request' (duration: 483.129822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:32.067679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.58411Z","time spent":"483.531863ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:32.067685Z","caller":"traceutil/trace.go:171","msg":"trace[856050095] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"483.44135ms","start":"2026-03-19T17:53:31.584234Z","end":"2026-03-19T17:53:32.067676Z","steps":["trace[856050095] 'process raft request' (duration: 483.301605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:32.067721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.584221Z","time spent":"483.479042ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:32.067714Z","caller":"traceutil/trace.go:171","msg":"trace[1121228539] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:753; }","duration":"477.474858ms","start":"2026-03-19T17:53:31.590199Z","end":"2026-03-19T17:53:32.067673Z","steps":["trace[1121228539] 'read index received' (duration: 278.003736ms)","trace[1121228539] 'applied index is now lower than readState.Index' (duration: 199.469482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:32.067806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.599781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:53:32.067839Z","caller":"traceutil/trace.go:171","msg":"trace[1102070512] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:727; }","duration":"477.661643ms","start":"2026-03-19T17:53:31.590167Z","end":"2026-03-19T17:53:32.067829Z","steps":["trace[1102070512] 'agreement among raft nodes before linearized reading' (duration: 477.57275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:32.067869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:31.590155Z","time spent":"477.708223ms","remote":"127.0.0.1:45962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:53:33.272611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:53:33.753431Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013957517s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:53:34.153952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.326679ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T17:53:34.154078Z","caller":"traceutil/trace.go:171","msg":"trace[1534331546] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"1.381892872s","start":"2026-03-19T17:53:32.772165Z","end":"2026-03-19T17:53:34.154057Z","steps":["trace[1534331546] 'read index received' (duration: 981.402208ms)","trace[1534331546] 'applied index is now lower than readState.Index' (duration: 400.488854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:34.154359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382187039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:34.154413Z","caller":"traceutil/trace.go:171","msg":"trace[1529729066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"1.382294082s","start":"2026-03-19T17:53:32.772102Z","end":"2026-03-19T17:53:34.154396Z","steps":["trace[1529729066] 'agreement among raft nodes before linearized reading' (duration: 1.382164478s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:34.154463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:32.772073Z","time spent":"1.382378845s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:34.154547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309596566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:53:34.154668Z","caller":"traceutil/trace.go:171","msg":"trace[136020420] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:727; }","duration":"1.309817391s","start":"2026-03-19T17:53:32.844828Z","end":"2026-03-19T17:53:34.154646Z","steps":["trace[136020420] 'agreement among raft nodes before linearized reading' (duration: 1.309509143s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:34.154762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:32.8448Z","time spent":"1.309934415s","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:53:34.154851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.799482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:34.154917Z","caller":"traceutil/trace.go:171","msg":"trace[1318596881] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:727; }","duration":"389.890945ms","start":"2026-03-19T17:53:33.765011Z","end":"2026-03-19T17:53:34.154902Z","steps":["trace[1318596881] 'agreement among raft nodes before linearized reading' (duration: 389.774051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:34.154958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:33.765004Z","time spent":"389.942976ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:53:34.155152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.140461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:34.155229Z","caller":"traceutil/trace.go:171","msg":"trace[1873735828] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:727; }","duration":"390.220063ms","start":"2026-03-19T17:53:33.764996Z","end":"2026-03-19T17:53:34.155216Z","steps":["trace[1873735828] 'agreement among raft nodes before linearized reading' (duration: 390.10924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:34.155301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:33.764968Z","time spent":"390.313866ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-03-19T17:53:34.15552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.578313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:34.155586Z","caller":"traceutil/trace.go:171","msg":"trace[342163553] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:727; }","duration":"390.678506ms","start":"2026-03-19T17:53:33.764896Z","end":"2026-03-19T17:53:34.155574Z","steps":["trace[342163553] 'agreement among raft nodes before linearized reading' (duration: 390.590093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:34.155654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:33.764872Z","time spent":"390.769198ms","remote":"127.0.0.1:47796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-03-19T17:53:34.155957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.477204ms","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-03-19T17:53:34.156035Z","caller":"traceutil/trace.go:171","msg":"trace[1617463898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"179.601768ms","start":"2026-03-19T17:53:33.976422Z","end":"2026-03-19T17:53:34.156024Z","steps":["trace[1617463898] 'agreement among raft nodes before linearized reading' (duration: 178.687313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:36.686852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884198945,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:53:37.034385Z","caller":"traceutil/trace.go:171","msg":"trace[329794061] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"848.535562ms","start":"2026-03-19T17:53:36.185816Z","end":"2026-03-19T17:53:37.034352Z","steps":["trace[329794061] 'read index received' (duration: 848.329686ms)","trace[329794061] 'applied index is now lower than readState.Index' (duration: 204.476µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:37.034465Z","caller":"traceutil/trace.go:171","msg":"trace[550745055] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"861.576775ms","start":"2026-03-19T17:53:36.17285Z","end":"2026-03-19T17:53:37.034427Z","steps":["trace[550745055] 'process raft request' (duration: 861.290368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:37.03466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.172832Z","time spent":"861.72776ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:37.034712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.82603ms","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-03-19T17:53:37.034751Z","caller":"traceutil/trace.go:171","msg":"trace[1877322143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"848.943973ms","start":"2026-03-19T17:53:36.185795Z","end":"2026-03-19T17:53:37.034739Z","steps":["trace[1877322143] 'agreement among raft nodes before linearized reading' (duration: 848.730407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:37.034753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.906842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-19T17:53:37.034763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.891706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-19T17:53:37.034841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.595015ms","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-03-19T17:53:37.034849Z","caller":"traceutil/trace.go:171","msg":"trace[11967374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"849.028675ms","start":"2026-03-19T17:53:36.185796Z","end":"2026-03-19T17:53:37.034825Z","steps":["trace[11967374] 'agreement among raft nodes before linearized reading' (duration: 848.709077ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:37.034875Z","caller":"traceutil/trace.go:171","msg":"trace[1065677440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"841.654226ms","start":"2026-03-19T17:53:36.193211Z","end":"2026-03-19T17:53:37.034865Z","steps":["trace[1065677440] 'agreement among raft nodes before linearized reading' (duration: 841.562245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:37.034907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.185733Z","time spent":"849.162819ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:53:37.034874Z","caller":"traceutil/trace.go:171","msg":"trace[716356975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"841.038209ms","start":"2026-03-19T17:53:36.193812Z","end":"2026-03-19T17:53:37.034851Z","steps":["trace[716356975] 'agreement among raft nodes before linearized reading' (duration: 840.781082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:37.034995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.193803Z","time spent":"841.181663ms","remote":"127.0.0.1:45804","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-03-19T17:53:37.034916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.193157Z","time spent":"841.751529ms","remote":"127.0.0.1:45804","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-03-19T17:53:37.034792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:36.185763Z","time spent":"849.018996ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:53:37.034759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.458833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T17:53:37.035174Z","caller":"traceutil/trace.go:171","msg":"trace[234784530] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:733; }","duration":"140.948905ms","start":"2026-03-19T17:53:36.894213Z","end":"2026-03-19T17:53:37.035162Z","steps":["trace[234784530] 'agreement among raft nodes before linearized reading' (duration: 140.325458ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:43.209009Z","caller":"traceutil/trace.go:171","msg":"trace[820588520] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"100.755937ms","start":"2026-03-19T17:53:43.108226Z","end":"2026-03-19T17:53:43.208982Z","steps":["trace[820588520] 'read index received' (duration: 100.507891ms)","trace[820588520] 'applied index is now lower than readState.Index' (duration: 247.276µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:43.209125Z","caller":"traceutil/trace.go:171","msg":"trace[1292932276] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"135.558559ms","start":"2026-03-19T17:53:43.073558Z","end":"2026-03-19T17:53:43.209117Z","steps":["trace[1292932276] 'process raft request' (duration: 135.23164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:43.209382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.136538ms","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-03-19T17:53:43.209417Z","caller":"traceutil/trace.go:171","msg":"trace[1094154732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"101.242461ms","start":"2026-03-19T17:53:43.108163Z","end":"2026-03-19T17:53:43.209406Z","steps":["trace[1094154732] 'agreement among raft nodes before linearized reading' (duration: 101.100487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:43.209625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.288303ms","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-03-19T17:53:43.209708Z","caller":"traceutil/trace.go:171","msg":"trace[88431550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"101.424646ms","start":"2026-03-19T17:53:43.108264Z","end":"2026-03-19T17:53:43.209689Z","steps":["trace[88431550] 'agreement among raft nodes before linearized reading' (duration: 101.159878ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:45.452346Z","caller":"traceutil/trace.go:171","msg":"trace[2025214027] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"153.441065ms","start":"2026-03-19T17:53:45.298871Z","end":"2026-03-19T17:53:45.452312Z","steps":["trace[2025214027] 'process raft request' (duration: 153.348552ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:45.45327Z","caller":"traceutil/trace.go:171","msg":"trace[1898809287] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"154.689748ms","start":"2026-03-19T17:53:45.298472Z","end":"2026-03-19T17:53:45.453162Z","steps":["trace[1898809287] 'process raft request' (duration: 153.538927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:47.643727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.063988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:53:47.643897Z","caller":"traceutil/trace.go:171","msg":"trace[1451200311] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"187.720942ms","start":"2026-03-19T17:53:47.456142Z","end":"2026-03-19T17:53:47.643863Z","steps":["trace[1451200311] 'read index received' (duration: 25.334255ms)","trace[1451200311] 'applied index is now lower than readState.Index' (duration: 162.385297ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:47.643956Z","caller":"traceutil/trace.go:171","msg":"trace[1371215913] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"269.571218ms","start":"2026-03-19T17:53:47.374352Z","end":"2026-03-19T17:53:47.643923Z","steps":["trace[1371215913] 'process raft request' (duration: 107.176031ms)","trace[1371215913] 'compare' (duration: 161.934435ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:47.644122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.984359ms","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-03-19T17:53:47.644171Z","caller":"traceutil/trace.go:171","msg":"trace[1813385758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"188.063991ms","start":"2026-03-19T17:53:47.456078Z","end":"2026-03-19T17:53:47.644142Z","steps":["trace[1813385758] 'agreement among raft nodes before linearized reading' (duration: 187.905486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:47.644369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.234959ms","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-03-19T17:53:47.644486Z","caller":"traceutil/trace.go:171","msg":"trace[858002337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"187.416483ms","start":"2026-03-19T17:53:47.457041Z","end":"2026-03-19T17:53:47.644457Z","steps":["trace[858002337] 'agreement among raft nodes before linearized reading' (duration: 187.031713ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:48.062021Z","caller":"traceutil/trace.go:171","msg":"trace[1862250942] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"412.663172ms","start":"2026-03-19T17:53:47.649322Z","end":"2026-03-19T17:53:48.061985Z","steps":["trace[1862250942] 'process raft request' (duration: 340.491678ms)","trace[1862250942] 'compare' (duration: 72.029959ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:48.062238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:47.64929Z","time spent":"412.825506ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:48.072347Z","caller":"traceutil/trace.go:171","msg":"trace[1787884168] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"302.642823ms","start":"2026-03-19T17:53:47.769684Z","end":"2026-03-19T17:53:48.072326Z","steps":["trace[1787884168] 'read index received' (duration: 220.094478ms)","trace[1787884168] 'applied index is now lower than readState.Index' (duration: 82.547615ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:48.072713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.016364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:48.072698Z","caller":"traceutil/trace.go:171","msg":"trace[758616854] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"422.92409ms","start":"2026-03-19T17:53:47.649725Z","end":"2026-03-19T17:53:48.07265Z","steps":["trace[758616854] 'process raft request' (duration: 422.505608ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:48.072759Z","caller":"traceutil/trace.go:171","msg":"trace[551057067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"303.123087ms","start":"2026-03-19T17:53:47.769623Z","end":"2026-03-19T17:53:48.072746Z","steps":["trace[551057067] 'agreement among raft nodes before linearized reading' (duration: 302.958392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:48.072807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:47.769593Z","time spent":"303.205839ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:53:48.072957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:47.649701Z","time spent":"423.104165ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:58.298413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.808326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:53:58.298528Z","caller":"traceutil/trace.go:171","msg":"trace[1536642739] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"950.804171ms","start":"2026-03-19T17:53:57.347707Z","end":"2026-03-19T17:53:58.298511Z","steps":["trace[1536642739] 'read index received' (duration: 397.697107ms)","trace[1536642739] 'applied index is now lower than readState.Index' (duration: 553.102464ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:58.298627Z","caller":"traceutil/trace.go:171","msg":"trace[1806046286] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.349799173s","start":"2026-03-19T17:53:56.948816Z","end":"2026-03-19T17:53:58.298615Z","steps":["trace[1806046286] 'process raft request' (duration: 796.664828ms)","trace[1806046286] 'compare' (duration: 552.57193ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:58.298684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:56.948795Z","time spent":"1.349858964s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:58.299005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.253893ms","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-03-19T17:53:58.299081Z","caller":"traceutil/trace.go:171","msg":"trace[1824229722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"828.390027ms","start":"2026-03-19T17:53:57.470669Z","end":"2026-03-19T17:53:58.299059Z","steps":["trace[1824229722] 'agreement among raft nodes before linearized reading' (duration: 828.12963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.299121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:57.470641Z","time spent":"828.473459ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:53:58.299141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.424093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-19T17:53:58.299202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.496779ms","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-03-19T17:53:58.2992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.63454ms","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-03-19T17:53:58.299244Z","caller":"traceutil/trace.go:171","msg":"trace[1285241821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"951.595983ms","start":"2026-03-19T17:53:57.347636Z","end":"2026-03-19T17:53:58.299232Z","steps":["trace[1285241821] 'agreement among raft nodes before linearized reading' (duration: 951.437988ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.299533Z","caller":"traceutil/trace.go:171","msg":"trace[1781830148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"608.028241ms","start":"2026-03-19T17:53:57.691481Z","end":"2026-03-19T17:53:58.299509Z","steps":["trace[1781830148] 'agreement among raft nodes before linearized reading' (duration: 607.479786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.299835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:57.347392Z","time spent":"952.374295ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:53:58.299683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.816654ms","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-03-19T17:53:58.299262Z","caller":"traceutil/trace.go:171","msg":"trace[102783992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"184.595588ms","start":"2026-03-19T17:53:58.114629Z","end":"2026-03-19T17:53:58.299225Z","steps":["trace[102783992] 'agreement among raft nodes before linearized reading' (duration: 184.257908ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.299955Z","caller":"traceutil/trace.go:171","msg":"trace[687942550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"185.186804ms","start":"2026-03-19T17:53:58.114744Z","end":"2026-03-19T17:53:58.299931Z","steps":["trace[687942550] 'agreement among raft nodes before linearized reading' (duration: 184.137565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.301983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:57.691449Z","time spent":"608.40884ms","remote":"127.0.0.1:45804","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-03-19T17:53:58.805632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884199120,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:53:58.839178Z","caller":"traceutil/trace.go:171","msg":"trace[790222278] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"534.48101ms","start":"2026-03-19T17:53:58.304666Z","end":"2026-03-19T17:53:58.839147Z","steps":["trace[790222278] 'process raft request' (duration: 534.306665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.839375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.304646Z","time spent":"534.625963ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:58.896439Z","caller":"traceutil/trace.go:171","msg":"trace[1488322698] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"591.252426ms","start":"2026-03-19T17:53:58.305166Z","end":"2026-03-19T17:53:58.896418Z","steps":["trace[1488322698] 'process raft request' (duration: 591.00835ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.896481Z","caller":"traceutil/trace.go:171","msg":"trace[1122825085] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"590.074085ms","start":"2026-03-19T17:53:58.306372Z","end":"2026-03-19T17:53:58.896446Z","steps":["trace[1122825085] 'process raft request' (duration: 589.975332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.896579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.305138Z","time spent":"591.3901ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:58.896716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.30636Z","time spent":"590.234639ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:53:58.897005Z","caller":"traceutil/trace.go:171","msg":"trace[1560421379] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:831; }","duration":"591.984257ms","start":"2026-03-19T17:53:58.304976Z","end":"2026-03-19T17:53:58.89696Z","steps":["trace[1560421379] 'read index received' (duration: 534.089619ms)","trace[1560421379] 'applied index is now lower than readState.Index' (duration: 57.891898ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:58.897159Z","caller":"traceutil/trace.go:171","msg":"trace[1479512527] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"590.313052ms","start":"2026-03-19T17:53:58.306823Z","end":"2026-03-19T17:53:58.897136Z","steps":["trace[1479512527] 'process raft request' (duration: 589.570362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.897191Z","caller":"traceutil/trace.go:171","msg":"trace[1065266413] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"589.12912ms","start":"2026-03-19T17:53:58.30803Z","end":"2026-03-19T17:53:58.897159Z","steps":["trace[1065266413] 'process raft request' (duration: 588.559054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.897266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.306791Z","time spent":"590.417634ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:58.897348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.348377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-19T17:53:58.897401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.308018Z","time spent":"589.255523ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:58.897667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.015262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:58.898003Z","caller":"traceutil/trace.go:171","msg":"trace[45488744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"126.423843ms","start":"2026-03-19T17:53:58.771553Z","end":"2026-03-19T17:53:58.897977Z","steps":["trace[45488744] 'agreement among raft nodes before linearized reading' (duration: 125.779856ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.897425Z","caller":"traceutil/trace.go:171","msg":"trace[998909844] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:799; }","duration":"592.446639ms","start":"2026-03-19T17:53:58.30496Z","end":"2026-03-19T17:53:58.897406Z","steps":["trace[998909844] 'agreement among raft nodes before linearized reading' (duration: 592.236233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.898359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.304949Z","time spent":"593.345103ms","remote":"127.0.0.1:45814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-19T17:54:01.367105Z","caller":"traceutil/trace.go:171","msg":"trace[601939474] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"464.183837ms","start":"2026-03-19T17:54:00.902887Z","end":"2026-03-19T17:54:01.367071Z","steps":["trace[601939474] 'read index received' (duration: 464.032383ms)","trace[601939474] 'applied index is now lower than readState.Index' (duration: 149.864µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:01.367263Z","caller":"traceutil/trace.go:171","msg":"trace[2034949822] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"518.237721ms","start":"2026-03-19T17:54:00.848984Z","end":"2026-03-19T17:54:01.367222Z","steps":["trace[2034949822] 'process raft request' (duration: 517.895101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:01.367475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:00.848941Z","time spent":"518.413175ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:01.367601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.660939ms","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-03-19T17:54:01.367644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.903372ms","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-03-19T17:54:01.367731Z","caller":"traceutil/trace.go:171","msg":"trace[630205593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"464.847024ms","start":"2026-03-19T17:54:00.902853Z","end":"2026-03-19T17:54:01.3677Z","steps":["trace[630205593] 'agreement among raft nodes before linearized reading' (duration: 464.408192ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:01.367739Z","caller":"traceutil/trace.go:171","msg":"trace[1445481067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"463.029175ms","start":"2026-03-19T17:54:00.904688Z","end":"2026-03-19T17:54:01.367717Z","steps":["trace[1445481067] 'agreement among raft nodes before linearized reading' (duration: 462.726717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:01.367804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:00.904682Z","time spent":"463.113478ms","remote":"127.0.0.1:45804","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-03-19T17:54:01.367806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:00.902835Z","time spent":"464.956789ms","remote":"127.0.0.1:45804","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-03-19T17:54:01.367769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.003454ms","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-03-19T17:54:01.368073Z","caller":"traceutil/trace.go:171","msg":"trace[1286409827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"463.393265ms","start":"2026-03-19T17:54:00.904659Z","end":"2026-03-19T17:54:01.368052Z","steps":["trace[1286409827] 'agreement among raft nodes before linearized reading' (duration: 462.81291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:01.368198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:00.904633Z","time spent":"463.519119ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:01.368232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.269951ms","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-03-19T17:54:01.368345Z","caller":"traceutil/trace.go:171","msg":"trace[1350501218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"463.484798ms","start":"2026-03-19T17:54:00.904844Z","end":"2026-03-19T17:54:01.368329Z","steps":["trace[1350501218] 'agreement among raft nodes before linearized reading' (duration: 462.575993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:01.368426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:00.904817Z","time spent":"463.596262ms","remote":"127.0.0.1:45804","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-03-19T17:54:01.642819Z","caller":"traceutil/trace.go:171","msg":"trace[1514524120] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"270.03038ms","start":"2026-03-19T17:54:01.37275Z","end":"2026-03-19T17:54:01.64278Z","steps":["trace[1514524120] 'process raft request' (duration: 232.681659ms)","trace[1514524120] 'compare' (duration: 36.870389ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:01.642969Z","caller":"traceutil/trace.go:171","msg":"trace[116834922] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"269.501918ms","start":"2026-03-19T17:54:01.373458Z","end":"2026-03-19T17:54:01.64296Z","steps":["trace[116834922] 'process raft request' (duration: 269.267711ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:01.643383Z","caller":"traceutil/trace.go:171","msg":"trace[909221651] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"269.556469ms","start":"2026-03-19T17:54:01.373808Z","end":"2026-03-19T17:54:01.643364Z","steps":["trace[909221651] 'process raft request' (duration: 269.102416ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:01.643594Z","caller":"traceutil/trace.go:171","msg":"trace[2083946292] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"268.666423ms","start":"2026-03-19T17:54:01.374883Z","end":"2026-03-19T17:54:01.643549Z","steps":["trace[2083946292] 'process raft request' (duration: 268.453477ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:03.554494Z","caller":"traceutil/trace.go:171","msg":"trace[658082653] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"176.095578ms","start":"2026-03-19T17:54:03.378359Z","end":"2026-03-19T17:54:03.554455Z","steps":["trace[658082653] 'process raft request' (duration: 175.870162ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:09.834246Z","caller":"traceutil/trace.go:171","msg":"trace[1636043266] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"135.92756ms","start":"2026-03-19T17:54:09.69828Z","end":"2026-03-19T17:54:09.834208Z","steps":["trace[1636043266] 'read index received' (duration: 135.747565ms)","trace[1636043266] 'applied index is now lower than readState.Index' (duration: 178.175µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:09.834726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.523035ms","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-03-19T17:54:09.834816Z","caller":"traceutil/trace.go:171","msg":"trace[112110663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"133.637738ms","start":"2026-03-19T17:54:09.701159Z","end":"2026-03-19T17:54:09.834797Z","steps":["trace[112110663] 'agreement among raft nodes before linearized reading' (duration: 133.395631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:09.834756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.090714ms","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-03-19T17:54:09.835024Z","caller":"traceutil/trace.go:171","msg":"trace[1777815445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"136.436484ms","start":"2026-03-19T17:54:09.698562Z","end":"2026-03-19T17:54:09.834998Z","steps":["trace[1777815445] 'agreement among raft nodes before linearized reading' (duration: 135.953362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:09.83505Z","caller":"traceutil/trace.go:171","msg":"trace[1114718952] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"247.688556ms","start":"2026-03-19T17:54:09.58733Z","end":"2026-03-19T17:54:09.835018Z","steps":["trace[1114718952] 'process raft request' (duration: 246.697039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:09.835177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.26721ms","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-03-19T17:54:09.835283Z","caller":"traceutil/trace.go:171","msg":"trace[1914662913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"137.03388ms","start":"2026-03-19T17:54:09.698223Z","end":"2026-03-19T17:54:09.835257Z","steps":["trace[1914662913] 'agreement among raft nodes before linearized reading' (duration: 136.195738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:09.835602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.92209ms","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-03-19T17:54:09.835746Z","caller":"traceutil/trace.go:171","msg":"trace[718679750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"136.120095ms","start":"2026-03-19T17:54:09.699601Z","end":"2026-03-19T17:54:09.835721Z","steps":["trace[718679750] 'agreement among raft nodes before linearized reading' (duration: 135.058406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:10.050125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.057031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:54:10.050247Z","caller":"traceutil/trace.go:171","msg":"trace[1308769745] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"209.439621ms","start":"2026-03-19T17:54:09.840787Z","end":"2026-03-19T17:54:10.050227Z","steps":["trace[1308769745] 'process raft request' (duration: 38.185454ms)","trace[1308769745] 'compare' (duration: 170.903747ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:10.054952Z","caller":"traceutil/trace.go:171","msg":"trace[580380007] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"213.744847ms","start":"2026-03-19T17:54:09.841119Z","end":"2026-03-19T17:54:10.054864Z","steps":["trace[580380007] 'process raft request' (duration: 213.554652ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:10.055222Z","caller":"traceutil/trace.go:171","msg":"trace[129758441] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"213.356726ms","start":"2026-03-19T17:54:09.841843Z","end":"2026-03-19T17:54:10.055199Z","steps":["trace[129758441] 'process raft request' (duration: 213.225012ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:10.055324Z","caller":"traceutil/trace.go:171","msg":"trace[901659075] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"213.582152ms","start":"2026-03-19T17:54:09.841733Z","end":"2026-03-19T17:54:10.055316Z","steps":["trace[901659075] 'process raft request' (duration: 213.094669ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:12.586964Z","caller":"traceutil/trace.go:171","msg":"trace[1343864671] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"144.491862ms","start":"2026-03-19T17:54:12.44244Z","end":"2026-03-19T17:54:12.586932Z","steps":["trace[1343864671] 'process raft request' (duration: 144.278606ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:14.083393Z","caller":"traceutil/trace.go:171","msg":"trace[761941749] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"110.654925ms","start":"2026-03-19T17:54:13.972689Z","end":"2026-03-19T17:54:14.083344Z","steps":["trace[761941749] 'process raft request' (duration: 100.595323ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:14.08341Z","caller":"traceutil/trace.go:171","msg":"trace[1805475727] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"110.535072ms","start":"2026-03-19T17:54:13.972829Z","end":"2026-03-19T17:54:14.083364Z","steps":["trace[1805475727] 'process raft request' (duration: 110.426569ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:14.345557Z","caller":"traceutil/trace.go:171","msg":"trace[130388638] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"167.097174ms","start":"2026-03-19T17:54:14.178429Z","end":"2026-03-19T17:54:14.345526Z","steps":["trace[130388638] 'process raft request' (duration: 166.163699ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:15.742726Z","caller":"traceutil/trace.go:171","msg":"trace[1647638493] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"221.520147ms","start":"2026-03-19T17:54:15.521182Z","end":"2026-03-19T17:54:15.742702Z","steps":["trace[1647638493] 'process raft request' (duration: 221.364974ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:15.888385Z","caller":"traceutil/trace.go:171","msg":"trace[1113077945] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"117.553382ms","start":"2026-03-19T17:54:15.770799Z","end":"2026-03-19T17:54:15.888352Z","steps":["trace[1113077945] 'read index received' (duration: 90.265254ms)","trace[1113077945] 'applied index is now lower than readState.Index' (duration: 27.286828ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:15.888511Z","caller":"traceutil/trace.go:171","msg":"trace[651149724] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"136.992029ms","start":"2026-03-19T17:54:15.751483Z","end":"2026-03-19T17:54:15.888475Z","steps":["trace[651149724] 'process raft request' (duration: 109.639709ms)","trace[651149724] 'compare' (duration: 26.955709ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:15.888701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.873711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:15.88882Z","caller":"traceutil/trace.go:171","msg":"trace[362981671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"118.041236ms","start":"2026-03-19T17:54:15.770751Z","end":"2026-03-19T17:54:15.888792Z","steps":["trace[362981671] 'agreement among raft nodes before linearized reading' (duration: 117.803179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:16.389329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.767806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-19T17:54:16.38933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.839938ms","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-03-19T17:54:16.389426Z","caller":"traceutil/trace.go:171","msg":"trace[1226274376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"210.90837ms","start":"2026-03-19T17:54:16.178498Z","end":"2026-03-19T17:54:16.389406Z","steps":["trace[1226274376] 'range keys from in-memory index tree' (duration: 210.55409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:16.389394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.15592ms","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-03-19T17:54:16.38945Z","caller":"traceutil/trace.go:171","msg":"trace[598814294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"211.039053ms","start":"2026-03-19T17:54:16.178385Z","end":"2026-03-19T17:54:16.389424Z","steps":["trace[598814294] 'range keys from in-memory index tree' (duration: 210.480668ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:16.389497Z","caller":"traceutil/trace.go:171","msg":"trace[583568099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"210.340715ms","start":"2026-03-19T17:54:16.179134Z","end":"2026-03-19T17:54:16.389474Z","steps":["trace[583568099] 'range keys from in-memory index tree' (duration: 209.905063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:16.389472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.122008ms","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-03-19T17:54:16.389569Z","caller":"traceutil/trace.go:171","msg":"trace[2104944011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"210.281282ms","start":"2026-03-19T17:54:16.179269Z","end":"2026-03-19T17:54:16.38955Z","steps":["trace[2104944011] 'range keys from in-memory index tree' (duration: 209.904933ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:16.509737Z","caller":"traceutil/trace.go:171","msg":"trace[1884972266] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"113.453961ms","start":"2026-03-19T17:54:16.396262Z","end":"2026-03-19T17:54:16.509715Z","steps":["trace[1884972266] 'process raft request' (duration: 113.292928ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:16.536912Z","caller":"traceutil/trace.go:171","msg":"trace[764534173] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"140.502594ms","start":"2026-03-19T17:54:16.396374Z","end":"2026-03-19T17:54:16.536877Z","steps":["trace[764534173] 'process raft request' (duration: 140.192655ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:16.537035Z","caller":"traceutil/trace.go:171","msg":"trace[1077543604] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"139.312672ms","start":"2026-03-19T17:54:16.397692Z","end":"2026-03-19T17:54:16.537004Z","steps":["trace[1077543604] 'process raft request' (duration: 139.134327ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:16.536905Z","caller":"traceutil/trace.go:171","msg":"trace[1174441055] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"140.316309ms","start":"2026-03-19T17:54:16.396558Z","end":"2026-03-19T17:54:16.536874Z","steps":["trace[1174441055] 'process raft request' (duration: 140.216286ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:17.08038Z","caller":"traceutil/trace.go:171","msg":"trace[2041317635] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"145.058367ms","start":"2026-03-19T17:54:16.935294Z","end":"2026-03-19T17:54:17.080352Z","steps":["trace[2041317635] 'process raft request' (duration: 144.849241ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:17.085904Z","caller":"traceutil/trace.go:171","msg":"trace[1577577409] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"139.028555ms","start":"2026-03-19T17:54:16.94683Z","end":"2026-03-19T17:54:17.085859Z","steps":["trace[1577577409] 'process raft request' (duration: 138.745487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:21.272922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884199377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:54:21.773682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884199377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:54:22.141952Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.50555461s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T17:54:22.201194Z","caller":"traceutil/trace.go:171","msg":"trace[1479009708] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"1.564860836s","start":"2026-03-19T17:54:20.63629Z","end":"2026-03-19T17:54:22.201151Z","steps":["trace[1479009708] 'process raft request' (duration: 1.505883459s)","trace[1479009708] 'compare' (duration: 57.21876ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:22.201271Z","caller":"traceutil/trace.go:171","msg":"trace[1306521826] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"1.428612327s","start":"2026-03-19T17:54:20.772606Z","end":"2026-03-19T17:54:22.201218Z","steps":["trace[1306521826] 'read index received' (duration: 1.369542367s)","trace[1306521826] 'applied index is now lower than readState.Index' (duration: 59.06862ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:22.201382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:20.636271Z","time spent":"1.565009839s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:22.201737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42912843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:22.201743Z","caller":"traceutil/trace.go:171","msg":"trace[360404968] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.564176086s","start":"2026-03-19T17:54:20.637547Z","end":"2026-03-19T17:54:22.201723Z","steps":["trace[360404968] 'process raft request' (duration: 1.56357355s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:22.201776Z","caller":"traceutil/trace.go:171","msg":"trace[88499592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"1.429209503s","start":"2026-03-19T17:54:20.772554Z","end":"2026-03-19T17:54:22.201763Z","steps":["trace[88499592] 'agreement among raft nodes before linearized reading' (duration: 1.429142481s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:22.201806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:20.772525Z","time spent":"1.429274704s","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:54:22.201831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:20.637536Z","time spent":"1.564246369s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:22.202011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.490421ms","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-03-19T17:54:22.202108Z","caller":"traceutil/trace.go:171","msg":"trace[539445880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"144.667957ms","start":"2026-03-19T17:54:22.057413Z","end":"2026-03-19T17:54:22.202081Z","steps":["trace[539445880] 'agreement among raft nodes before linearized reading' (duration: 144.366399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:22.710108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601806165884199382,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:54:23.149869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.063521ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:23.150022Z","caller":"traceutil/trace.go:171","msg":"trace[2011870111] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:907; }","duration":"701.232594ms","start":"2026-03-19T17:54:22.448766Z","end":"2026-03-19T17:54:23.149999Z","steps":["trace[2011870111] 'range keys from in-memory index tree' (duration: 701.04489ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:23.192099Z","caller":"traceutil/trace.go:171","msg":"trace[1044779370] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"985.397238ms","start":"2026-03-19T17:54:22.206677Z","end":"2026-03-19T17:54:23.192074Z","steps":["trace[1044779370] 'process raft request' (duration: 985.180122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.192271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.206656Z","time spent":"985.523141ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:23.2003Z","caller":"traceutil/trace.go:171","msg":"trace[1061221191] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:949; }","duration":"990.343371ms","start":"2026-03-19T17:54:22.209933Z","end":"2026-03-19T17:54:23.200276Z","steps":["trace[1061221191] 'read index received' (duration: 982.002885ms)","trace[1061221191] 'applied index is now lower than readState.Index' (duration: 8.339666ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:23.200447Z","caller":"traceutil/trace.go:171","msg":"trace[544949416] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"674.486511ms","start":"2026-03-19T17:54:22.525949Z","end":"2026-03-19T17:54:23.200436Z","steps":["trace[544949416] 'process raft request' (duration: 674.186912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.200591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.65024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:54:23.200604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.525924Z","time spent":"674.559231ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:23.200643Z","caller":"traceutil/trace.go:171","msg":"trace[380679046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"990.744113ms","start":"2026-03-19T17:54:22.209886Z","end":"2026-03-19T17:54:23.20063Z","steps":["trace[380679046] 'agreement among raft nodes before linearized reading' (duration: 990.65751ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:23.200642Z","caller":"traceutil/trace.go:171","msg":"trace[1091487413] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"408.007616ms","start":"2026-03-19T17:54:22.792594Z","end":"2026-03-19T17:54:23.200602Z","steps":["trace[1091487413] 'process raft request' (duration: 407.644476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.20085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.30964ms","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-03-19T17:54:23.200924Z","caller":"traceutil/trace.go:171","msg":"trace[1586016462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"659.412652ms","start":"2026-03-19T17:54:22.541499Z","end":"2026-03-19T17:54:23.200912Z","steps":["trace[1586016462] 'agreement among raft nodes before linearized reading' (duration: 659.239968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.200962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.54148Z","time spent":"659.471585ms","remote":"127.0.0.1:45804","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-03-19T17:54:23.200984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.792563Z","time spent":"408.236431ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:23.20111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.426056ms","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-03-19T17:54:23.201145Z","caller":"traceutil/trace.go:171","msg":"trace[641386391] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:910; }","duration":"224.551039ms","start":"2026-03-19T17:54:22.976585Z","end":"2026-03-19T17:54:23.201136Z","steps":["trace[641386391] 'agreement among raft nodes before linearized reading' (duration: 224.446386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.201151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.209876Z","time spent":"990.893537ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:54:23.201389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.906204ms","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-03-19T17:54:23.201426Z","caller":"traceutil/trace.go:171","msg":"trace[941857837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"622.989346ms","start":"2026-03-19T17:54:22.578427Z","end":"2026-03-19T17:54:23.201417Z","steps":["trace[941857837] 'agreement among raft nodes before linearized reading' (duration: 622.891193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:23.201652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:22.578404Z","time spent":"623.236483ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:54:35.533262Z","caller":"traceutil/trace.go:171","msg":"trace[855583991] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"257.694617ms","start":"2026-03-19T17:54:35.275511Z","end":"2026-03-19T17:54:35.533206Z","steps":["trace[855583991] 'read index received' (duration: 257.282255ms)","trace[855583991] 'applied index is now lower than readState.Index' (duration: 410.872µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:35.533337Z","caller":"traceutil/trace.go:171","msg":"trace[1365475245] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"266.162506ms","start":"2026-03-19T17:54:35.267134Z","end":"2026-03-19T17:54:35.533296Z","steps":["trace[1365475245] 'process raft request' (duration: 265.788766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:35.533827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.726704ms","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-03-19T17:54:35.533844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.244491ms","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-03-19T17:54:35.534015Z","caller":"traceutil/trace.go:171","msg":"trace[1758679402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"258.512988ms","start":"2026-03-19T17:54:35.275466Z","end":"2026-03-19T17:54:35.533979Z","steps":["trace[1758679402] 'agreement among raft nodes before linearized reading' (duration: 257.943553ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:35.537701Z","caller":"traceutil/trace.go:171","msg":"trace[435566876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"255.864237ms","start":"2026-03-19T17:54:35.278038Z","end":"2026-03-19T17:54:35.533903Z","steps":["trace[435566876] 'agreement among raft nodes before linearized reading' (duration: 255.434065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:35.984059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.925372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:54:35.984327Z","caller":"traceutil/trace.go:171","msg":"trace[1072238075] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"213.49154ms","start":"2026-03-19T17:54:35.770811Z","end":"2026-03-19T17:54:35.984302Z","steps":["trace[1072238075] 'read index received' (duration: 201.752632ms)","trace[1072238075] 'applied index is now lower than readState.Index' (duration: 11.736808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:35.984453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.634424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:35.984504Z","caller":"traceutil/trace.go:171","msg":"trace[1208229067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"213.678065ms","start":"2026-03-19T17:54:35.770803Z","end":"2026-03-19T17:54:35.984481Z","steps":["trace[1208229067] 'agreement among raft nodes before linearized reading' (duration: 213.580982ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:35.984486Z","caller":"traceutil/trace.go:171","msg":"trace[260478859] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"443.153128ms","start":"2026-03-19T17:54:35.541277Z","end":"2026-03-19T17:54:35.98443Z","steps":["trace[260478859] 'process raft request' (duration: 54.727082ms)","trace[260478859] 'compare' (duration: 387.777378ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:35.984607Z","caller":"traceutil/trace.go:171","msg":"trace[2038448012] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"441.799671ms","start":"2026-03-19T17:54:35.54267Z","end":"2026-03-19T17:54:35.98447Z","steps":["trace[2038448012] 'process raft request' (duration: 441.549674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:35.984735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:35.541254Z","time spent":"443.342213ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:35.985845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:35.542642Z","time spent":"442.100178ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:38.667542Z","caller":"traceutil/trace.go:171","msg":"trace[1940162503] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"133.857024ms","start":"2026-03-19T17:54:38.533654Z","end":"2026-03-19T17:54:38.667511Z","steps":["trace[1940162503] 'process raft request' (duration: 133.624688ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:38.667781Z","caller":"traceutil/trace.go:171","msg":"trace[265748718] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"128.964072ms","start":"2026-03-19T17:54:38.538781Z","end":"2026-03-19T17:54:38.667745Z","steps":["trace[265748718] 'process raft request' (duration: 128.673034ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:39.8456Z","caller":"traceutil/trace.go:171","msg":"trace[1654859380] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"291.839021ms","start":"2026-03-19T17:54:39.553712Z","end":"2026-03-19T17:54:39.845551Z","steps":["trace[1654859380] 'process raft request' (duration: 291.670576ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:40.089078Z","caller":"traceutil/trace.go:171","msg":"trace[1343295201] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"240.133292ms","start":"2026-03-19T17:54:39.848891Z","end":"2026-03-19T17:54:40.089024Z","steps":["trace[1343295201] 'process raft request' (duration: 220.987913ms)","trace[1343295201] 'compare' (duration: 18.883391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:40.488611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.383905ms","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-03-19T17:54:40.488723Z","caller":"traceutil/trace.go:171","msg":"trace[177817257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"101.5839ms","start":"2026-03-19T17:54:40.387115Z","end":"2026-03-19T17:54:40.488699Z","steps":["trace[177817257] 'range keys from in-memory index tree' (duration: 101.112427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:40.970044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.316419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:40.970133Z","caller":"traceutil/trace.go:171","msg":"trace[1746100935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"198.462463ms","start":"2026-03-19T17:54:40.77165Z","end":"2026-03-19T17:54:40.970113Z","steps":["trace[1746100935] 'range keys from in-memory index tree' (duration: 198.152955ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:41.863163Z","caller":"traceutil/trace.go:171","msg":"trace[1063138402] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"416.209077ms","start":"2026-03-19T17:54:41.44693Z","end":"2026-03-19T17:54:41.863139Z","steps":["trace[1063138402] 'read index received' (duration: 416.076224ms)","trace[1063138402] 'applied index is now lower than readState.Index' (duration: 131.113µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:41.86319Z","caller":"traceutil/trace.go:171","msg":"trace[2084534960] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"522.712712ms","start":"2026-03-19T17:54:41.340453Z","end":"2026-03-19T17:54:41.863166Z","steps":["trace[2084534960] 'process raft request' (duration: 522.532417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:41.863343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.402792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:54:41.863382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:41.340429Z","time spent":"522.867995ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:54:41.863394Z","caller":"traceutil/trace.go:171","msg":"trace[44664717] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:980; }","duration":"416.508707ms","start":"2026-03-19T17:54:41.446873Z","end":"2026-03-19T17:54:41.863382Z","steps":["trace[44664717] 'agreement among raft nodes before linearized reading' (duration: 416.370652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:41.863438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:41.446843Z","time spent":"416.585688ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-19T17:54:42.2483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.272173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:54:42.248536Z","caller":"traceutil/trace.go:171","msg":"trace[1823101658] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"380.108021ms","start":"2026-03-19T17:54:41.868414Z","end":"2026-03-19T17:54:42.248522Z","steps":["trace[1823101658] 'process raft request' (duration: 275.54696ms)","trace[1823101658] 'compare' (duration: 104.17572ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:42.248614Z","caller":"traceutil/trace.go:171","msg":"trace[1075981983] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"380.076709ms","start":"2026-03-19T17:54:41.86848Z","end":"2026-03-19T17:54:42.248557Z","steps":["trace[1075981983] 'process raft request' (duration: 379.895054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:42.248806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:41.868464Z","time spent":"380.249125ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:54:42.248829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.679464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-19T17:54:42.248892Z","caller":"traceutil/trace.go:171","msg":"trace[1793848059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"114.797978ms","start":"2026-03-19T17:54:42.134075Z","end":"2026-03-19T17:54:42.248873Z","steps":["trace[1793848059] 'agreement among raft nodes before linearized reading' (duration: 114.603503ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:42.248477Z","caller":"traceutil/trace.go:171","msg":"trace[1058149691] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1027; }","duration":"114.315784ms","start":"2026-03-19T17:54:42.134149Z","end":"2026-03-19T17:54:42.248465Z","steps":["trace[1058149691] 'read index received' (duration: 9.828176ms)","trace[1058149691] 'applied index is now lower than readState.Index' (duration: 104.486648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:42.249198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:41.868391Z","time spent":"380.173362ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:42.743856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.541624ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:42.744015Z","caller":"traceutil/trace.go:171","msg":"trace[1836982833] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:983; }","duration":"294.727359ms","start":"2026-03-19T17:54:42.449267Z","end":"2026-03-19T17:54:42.743995Z","steps":["trace[1836982833] 'range keys from in-memory index tree' (duration: 294.513414ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:42.805163Z","caller":"traceutil/trace.go:171","msg":"trace[1886661107] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"550.286278ms","start":"2026-03-19T17:54:42.254844Z","end":"2026-03-19T17:54:42.80513Z","steps":["trace[1886661107] 'process raft request' (duration: 549.996951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:42.805415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.254799Z","time spent":"550.476883ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:42.805456Z","caller":"traceutil/trace.go:171","msg":"trace[76800488] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"550.140615ms","start":"2026-03-19T17:54:42.255261Z","end":"2026-03-19T17:54:42.805402Z","steps":["trace[76800488] 'process raft request' (duration: 549.798816ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:42.805504Z","caller":"traceutil/trace.go:171","msg":"trace[713091794] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"524.173371ms","start":"2026-03-19T17:54:42.281309Z","end":"2026-03-19T17:54:42.805482Z","steps":["trace[713091794] 'process raft request' (duration: 524.11053ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:42.805554Z","caller":"traceutil/trace.go:171","msg":"trace[399074283] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"548.012767ms","start":"2026-03-19T17:54:42.257517Z","end":"2026-03-19T17:54:42.80553Z","steps":["trace[399074283] 'process raft request' (duration: 547.792061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:42.805646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.255253Z","time spent":"550.304508ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:54:42.805655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.281277Z","time spent":"524.295234ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:54:42.805676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.257507Z","time spent":"548.092829ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:43.128595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.950688ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T17:54:43.128869Z","caller":"traceutil/trace.go:171","msg":"trace[1405515261] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"317.649229ms","start":"2026-03-19T17:54:42.811202Z","end":"2026-03-19T17:54:43.128851Z","steps":["trace[1405515261] 'process raft request' (duration: 317.520016ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.128852Z","caller":"traceutil/trace.go:171","msg":"trace[2076950940] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1030; }","duration":"773.961963ms","start":"2026-03-19T17:54:42.35485Z","end":"2026-03-19T17:54:43.128812Z","steps":["trace[2076950940] 'read index received' (duration: 450.017903ms)","trace[2076950940] 'applied index is now lower than readState.Index' (duration: 323.93968ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:43.128967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.81117Z","time spent":"317.734913ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:54:43.129094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.227831ms","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-03-19T17:54:43.129191Z","caller":"traceutil/trace.go:171","msg":"trace[1836580842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:988; }","duration":"774.366913ms","start":"2026-03-19T17:54:42.354793Z","end":"2026-03-19T17:54:43.12916Z","steps":["trace[1836580842] 'agreement among raft nodes before linearized reading' (duration: 774.096276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.129237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.354771Z","time spent":"774.459468ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:43.129297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.291136ms","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-03-19T17:54:43.129297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.242803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:43.129447Z","caller":"traceutil/trace.go:171","msg":"trace[33557885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"612.461151ms","start":"2026-03-19T17:54:42.51691Z","end":"2026-03-19T17:54:43.129371Z","steps":["trace[33557885] 'agreement among raft nodes before linearized reading' (duration: 612.175603ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.129434Z","caller":"traceutil/trace.go:171","msg":"trace[244474141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"357.453628ms","start":"2026-03-19T17:54:42.771946Z","end":"2026-03-19T17:54:43.1294Z","steps":["trace[244474141] 'agreement among raft nodes before linearized reading' (duration: 357.190801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.129496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.516874Z","time spent":"612.610504ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:43.129519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.771921Z","time spent":"357.58557ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:54:43.3512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.689995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:54:43.351296Z","caller":"traceutil/trace.go:171","msg":"trace[460526598] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"217.786047ms","start":"2026-03-19T17:54:43.133497Z","end":"2026-03-19T17:54:43.351283Z","steps":["trace[460526598] 'process raft request' (duration: 95.943918ms)","trace[460526598] 'compare' (duration: 121.577042ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:43.449205Z","caller":"traceutil/trace.go:171","msg":"trace[1612609559] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"103.512913ms","start":"2026-03-19T17:54:43.345665Z","end":"2026-03-19T17:54:43.449178Z","steps":["trace[1612609559] 'process raft request' (duration: 103.356358ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:44.020557Z","caller":"traceutil/trace.go:171","msg":"trace[2015993861] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"372.265739ms","start":"2026-03-19T17:54:43.648263Z","end":"2026-03-19T17:54:44.020528Z","steps":["trace[2015993861] 'process raft request' (duration: 372.063173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:44.020756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.648235Z","time spent":"372.415343ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:44.518075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.561768ms","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-03-19T17:54:44.518383Z","caller":"traceutil/trace.go:171","msg":"trace[1685033003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"262.846285ms","start":"2026-03-19T17:54:44.2554Z","end":"2026-03-19T17:54:44.518246Z","steps":["trace[1685033003] 'range keys from in-memory index tree' (duration: 262.332191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:44.519229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.709006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-19T17:54:44.519689Z","caller":"traceutil/trace.go:171","msg":"trace[2112456030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"231.19689ms","start":"2026-03-19T17:54:44.288475Z","end":"2026-03-19T17:54:44.519672Z","steps":["trace[2112456030] 'range keys from in-memory index tree' (duration: 230.584812ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:45.731722Z","caller":"traceutil/trace.go:171","msg":"trace[1735003718] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"375.775213ms","start":"2026-03-19T17:54:45.355915Z","end":"2026-03-19T17:54:45.73169Z","steps":["trace[1735003718] 'read index received' (duration: 375.201227ms)","trace[1735003718] 'applied index is now lower than readState.Index' (duration: 572.176µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:45.731776Z","caller":"traceutil/trace.go:171","msg":"trace[951605434] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"556.608158ms","start":"2026-03-19T17:54:45.175137Z","end":"2026-03-19T17:54:45.731745Z","steps":["trace[951605434] 'process raft request' (duration: 556.357912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:45.731955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.02452ms","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-03-19T17:54:45.73197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:45.175109Z","time spent":"556.736002ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:45.732023Z","caller":"traceutil/trace.go:171","msg":"trace[5031220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"376.107223ms","start":"2026-03-19T17:54:45.355896Z","end":"2026-03-19T17:54:45.732004Z","steps":["trace[5031220] 'agreement among raft nodes before linearized reading' (duration: 375.917397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:45.73208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:45.355835Z","time spent":"376.231835ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:54:51.815037Z","caller":"traceutil/trace.go:171","msg":"trace[1026133781] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"116.623187ms","start":"2026-03-19T17:54:51.698379Z","end":"2026-03-19T17:54:51.815002Z","steps":["trace[1026133781] 'process raft request' (duration: 116.3476ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:52.434008Z","caller":"traceutil/trace.go:171","msg":"trace[466453716] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"612.575734ms","start":"2026-03-19T17:54:51.821166Z","end":"2026-03-19T17:54:52.433742Z","steps":["trace[466453716] 'process raft request' (duration: 522.751582ms)","trace[466453716] 'compare' (duration: 89.596696ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:52.434151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:51.821095Z","time spent":"612.980634ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:53.028442Z","caller":"traceutil/trace.go:171","msg":"trace[1179265691] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.207179221s","start":"2026-03-19T17:54:51.821202Z","end":"2026-03-19T17:54:53.028381Z","steps":["trace[1179265691] 'process raft request' (duration: 1.118035007s)","trace[1179265691] 'compare' (duration: 88.792634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:53.028605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:51.82118Z","time spent":"1.207336335s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:53.032107Z","caller":"traceutil/trace.go:171","msg":"trace[395394041] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1096; }","duration":"1.161017822s","start":"2026-03-19T17:54:51.871061Z","end":"2026-03-19T17:54:53.032079Z","steps":["trace[395394041] 'read index received' (duration: 472.82044ms)","trace[395394041] 'applied index is now lower than readState.Index' (duration: 688.195772ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:53.032228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161162185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:54:53.032325Z","caller":"traceutil/trace.go:171","msg":"trace[1171912744] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1049; }","duration":"1.161265858s","start":"2026-03-19T17:54:51.871039Z","end":"2026-03-19T17:54:53.032304Z","steps":["trace[1171912744] 'agreement among raft nodes before linearized reading' (duration: 1.161128234s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:53.032378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.570153ms","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-03-19T17:54:53.032468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.625619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:53.032476Z","caller":"traceutil/trace.go:171","msg":"trace[648434900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"387.715796ms","start":"2026-03-19T17:54:52.644743Z","end":"2026-03-19T17:54:53.032459Z","steps":["trace[648434900] 'agreement among raft nodes before linearized reading' (duration: 387.5052ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:53.032512Z","caller":"traceutil/trace.go:171","msg":"trace[316548629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"259.66925ms","start":"2026-03-19T17:54:52.772814Z","end":"2026-03-19T17:54:53.032483Z","steps":["trace[316548629] 'agreement among raft nodes before linearized reading' (duration: 259.63813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:53.032484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.279397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T17:54:53.032567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:52.644722Z","time spent":"387.784369ms","remote":"127.0.0.1:45804","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-03-19T17:54:53.032658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.26768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-19T17:54:53.032703Z","caller":"traceutil/trace.go:171","msg":"trace[783262929] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1049; }","duration":"204.308151ms","start":"2026-03-19T17:54:52.82838Z","end":"2026-03-19T17:54:53.032688Z","steps":["trace[783262929] 'agreement among raft nodes before linearized reading' (duration: 204.200768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:53.03238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:51.871012Z","time spent":"1.161360481s","remote":"127.0.0.1:51792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:54:53.032813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.248904ms","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-03-19T17:54:53.032922Z","caller":"traceutil/trace.go:171","msg":"trace[1702019404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"380.423179ms","start":"2026-03-19T17:54:52.652475Z","end":"2026-03-19T17:54:53.032898Z","steps":["trace[1702019404] 'agreement among raft nodes before linearized reading' (duration: 380.08811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:53.032974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:52.652451Z","time spent":"380.515551ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:54:53.033019Z","caller":"traceutil/trace.go:171","msg":"trace[406616167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"113.40531ms","start":"2026-03-19T17:54:52.919136Z","end":"2026-03-19T17:54:53.032542Z","steps":["trace[406616167] 'agreement among raft nodes before linearized reading' (duration: 113.155944ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:17.412942Z","caller":"traceutil/trace.go:171","msg":"trace[762052747] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"161.207984ms","start":"2026-03-19T17:55:17.251705Z","end":"2026-03-19T17:55:17.412913Z","steps":["trace[762052747] 'read index received' (duration: 150.453073ms)","trace[762052747] 'applied index is now lower than readState.Index' (duration: 10.753231ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:17.413059Z","caller":"traceutil/trace.go:171","msg":"trace[1646385791] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"166.002845ms","start":"2026-03-19T17:55:17.247015Z","end":"2026-03-19T17:55:17.413018Z","steps":["trace[1646385791] 'process raft request' (duration: 155.057938ms)","trace[1646385791] 'compare' (duration: 10.716151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:55:17.413208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.486221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-19T17:55:17.413238Z","caller":"traceutil/trace.go:171","msg":"trace[1272310396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1152; }","duration":"161.568945ms","start":"2026-03-19T17:55:17.251662Z","end":"2026-03-19T17:55:17.413231Z","steps":["trace[1272310396] 'agreement among raft nodes before linearized reading' (duration: 161.4086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:17.413405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.246778ms","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-03-19T17:55:17.413518Z","caller":"traceutil/trace.go:171","msg":"trace[1829080877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1152; }","duration":"160.412633ms","start":"2026-03-19T17:55:17.25308Z","end":"2026-03-19T17:55:17.413493Z","steps":["trace[1829080877] 'agreement among raft nodes before linearized reading' (duration: 160.076774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.375447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.440128ms","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-03-19T17:55:23.375551Z","caller":"traceutil/trace.go:171","msg":"trace[1548943738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"108.605884ms","start":"2026-03-19T17:55:23.266926Z","end":"2026-03-19T17:55:23.375531Z","steps":["trace[1548943738] 'range keys from in-memory index tree' (duration: 108.152031ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.534947Z","caller":"traceutil/trace.go:171","msg":"trace[489556952] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"154.587408ms","start":"2026-03-19T17:55:23.380312Z","end":"2026-03-19T17:55:23.534899Z","steps":["trace[489556952] 'process raft request' (duration: 154.319922ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.672315Z","caller":"traceutil/trace.go:171","msg":"trace[1352605185] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"132.062813ms","start":"2026-03-19T17:55:23.540213Z","end":"2026-03-19T17:55:23.672276Z","steps":["trace[1352605185] 'process raft request' (duration: 67.816271ms)","trace[1352605185] 'compare' (duration: 63.78413ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:23.672366Z","caller":"traceutil/trace.go:171","msg":"trace[1287592841] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"131.295803ms","start":"2026-03-19T17:55:23.541026Z","end":"2026-03-19T17:55:23.672322Z","steps":["trace[1287592841] 'process raft request' (duration: 131.261251ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.672543Z","caller":"traceutil/trace.go:171","msg":"trace[230895206] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"131.587091ms","start":"2026-03-19T17:55:23.540949Z","end":"2026-03-19T17:55:23.672536Z","steps":["trace[230895206] 'process raft request' (duration: 131.21363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:44.047911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.712182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-19T17:55:44.048155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.689076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-19T17:55:44.04822Z","caller":"traceutil/trace.go:171","msg":"trace[1980213297] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1257; }","duration":"258.096643ms","start":"2026-03-19T17:55:43.790107Z","end":"2026-03-19T17:55:44.048204Z","steps":["trace[1980213297] 'count revisions from in-memory index tree' (duration: 257.509187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:44.048043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.240434ms","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-03-19T17:55:44.048257Z","caller":"traceutil/trace.go:171","msg":"trace[1762119274] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1257; }","duration":"226.85606ms","start":"2026-03-19T17:55:43.821374Z","end":"2026-03-19T17:55:44.04823Z","steps":["trace[1762119274] 'range keys from in-memory index tree' (duration: 226.45542ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:44.048297Z","caller":"traceutil/trace.go:171","msg":"trace[2096250269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"237.564022ms","start":"2026-03-19T17:55:43.810715Z","end":"2026-03-19T17:55:44.048279Z","steps":["trace[2096250269] 'range keys from in-memory index tree' (duration: 236.945116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:44.048355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.850665ms","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-03-19T17:55:44.048092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.541847ms","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-03-19T17:55:44.048471Z","caller":"traceutil/trace.go:171","msg":"trace[2101243370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"228.973008ms","start":"2026-03-19T17:55:43.819481Z","end":"2026-03-19T17:55:44.048454Z","steps":["trace[2101243370] 'range keys from in-memory index tree' (duration: 228.31487ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:44.048483Z","caller":"traceutil/trace.go:171","msg":"trace[1091861725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"229.009309ms","start":"2026-03-19T17:55:43.819443Z","end":"2026-03-19T17:55:44.048453Z","steps":["trace[1091861725] 'range keys from in-memory index tree' (duration: 228.576888ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:45.933673Z","caller":"traceutil/trace.go:171","msg":"trace[1055723498] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"162.119053ms","start":"2026-03-19T17:55:45.771522Z","end":"2026-03-19T17:55:45.933641Z","steps":["trace[1055723498] 'read index received' (duration: 161.924198ms)","trace[1055723498] 'applied index is now lower than readState.Index' (duration: 193.155µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:45.9338Z","caller":"traceutil/trace.go:171","msg":"trace[1343538569] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"179.491568ms","start":"2026-03-19T17:55:45.754264Z","end":"2026-03-19T17:55:45.933756Z","steps":["trace[1343538569] 'process raft request' (duration: 179.19068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:45.933865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.311778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:55:45.933973Z","caller":"traceutil/trace.go:171","msg":"trace[138583849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"162.427283ms","start":"2026-03-19T17:55:45.771495Z","end":"2026-03-19T17:55:45.933922Z","steps":["trace[138583849] 'agreement among raft nodes before linearized reading' (duration: 162.262848ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:02.235166Z","caller":"traceutil/trace.go:171","msg":"trace[1754373516] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"135.707313ms","start":"2026-03-19T17:56:02.099391Z","end":"2026-03-19T17:56:02.235098Z","steps":["trace[1754373516] 'process raft request' (duration: 135.247071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:02.495134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.865377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:56:02.495325Z","caller":"traceutil/trace.go:171","msg":"trace[538866313] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"254.255418ms","start":"2026-03-19T17:56:02.241038Z","end":"2026-03-19T17:56:02.495293Z","steps":["trace[538866313] 'process raft request' (duration: 117.928988ms)","trace[538866313] 'compare' (duration: 135.629091ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:02.495511Z","caller":"traceutil/trace.go:171","msg":"trace[574004837] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"254.044782ms","start":"2026-03-19T17:56:02.241419Z","end":"2026-03-19T17:56:02.495464Z","steps":["trace[574004837] 'process raft request' (duration: 253.827706ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:02.495603Z","caller":"traceutil/trace.go:171","msg":"trace[1128190814] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"253.401814ms","start":"2026-03-19T17:56:02.24208Z","end":"2026-03-19T17:56:02.495482Z","steps":["trace[1128190814] 'process raft request' (duration: 253.25374ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:02.495719Z","caller":"traceutil/trace.go:171","msg":"trace[463951133] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"252.831059ms","start":"2026-03-19T17:56:02.242872Z","end":"2026-03-19T17:56:02.495703Z","steps":["trace[463951133] 'process raft request' (duration: 252.50656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:02.670126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.668846ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T17:56:22.812903Z","caller":"traceutil/trace.go:171","msg":"trace[118289911] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"116.257462ms","start":"2026-03-19T17:56:22.696615Z","end":"2026-03-19T17:56:22.812872Z","steps":["trace[118289911] 'process raft request' (duration: 70.830102ms)","trace[118289911] 'compare' (duration: 45.320197ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:34.869777Z","caller":"traceutil/trace.go:171","msg":"trace[297579576] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"180.280659ms","start":"2026-03-19T17:56:34.689458Z","end":"2026-03-19T17:56:34.869739Z","steps":["trace[297579576] 'process raft request' (duration: 180.030933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:35.160739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.308974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:56:35.16105Z","caller":"traceutil/trace.go:171","msg":"trace[1774821583] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"286.358574ms","start":"2026-03-19T17:56:34.874643Z","end":"2026-03-19T17:56:35.161002Z","steps":["trace[1774821583] 'process raft request' (duration: 169.558756ms)","trace[1774821583] 'compare' (duration: 115.996635ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:35.16123Z","caller":"traceutil/trace.go:171","msg":"trace[646749084] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"286.451457ms","start":"2026-03-19T17:56:34.874762Z","end":"2026-03-19T17:56:35.161214Z","steps":["trace[646749084] 'process raft request' (duration: 286.112687ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:35.16115Z","caller":"traceutil/trace.go:171","msg":"trace[951475330] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1533; }","duration":"271.9223ms","start":"2026-03-19T17:56:34.889207Z","end":"2026-03-19T17:56:35.161129Z","steps":["trace[951475330] 'read index received' (duration: 33.906616ms)","trace[951475330] 'applied index is now lower than readState.Index' (duration: 238.013864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:56:35.161496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.283909ms","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-03-19T17:56:35.161547Z","caller":"traceutil/trace.go:171","msg":"trace[1238686885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1466; }","duration":"272.418743ms","start":"2026-03-19T17:56:34.889118Z","end":"2026-03-19T17:56:35.161537Z","steps":["trace[1238686885] 'agreement among raft nodes before linearized reading' (duration: 272.251928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:08.562519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.231021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-19T17:57:08.562666Z","caller":"traceutil/trace.go:171","msg":"trace[489635518] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1602; }","duration":"125.452267ms","start":"2026-03-19T17:57:08.437184Z","end":"2026-03-19T17:57:08.562636Z","steps":["trace[489635518] 'range keys from in-memory index tree' (duration: 124.963744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:08.789507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.546229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:08.789684Z","caller":"traceutil/trace.go:171","msg":"trace[1796902781] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"221.052941ms","start":"2026-03-19T17:57:08.568569Z","end":"2026-03-19T17:57:08.789622Z","steps":["trace[1796902781] 'process raft request' (duration: 115.311307ms)","trace[1796902781] 'compare' (duration: 105.290933ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:35.208152Z","caller":"traceutil/trace.go:171","msg":"trace[1921229504] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"150.348576ms","start":"2026-03-19T17:57:35.057769Z","end":"2026-03-19T17:57:35.208117Z","steps":["trace[1921229504] 'process raft request' (duration: 150.11877ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:35.332508Z","caller":"traceutil/trace.go:171","msg":"trace[1204712989] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"101.799859ms","start":"2026-03-19T17:57:35.230674Z","end":"2026-03-19T17:57:35.332474Z","steps":["trace[1204712989] 'process raft request' (duration: 101.551662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:58:07.242263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.65536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:58:07.242473Z","caller":"traceutil/trace.go:171","msg":"trace[1047921482] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"249.294002ms","start":"2026-03-19T17:58:06.993153Z","end":"2026-03-19T17:58:07.242447Z","steps":["trace[1047921482] 'read index received' (duration: 42.107727ms)","trace[1047921482] 'applied index is now lower than readState.Index' (duration: 207.183955ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:58:07.242487Z","caller":"traceutil/trace.go:171","msg":"trace[137295530] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"254.36384ms","start":"2026-03-19T17:58:06.988081Z","end":"2026-03-19T17:58:07.242445Z","steps":["trace[137295530] 'process raft request' (duration: 47.194856ms)","trace[137295530] 'compare' (duration: 206.481915ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:58:07.242964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.807866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-03-19T17:58:07.243113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.811417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-9cfb1c9ded7870a5436cd7e6b965fa6901705b08fe2b9856f330161a16a67188\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-03-19T17:58:07.24317Z","caller":"traceutil/trace.go:171","msg":"trace[1950935467] range","detail":"{range_begin:/registry/volumeattachments/csi-9cfb1c9ded7870a5436cd7e6b965fa6901705b08fe2b9856f330161a16a67188; range_end:; response_count:1; response_revision:1861; }","duration":"205.92651ms","start":"2026-03-19T17:58:07.037228Z","end":"2026-03-19T17:58:07.243154Z","steps":["trace[1950935467] 'agreement among raft nodes before linearized reading' (duration: 205.720514ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:58:07.243042Z","caller":"traceutil/trace.go:171","msg":"trace[1989053603] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1861; }","duration":"249.894937ms","start":"2026-03-19T17:58:06.99313Z","end":"2026-03-19T17:58:07.243024Z","steps":["trace[1989053603] 'agreement among raft nodes before linearized reading' (duration: 249.772114ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:58:10.567023Z","caller":"traceutil/trace.go:171","msg":"trace[1644938088] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"273.11413ms","start":"2026-03-19T17:58:10.293873Z","end":"2026-03-19T17:58:10.566988Z","steps":["trace[1644938088] 'process raft request' (duration: 272.851973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:58:18.262831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.809664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-19T17:58:18.262922Z","caller":"traceutil/trace.go:171","msg":"trace[1839175453] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1913; }","duration":"329.948948ms","start":"2026-03-19T17:58:17.93295Z","end":"2026-03-19T17:58:18.262899Z","steps":["trace[1839175453] 'range keys from in-memory index tree' (duration: 329.606749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:58:18.262969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:58:17.932927Z","time spent":"330.03337ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-19T17:58:18.263215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.775134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:58:18.263313Z","caller":"traceutil/trace.go:171","msg":"trace[484997163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1913; }","duration":"330.01132ms","start":"2026-03-19T17:58:17.933275Z","end":"2026-03-19T17:58:18.263286Z","steps":["trace[484997163] 'range keys from in-memory index tree' (duration: 329.60962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:58:18.263336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.693312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T17:58:18.26337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:58:17.933263Z","time spent":"330.089752ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:58:18.263635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.211285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-19T17:58:18.263666Z","caller":"traceutil/trace.go:171","msg":"trace[351193085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1913; }","duration":"330.281857ms","start":"2026-03-19T17:58:17.933376Z","end":"2026-03-19T17:58:18.263657Z","steps":["trace[351193085] 'range keys from in-memory index tree' (duration: 330.092652ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:58:18.263378Z","caller":"traceutil/trace.go:171","msg":"trace[1667532882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1913; }","duration":"441.770894ms","start":"2026-03-19T17:58:17.821596Z","end":"2026-03-19T17:58:18.263367Z","steps":["trace[1667532882] 'range keys from in-memory index tree' (duration: 441.568639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:58:18.263693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:58:17.933352Z","time spent":"330.33343ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:58:18.263724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:58:17.821573Z","time spent":"442.134934ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:58:18.26668Z","caller":"traceutil/trace.go:171","msg":"trace[690900077] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"161.154509ms","start":"2026-03-19T17:58:18.105505Z","end":"2026-03-19T17:58:18.26666Z","steps":["trace[690900077] 'process raft request' (duration: 160.868362ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:58:56.891776Z","caller":"traceutil/trace.go:171","msg":"trace[555784036] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"122.792106ms","start":"2026-03-19T17:58:56.768961Z","end":"2026-03-19T17:58:56.891753Z","steps":["trace[555784036] 'read index received' (duration: 33.992567ms)","trace[555784036] 'applied index is now lower than readState.Index' (duration: 88.798569ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:58:56.891921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.93998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:58:56.891902Z","caller":"traceutil/trace.go:171","msg":"trace[1637440050] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"123.756071ms","start":"2026-03-19T17:58:56.768112Z","end":"2026-03-19T17:58:56.891868Z","steps":["trace[1637440050] 'process raft request' (duration: 117.11152ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:58:56.891947Z","caller":"traceutil/trace.go:171","msg":"trace[1663365414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2081; }","duration":"123.008661ms","start":"2026-03-19T17:58:56.76893Z","end":"2026-03-19T17:58:56.891939Z","steps":["trace[1663365414] 'agreement among raft nodes before linearized reading' (duration: 122.929499ms)"],"step_count":1}